{"level":"warn","ts":"2026-02-20T15:22:04.070942Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T15:22:04.071026Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-02-20T15:22:04.071086Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T15:22:04.071103Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-20T15:22:04.071118Z","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-02-20T15:22:04.07169Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-02-20T15:22:04.071757Z","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.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-02-20T15:22:04.075818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.86647ms"} {"level":"info","ts":"2026-02-20T15:22:04.082134Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-02-20T15:22:04.082208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T15:22:04.082228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-02-20T15:22:04.082242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T15:22:04.08225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-02-20T15:22:04.082286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-02-20T15:22:04.154208Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T15:22:04.15616Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T15:22:04.157367Z","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-02-20T15:22:04.16112Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T15:22:04.161645Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T15:22:04.161774Z","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-02-20T15:22:04.162018Z","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-02-20T15:22:04.162133Z","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-02-20T15:22:04.163249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-02-20T15:22:04.163427Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-20T15:22:04.166116Z","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-02-20T15:22:04.166176Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-20T15:22:04.166196Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-20T15:22:04.166568Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T15:22:04.166614Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T15:22:04.583065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T15:22:04.583122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T15:22:04.583159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-02-20T15:22:04.583178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-02-20T15:22:04.58319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-20T15:22:04.583206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-02-20T15:22:04.583247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-20T15:22:04.584126Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:22:04.58482Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T15:22:04.584935Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T15:22:04.584822Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T15:22:04.585189Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:22:04.585376Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T15:22:04.585443Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T15:22:04.585523Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:22:04.585604Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T15:22:04.588285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T15:22:04.594523Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"warn","ts":"2026-02-20T15:22:16.061619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.481065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:22:16.061809Z","caller":"traceutil/trace.go:171","msg":"trace[773142304] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"208.100736ms","start":"2026-02-20T15:22:15.853692Z","end":"2026-02-20T15:22:16.061793Z","steps":["trace[773142304] 'compare' (duration: 203.242268ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:16.105042Z","caller":"traceutil/trace.go:171","msg":"trace[1376975025] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"249.79477ms","start":"2026-02-20T15:22:15.85523Z","end":"2026-02-20T15:22:16.105025Z","steps":["trace[1376975025] 'process raft request' (duration: 249.760469ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:16.105096Z","caller":"traceutil/trace.go:171","msg":"trace[482969717] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"250.750767ms","start":"2026-02-20T15:22:15.854323Z","end":"2026-02-20T15:22:16.105074Z","steps":["trace[482969717] 'process raft request' (duration: 250.579712ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:16.145408Z","caller":"traceutil/trace.go:171","msg":"trace[789386539] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"283.279459ms","start":"2026-02-20T15:22:15.861739Z","end":"2026-02-20T15:22:16.145019Z","steps":["trace[789386539] 'process raft request' (duration: 283.099454ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:16.550038Z","caller":"traceutil/trace.go:171","msg":"trace[685230470] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"104.057813ms","start":"2026-02-20T15:22:16.445948Z","end":"2026-02-20T15:22:16.550005Z","steps":["trace[685230470] 'process raft request' (duration: 60.799155ms)","trace[685230470] 'compare' (duration: 43.110094ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:29.182455Z","caller":"traceutil/trace.go:171","msg":"trace[1513777476] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"462.782704ms","start":"2026-02-20T15:22:28.719655Z","end":"2026-02-20T15:22:29.182438Z","steps":["trace[1513777476] 'process raft request' (duration: 462.747143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:29.18269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:28.719633Z","time spent":"462.882698ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:22:29.182701Z","caller":"traceutil/trace.go:171","msg":"trace[1438243767] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"463.021951ms","start":"2026-02-20T15:22:28.719646Z","end":"2026-02-20T15:22:29.182668Z","steps":["trace[1438243767] 'process raft request' (duration: 462.663301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:29.182871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:28.719618Z","time spent":"463.166935ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:22:31.364208Z","caller":"traceutil/trace.go:171","msg":"trace[1619841016] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"173.095882ms","start":"2026-02-20T15:22:31.191086Z","end":"2026-02-20T15:22:31.364181Z","steps":["trace[1619841016] 'process raft request' (duration: 172.945158ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:34.593891Z","caller":"traceutil/trace.go:171","msg":"trace[1978614984] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"152.468187ms","start":"2026-02-20T15:22:34.441398Z","end":"2026-02-20T15:22:34.593866Z","steps":["trace[1978614984] 'read index received' (duration: 152.260771ms)","trace[1978614984] 'applied index is now lower than readState.Index' (duration: 206.326µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:34.59394Z","caller":"traceutil/trace.go:171","msg":"trace[1760119213] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"171.179698ms","start":"2026-02-20T15:22:34.422732Z","end":"2026-02-20T15:22:34.593912Z","steps":["trace[1760119213] 'process raft request' (duration: 170.923931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:34.594113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.647962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4020"} {"level":"info","ts":"2026-02-20T15:22:34.594203Z","caller":"traceutil/trace.go:171","msg":"trace[730637903] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"152.808677ms","start":"2026-02-20T15:22:34.44137Z","end":"2026-02-20T15:22:34.594179Z","steps":["trace[730637903] 'agreement among raft nodes before linearized reading' (duration: 152.586811ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:34.889574Z","caller":"traceutil/trace.go:171","msg":"trace[971186309] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"286.154781ms","start":"2026-02-20T15:22:34.6034Z","end":"2026-02-20T15:22:34.889555Z","steps":["trace[971186309] 'process raft request' (duration: 286.030097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:37.898962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279372368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T15:22:38.081206Z","caller":"traceutil/trace.go:171","msg":"trace[507272746] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"682.631723ms","start":"2026-02-20T15:22:37.398538Z","end":"2026-02-20T15:22:38.08117Z","steps":["trace[507272746] 'read index received' (duration: 682.470839ms)","trace[507272746] 'applied index is now lower than readState.Index' (duration: 159.154µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:38.081411Z","caller":"traceutil/trace.go:171","msg":"trace[1659198306] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"740.421444ms","start":"2026-02-20T15:22:37.340978Z","end":"2026-02-20T15:22:38.0814Z","steps":["trace[1659198306] 'process raft request' (duration: 740.004122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:38.081555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:37.340955Z","time spent":"740.497046ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:22:38.081739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.897594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T15:22:38.08178Z","caller":"traceutil/trace.go:171","msg":"trace[1464850040] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:515; }","duration":"248.980556ms","start":"2026-02-20T15:22:37.832786Z","end":"2026-02-20T15:22:38.081767Z","steps":["trace[1464850040] 'agreement among raft nodes before linearized reading' (duration: 248.912924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:38.081841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.282542ms","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-02-20T15:22:38.081814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.124444ms","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-02-20T15:22:38.081919Z","caller":"traceutil/trace.go:171","msg":"trace[1729891762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"265.292969ms","start":"2026-02-20T15:22:37.816607Z","end":"2026-02-20T15:22:38.0819Z","steps":["trace[1729891762] 'agreement among raft nodes before linearized reading' (duration: 264.99191ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:38.081919Z","caller":"traceutil/trace.go:171","msg":"trace[1099079225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"683.393036ms","start":"2026-02-20T15:22:37.398497Z","end":"2026-02-20T15:22:38.08189Z","steps":["trace[1099079225] 'agreement among raft nodes before linearized reading' (duration: 683.160019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:38.081983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:37.39848Z","time spent":"683.488878ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T15:22:42.254227Z","caller":"traceutil/trace.go:171","msg":"trace[1187318163] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"157.315915ms","start":"2026-02-20T15:22:42.096888Z","end":"2026-02-20T15:22:42.254204Z","steps":["trace[1187318163] 'read index received' (duration: 157.116959ms)","trace[1187318163] 'applied index is now lower than readState.Index' (duration: 198.046µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:42.254287Z","caller":"traceutil/trace.go:171","msg":"trace[121293556] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"315.305518ms","start":"2026-02-20T15:22:41.938961Z","end":"2026-02-20T15:22:42.254266Z","steps":["trace[121293556] 'process raft request' (duration: 315.097872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:42.254414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:41.938941Z","time spent":"315.393161ms","remote":"127.0.0.1:56896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:22:42.254544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.024868ms","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-02-20T15:22:42.2546Z","caller":"traceutil/trace.go:171","msg":"trace[244328906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"150.10654ms","start":"2026-02-20T15:22:42.104478Z","end":"2026-02-20T15:22:42.254584Z","steps":["trace[244328906] 'agreement among raft nodes before linearized reading' (duration: 149.945385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:42.254604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.320456ms","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-02-20T15:22:42.254675Z","caller":"traceutil/trace.go:171","msg":"trace[1792819243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"156.42911ms","start":"2026-02-20T15:22:42.098228Z","end":"2026-02-20T15:22:42.254657Z","steps":["trace[1792819243] 'agreement among raft nodes before linearized reading' (duration: 156.187103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:42.254724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.825029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T15:22:42.254766Z","caller":"traceutil/trace.go:171","msg":"trace[773829107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"157.906752ms","start":"2026-02-20T15:22:42.096848Z","end":"2026-02-20T15:22:42.254755Z","steps":["trace[773829107] 'agreement among raft nodes before linearized reading' (duration: 157.480529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:42.516517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.991585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:22:42.516708Z","caller":"traceutil/trace.go:171","msg":"trace[1833181849] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"256.379147ms","start":"2026-02-20T15:22:42.260292Z","end":"2026-02-20T15:22:42.516671Z","steps":["trace[1833181849] 'process raft request' (duration: 256.320955ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:42.516788Z","caller":"traceutil/trace.go:171","msg":"trace[2060519727] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"256.651355ms","start":"2026-02-20T15:22:42.260099Z","end":"2026-02-20T15:22:42.51675Z","steps":["trace[2060519727] 'process raft request' (duration: 86.343971ms)","trace[2060519727] 'compare' (duration: 169.901412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:22:42.946098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.007185ms","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-02-20T15:22:42.946184Z","caller":"traceutil/trace.go:171","msg":"trace[804913266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"293.144329ms","start":"2026-02-20T15:22:42.653018Z","end":"2026-02-20T15:22:42.946162Z","steps":["trace[804913266] 'range keys from in-memory index tree' (duration: 292.81979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:44.616562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.448158ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T15:22:44.616695Z","caller":"traceutil/trace.go:171","msg":"trace[2006993991] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"1.01862188s","start":"2026-02-20T15:22:43.598054Z","end":"2026-02-20T15:22:44.616676Z","steps":["trace[2006993991] 'read index received' (duration: 491.079579ms)","trace[2006993991] 'applied index is now lower than readState.Index' (duration: 527.540811ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:22:44.616843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018767805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T15:22:44.616923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.354679ms","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-02-20T15:22:44.616966Z","caller":"traceutil/trace.go:171","msg":"trace[2103951477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"321.433352ms","start":"2026-02-20T15:22:44.295523Z","end":"2026-02-20T15:22:44.616956Z","steps":["trace[2103951477] 'agreement among raft nodes before linearized reading' (duration: 321.271577ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:44.616979Z","caller":"traceutil/trace.go:171","msg":"trace[2092050973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"1.01895426s","start":"2026-02-20T15:22:43.598Z","end":"2026-02-20T15:22:44.616954Z","steps":["trace[2092050973] 'agreement among raft nodes before linearized reading' (duration: 1.018735674s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:44.617004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:44.295508Z","time spent":"321.488903ms","remote":"127.0.0.1:56976","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-02-20T15:22:44.617034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:43.597979Z","time spent":"1.019043592s","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T15:22:46.788794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.891802ms","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-02-20T15:22:46.788864Z","caller":"traceutil/trace.go:171","msg":"trace[248827700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"113.972515ms","start":"2026-02-20T15:22:46.674872Z","end":"2026-02-20T15:22:46.788845Z","steps":["trace[248827700] 'range keys from in-memory index tree' (duration: 113.748998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:46.788937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.159944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:22:46.788993Z","caller":"traceutil/trace.go:171","msg":"trace[1845613987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"192.260877ms","start":"2026-02-20T15:22:46.596718Z","end":"2026-02-20T15:22:46.788979Z","steps":["trace[1845613987] 'range keys from in-memory index tree' (duration: 192.05588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:46.789032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.147128ms","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-02-20T15:22:46.789085Z","caller":"traceutil/trace.go:171","msg":"trace[741432975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"115.243921ms","start":"2026-02-20T15:22:46.673827Z","end":"2026-02-20T15:22:46.789071Z","steps":["trace[741432975] 'range keys from in-memory index tree' (duration: 115.007804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:46.789175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.232562ms","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-02-20T15:22:46.789198Z","caller":"traceutil/trace.go:171","msg":"trace[1760310895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"114.290364ms","start":"2026-02-20T15:22:46.674901Z","end":"2026-02-20T15:22:46.789191Z","steps":["trace[1760310895] 'range keys from in-memory index tree' (duration: 114.129559ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:48.073219Z","caller":"traceutil/trace.go:171","msg":"trace[1933734908] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"100.07687ms","start":"2026-02-20T15:22:47.973113Z","end":"2026-02-20T15:22:48.07319Z","steps":["trace[1933734908] 'process raft request' (duration: 68.606496ms)","trace[1933734908] 'compare' (duration: 31.367431ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:50.917615Z","caller":"traceutil/trace.go:171","msg":"trace[1101511174] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"167.871646ms","start":"2026-02-20T15:22:50.749721Z","end":"2026-02-20T15:22:50.917593Z","steps":["trace[1101511174] 'process raft request' (duration: 166.259331ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:50.918048Z","caller":"traceutil/trace.go:171","msg":"trace[624438936] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"131.440143ms","start":"2026-02-20T15:22:50.786596Z","end":"2026-02-20T15:22:50.918036Z","steps":["trace[624438936] 'read index received' (duration: 129.322305ms)","trace[624438936] 'applied index is now lower than readState.Index' (duration: 2.116308ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:22:50.918254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.633449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:22:50.918288Z","caller":"traceutil/trace.go:171","msg":"trace[1168650715] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:616; }","duration":"131.730661ms","start":"2026-02-20T15:22:50.78655Z","end":"2026-02-20T15:22:50.918281Z","steps":["trace[1168650715] 'agreement among raft nodes before linearized reading' (duration: 131.625688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:50.918524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.858695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:22:50.918558Z","caller":"traceutil/trace.go:171","msg":"trace[2020627061] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:616; }","duration":"131.909196ms","start":"2026-02-20T15:22:50.786641Z","end":"2026-02-20T15:22:50.918551Z","steps":["trace[2020627061] 'agreement among raft nodes before linearized reading' (duration: 131.832874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:50.918745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.600278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:22:50.918765Z","caller":"traceutil/trace.go:171","msg":"trace[848521774] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:616; }","duration":"131.637419ms","start":"2026-02-20T15:22:50.787123Z","end":"2026-02-20T15:22:50.91876Z","steps":["trace[848521774] 'agreement among raft nodes before linearized reading' (duration: 131.601168ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:52.517274Z","caller":"traceutil/trace.go:171","msg":"trace[600259106] transaction","detail":"{read_only:false; number_of_response:0; response_revision:636; }","duration":"193.91327ms","start":"2026-02-20T15:22:52.323337Z","end":"2026-02-20T15:22:52.51725Z","steps":["trace[600259106] 'process raft request' (duration: 165.884331ms)","trace[600259106] 'compare' (duration: 27.978887ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:55.127285Z","caller":"traceutil/trace.go:171","msg":"trace[846337859] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"175.274922ms","start":"2026-02-20T15:22:54.95198Z","end":"2026-02-20T15:22:55.127255Z","steps":["trace[846337859] 'process raft request' (duration: 134.700324ms)","trace[846337859] 'compare' (duration: 40.451604ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:55.133357Z","caller":"traceutil/trace.go:171","msg":"trace[2142881998] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:672; }","duration":"147.87188ms","start":"2026-02-20T15:22:54.985467Z","end":"2026-02-20T15:22:55.133339Z","steps":["trace[2142881998] 'read index received' (duration: 101.224424ms)","trace[2142881998] 'applied index is now lower than readState.Index' (duration: 46.646786ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:55.133432Z","caller":"traceutil/trace.go:171","msg":"trace[213051624] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"179.88392ms","start":"2026-02-20T15:22:54.953534Z","end":"2026-02-20T15:22:55.133418Z","steps":["trace[213051624] 'process raft request' (duration: 179.763227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:55.133532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.052176ms","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-02-20T15:22:55.133558Z","caller":"traceutil/trace.go:171","msg":"trace[945446673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:653; }","duration":"148.097777ms","start":"2026-02-20T15:22:54.985453Z","end":"2026-02-20T15:22:55.133551Z","steps":["trace[945446673] 'agreement among raft nodes before linearized reading' (duration: 147.956843ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:55.13344Z","caller":"traceutil/trace.go:171","msg":"trace[63910259] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"180.020014ms","start":"2026-02-20T15:22:54.953387Z","end":"2026-02-20T15:22:55.133407Z","steps":["trace[63910259] 'process raft request' (duration: 179.791248ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:22:57.509228Z","caller":"traceutil/trace.go:171","msg":"trace[1654007396] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"310.548222ms","start":"2026-02-20T15:22:57.198655Z","end":"2026-02-20T15:22:57.509203Z","steps":["trace[1654007396] 'process raft request' (duration: 295.201925ms)","trace[1654007396] 'compare' (duration: 15.178352ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:57.509283Z","caller":"traceutil/trace.go:171","msg":"trace[172185810] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:713; }","duration":"268.307027ms","start":"2026-02-20T15:22:57.240966Z","end":"2026-02-20T15:22:57.509273Z","steps":["trace[172185810] 'read index received' (duration: 252.773985ms)","trace[172185810] 'applied index is now lower than readState.Index' (duration: 15.532572ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:22:57.509351Z","caller":"traceutil/trace.go:171","msg":"trace[688324118] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"304.105972ms","start":"2026-02-20T15:22:57.205213Z","end":"2026-02-20T15:22:57.509319Z","steps":["trace[688324118] 'process raft request' (duration: 303.958218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:57.509424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.461442ms","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-02-20T15:22:57.50945Z","caller":"traceutil/trace.go:171","msg":"trace[1909926862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"268.525404ms","start":"2026-02-20T15:22:57.240918Z","end":"2026-02-20T15:22:57.509443Z","steps":["trace[1909926862] 'agreement among raft nodes before linearized reading' (duration: 268.38845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:22:57.509441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:57.198626Z","time spent":"310.678765ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:22:57.509487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:22:57.205203Z","time spent":"304.213056ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:04.690569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.360255506s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T15:23:04.690664Z","caller":"traceutil/trace.go:171","msg":"trace[2022980200] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"1.456481711s","start":"2026-02-20T15:23:03.234164Z","end":"2026-02-20T15:23:04.690646Z","steps":["trace[2022980200] 'read index received' (duration: 96.06534ms)","trace[2022980200] 'applied index is now lower than readState.Index' (duration: 1.360415301s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:23:04.691076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456906324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T15:23:04.69111Z","caller":"traceutil/trace.go:171","msg":"trace[916213729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"1.456969705s","start":"2026-02-20T15:23:03.23413Z","end":"2026-02-20T15:23:04.6911Z","steps":["trace[916213729] 'agreement among raft nodes before linearized reading' (duration: 1.456844221s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:04.691136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:03.234116Z","time spent":"1.457013427s","remote":"127.0.0.1:56976","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-02-20T15:23:04.691321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.126064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:23:04.691367Z","caller":"traceutil/trace.go:171","msg":"trace[2049416151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"802.207307ms","start":"2026-02-20T15:23:03.889145Z","end":"2026-02-20T15:23:04.691352Z","steps":["trace[2049416151] 'agreement among raft nodes before linearized reading' (duration: 802.136045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:04.691397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:03.889126Z","time spent":"802.264989ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T15:23:04.691406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152239425s","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-02-20T15:23:04.69143Z","caller":"traceutil/trace.go:171","msg":"trace[322412273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"1.152273936s","start":"2026-02-20T15:23:03.539149Z","end":"2026-02-20T15:23:04.691423Z","steps":["trace[322412273] 'agreement among raft nodes before linearized reading' (duration: 1.152206974s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:04.69145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:03.539143Z","time spent":"1.152301356s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T15:23:04.691431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153719696s","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-02-20T15:23:04.691506Z","caller":"traceutil/trace.go:171","msg":"trace[1355473304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"1.15384019s","start":"2026-02-20T15:23:03.537648Z","end":"2026-02-20T15:23:04.691489Z","steps":["trace[1355473304] 'agreement among raft nodes before linearized reading' (duration: 1.153625794s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:04.691549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:03.53763Z","time spent":"1.153910021s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T15:23:04.691448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145081246s","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-02-20T15:23:04.691733Z","caller":"traceutil/trace.go:171","msg":"trace[1666890421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"1.145385235s","start":"2026-02-20T15:23:03.546333Z","end":"2026-02-20T15:23:04.691718Z","steps":["trace[1666890421] 'agreement among raft nodes before linearized reading' (duration: 1.144983034s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:04.691759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:03.546328Z","time spent":"1.145424766s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T15:23:04.87112Z","caller":"traceutil/trace.go:171","msg":"trace[158640527] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"175.612311ms","start":"2026-02-20T15:23:04.69548Z","end":"2026-02-20T15:23:04.871092Z","steps":["trace[158640527] 'process raft request' (duration: 175.539879ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:04.871144Z","caller":"traceutil/trace.go:171","msg":"trace[348377557] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"175.695353ms","start":"2026-02-20T15:23:04.695418Z","end":"2026-02-20T15:23:04.871114Z","steps":["trace[348377557] 'process raft request' (duration: 175.452766ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:04.87149Z","caller":"traceutil/trace.go:171","msg":"trace[590357558] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"175.56479ms","start":"2026-02-20T15:23:04.695913Z","end":"2026-02-20T15:23:04.871478Z","steps":["trace[590357558] 'process raft request' (duration: 175.145588ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:06.890515Z","caller":"traceutil/trace.go:171","msg":"trace[540139826] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"158.525087ms","start":"2026-02-20T15:23:06.731965Z","end":"2026-02-20T15:23:06.89049Z","steps":["trace[540139826] 'process raft request' (duration: 158.27792ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:07.031924Z","caller":"traceutil/trace.go:171","msg":"trace[1311394061] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"138.841259ms","start":"2026-02-20T15:23:06.893056Z","end":"2026-02-20T15:23:07.031897Z","steps":["trace[1311394061] 'process raft request' (duration: 96.157543ms)","trace[1311394061] 'compare' (duration: 42.558983ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:23:07.039509Z","caller":"traceutil/trace.go:171","msg":"trace[1432818128] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"146.290586ms","start":"2026-02-20T15:23:06.893195Z","end":"2026-02-20T15:23:07.039485Z","steps":["trace[1432818128] 'process raft request' (duration: 146.198683ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:07.039707Z","caller":"traceutil/trace.go:171","msg":"trace[1954482142] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"146.466891ms","start":"2026-02-20T15:23:06.893215Z","end":"2026-02-20T15:23:07.039682Z","steps":["trace[1954482142] 'process raft request' (duration: 146.243725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.204348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.070344ms","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-02-20T15:23:28.204431Z","caller":"traceutil/trace.go:171","msg":"trace[2033511282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"770.177297ms","start":"2026-02-20T15:23:27.434232Z","end":"2026-02-20T15:23:28.204409Z","steps":["trace[2033511282] 'range keys from in-memory index tree' (duration: 769.911369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.204469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:27.434221Z","time spent":"770.239498ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T15:23:28.204455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.344045ms","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-02-20T15:23:28.204538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.547659ms","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-02-20T15:23:28.204558Z","caller":"traceutil/trace.go:171","msg":"trace[1500344538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"991.454058ms","start":"2026-02-20T15:23:27.213084Z","end":"2026-02-20T15:23:28.204538Z","steps":["trace[1500344538] 'range keys from in-memory index tree' (duration: 991.18848ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:28.204572Z","caller":"traceutil/trace.go:171","msg":"trace[1271379560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"923.615601ms","start":"2026-02-20T15:23:27.280948Z","end":"2026-02-20T15:23:28.204563Z","steps":["trace[1271379560] 'range keys from in-memory index tree' (duration: 923.430656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.204603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:27.280929Z","time spent":"923.669023ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T15:23:28.204615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:27.213044Z","time spent":"991.55551ms","remote":"127.0.0.1:56976","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-02-20T15:23:28.204669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.63297ms","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-02-20T15:23:28.204708Z","caller":"traceutil/trace.go:171","msg":"trace[756565744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"924.694051ms","start":"2026-02-20T15:23:27.280002Z","end":"2026-02-20T15:23:28.204696Z","steps":["trace[756565744] 'range keys from in-memory index tree' (duration: 924.499696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.204738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:27.279992Z","time spent":"924.740223ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T15:23:28.204713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.250175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:23:28.204811Z","caller":"traceutil/trace.go:171","msg":"trace[65103787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"604.40318ms","start":"2026-02-20T15:23:27.600384Z","end":"2026-02-20T15:23:28.204787Z","steps":["trace[65103787] 'range keys from in-memory index tree' (duration: 604.153422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.204831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.357004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T15:23:28.204855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:27.600361Z","time spent":"604.485491ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T15:23:28.204877Z","caller":"traceutil/trace.go:171","msg":"trace[940300074] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:782; }","duration":"474.421406ms","start":"2026-02-20T15:23:27.730435Z","end":"2026-02-20T15:23:28.204857Z","steps":["trace[940300074] 'count revisions from in-memory index tree' (duration: 474.305103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.204943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:27.730422Z","time spent":"474.501719ms","remote":"127.0.0.1:56952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-20T15:23:28.205034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.85088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T15:23:28.20511Z","caller":"traceutil/trace.go:171","msg":"trace[978852011] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:782; }","duration":"369.958853ms","start":"2026-02-20T15:23:27.835133Z","end":"2026-02-20T15:23:28.205092Z","steps":["trace[978852011] 'range keys from in-memory index tree' (duration: 369.647055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.205151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:27.835119Z","time spent":"370.020894ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-02-20T15:23:28.650152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:28.207473Z","time spent":"442.673984ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T15:23:28.993595Z","caller":"traceutil/trace.go:171","msg":"trace[583624182] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"784.190515ms","start":"2026-02-20T15:23:28.209366Z","end":"2026-02-20T15:23:28.993557Z","steps":["trace[583624182] 'process raft request' (duration: 783.924927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.993772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:28.209354Z","time spent":"784.314709ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:23:28.993892Z","caller":"traceutil/trace.go:171","msg":"trace[1576489235] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"784.37324ms","start":"2026-02-20T15:23:28.209485Z","end":"2026-02-20T15:23:28.993858Z","steps":["trace[1576489235] 'process raft request' (duration: 784.01852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.994043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:28.209458Z","time spent":"784.505344ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:23:28.994105Z","caller":"traceutil/trace.go:171","msg":"trace[2086196484] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"783.929018ms","start":"2026-02-20T15:23:28.210139Z","end":"2026-02-20T15:23:28.994068Z","steps":["trace[2086196484] 'process raft request' (duration: 783.593689ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:28.994105Z","caller":"traceutil/trace.go:171","msg":"trace[1806304369] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"487.312673ms","start":"2026-02-20T15:23:28.506766Z","end":"2026-02-20T15:23:28.994079Z","steps":["trace[1806304369] 'process raft request' (duration: 487.268332ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:28.994171Z","caller":"traceutil/trace.go:171","msg":"trace[2119291732] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"783.690122ms","start":"2026-02-20T15:23:28.210471Z","end":"2026-02-20T15:23:28.994161Z","steps":["trace[2119291732] 'process raft request' (duration: 783.496366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:28.994212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:28.210128Z","time spent":"784.01514ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:28.994232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:28.210462Z","time spent":"783.738183ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:28.994248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:28.506737Z","time spent":"487.436817ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:23:29.007542Z","caller":"traceutil/trace.go:171","msg":"trace[1967287492] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"356.259571ms","start":"2026-02-20T15:23:28.651266Z","end":"2026-02-20T15:23:29.007525Z","steps":["trace[1967287492] 'process raft request' (duration: 356.167048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:29.007643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:28.651249Z","time spent":"356.344833ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:23:29.21651Z","caller":"traceutil/trace.go:171","msg":"trace[1691863986] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"160.151521ms","start":"2026-02-20T15:23:29.056335Z","end":"2026-02-20T15:23:29.216486Z","steps":["trace[1691863986] 'process raft request' (duration: 125.150678ms)","trace[1691863986] 'compare' (duration: 34.804607ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:23:31.165776Z","caller":"traceutil/trace.go:171","msg":"trace[1666280904] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"163.192976ms","start":"2026-02-20T15:23:31.002552Z","end":"2026-02-20T15:23:31.165745Z","steps":["trace[1666280904] 'process raft request' (duration: 163.032891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:31.720844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279372967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T15:23:32.221908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279372967,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T15:23:32.610873Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.445185007s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T15:23:32.611616Z","caller":"traceutil/trace.go:171","msg":"trace[1166567916] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"1.607696284s","start":"2026-02-20T15:23:31.003899Z","end":"2026-02-20T15:23:32.611595Z","steps":["trace[1166567916] 'process raft request' (duration: 1.607295253s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:32.611787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:31.003885Z","time spent":"1.607811237s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:23:32.611945Z","caller":"traceutil/trace.go:171","msg":"trace[564437732] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.607944951s","start":"2026-02-20T15:23:31.003972Z","end":"2026-02-20T15:23:32.611917Z","steps":["trace[564437732] 'process raft request' (duration: 1.607577551s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:32.61211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:31.003959Z","time spent":"1.608062724s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:23:32.612189Z","caller":"traceutil/trace.go:171","msg":"trace[2066721901] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"1.608182768s","start":"2026-02-20T15:23:31.003983Z","end":"2026-02-20T15:23:32.612166Z","steps":["trace[2066721901] 'process raft request' (duration: 1.607840348s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:32.61234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:31.00397Z","time spent":"1.608298531s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:32.880955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.329228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:23:32.88106Z","caller":"traceutil/trace.go:171","msg":"trace[1938122247] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:820; }","duration":"1.660860741s","start":"2026-02-20T15:23:31.220181Z","end":"2026-02-20T15:23:32.881042Z","steps":["trace[1938122247] 'read index received' (duration: 1.39099555s)","trace[1938122247] 'applied index is now lower than readState.Index' (duration: 269.863891ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:23:32.881167Z","caller":"traceutil/trace.go:171","msg":"trace[1617620165] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"839.934674ms","start":"2026-02-20T15:23:32.041221Z","end":"2026-02-20T15:23:32.881156Z","steps":["trace[1617620165] 'process raft request' (duration: 707.319788ms)","trace[1617620165] 'compare' (duration: 132.130193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:23:32.881248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:32.041194Z","time spent":"840.021057ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:32.881444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661255633s","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-02-20T15:23:32.881443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283472092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:23:32.881482Z","caller":"traceutil/trace.go:171","msg":"trace[281585255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.661328795s","start":"2026-02-20T15:23:31.220143Z","end":"2026-02-20T15:23:32.881472Z","steps":["trace[281585255] 'agreement among raft nodes before linearized reading' (duration: 1.661209282s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:32.881477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.015602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:23:32.881509Z","caller":"traceutil/trace.go:171","msg":"trace[1337121704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"1.283586285s","start":"2026-02-20T15:23:31.597906Z","end":"2026-02-20T15:23:32.881492Z","steps":["trace[1337121704] 'agreement among raft nodes before linearized reading' (duration: 1.283461822s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:32.881563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:31.597886Z","time spent":"1.283669547s","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T15:23:32.88156Z","caller":"traceutil/trace.go:171","msg":"trace[1623062286] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:794; }","duration":"647.143186ms","start":"2026-02-20T15:23:32.2344Z","end":"2026-02-20T15:23:32.881543Z","steps":["trace[1623062286] 'agreement among raft nodes before linearized reading' (duration: 647.003382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:32.881509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:31.220123Z","time spent":"1.661380766s","remote":"127.0.0.1:56976","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-02-20T15:23:32.881708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:32.234381Z","time spent":"647.260389ms","remote":"127.0.0.1:57004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-20T15:23:34.732686Z","caller":"traceutil/trace.go:171","msg":"trace[1633437611] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"111.544071ms","start":"2026-02-20T15:23:34.621119Z","end":"2026-02-20T15:23:34.732664Z","steps":["trace[1633437611] 'process raft request' (duration: 111.49351ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:34.732777Z","caller":"traceutil/trace.go:171","msg":"trace[1009117169] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"111.836378ms","start":"2026-02-20T15:23:34.620908Z","end":"2026-02-20T15:23:34.732744Z","steps":["trace[1009117169] 'process raft request' (duration: 109.799652ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:38.965079Z","caller":"traceutil/trace.go:171","msg":"trace[1990660089] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"121.694142ms","start":"2026-02-20T15:23:38.843359Z","end":"2026-02-20T15:23:38.965053Z","steps":["trace[1990660089] 'process raft request' (duration: 101.534372ms)","trace[1990660089] 'compare' (duration: 20.045217ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:23:45.755337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279373161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T15:23:46.255944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279373161,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T15:23:46.345519Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.296482885s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T15:23:46.845162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.355709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:23:46.84531Z","caller":"traceutil/trace.go:171","msg":"trace[1313709758] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"1.589980352s","start":"2026-02-20T15:23:45.255293Z","end":"2026-02-20T15:23:46.845273Z","steps":["trace[1313709758] 'read index received' (duration: 1.090371646s)","trace[1313709758] 'applied index is now lower than readState.Index' (duration: 499.607066ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:23:46.845451Z","caller":"traceutil/trace.go:171","msg":"trace[1761071759] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"1.839773975s","start":"2026-02-20T15:23:45.00566Z","end":"2026-02-20T15:23:46.845434Z","steps":["trace[1761071759] 'process raft request' (duration: 1.340055287s)","trace[1761071759] 'compare' (duration: 498.949897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:23:46.845546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:45.005643Z","time spent":"1.839851997s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:46.845659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24847203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:23:46.845723Z","caller":"traceutil/trace.go:171","msg":"trace[2038178012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"1.248540782s","start":"2026-02-20T15:23:45.597168Z","end":"2026-02-20T15:23:46.845708Z","steps":["trace[2038178012] 'agreement among raft nodes before linearized reading' (duration: 1.248442639s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:46.84575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:45.597114Z","time spent":"1.248631015s","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T15:23:46.845812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590507196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T15:23:46.845871Z","caller":"traceutil/trace.go:171","msg":"trace[22198833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"1.590612159s","start":"2026-02-20T15:23:45.255246Z","end":"2026-02-20T15:23:46.845859Z","steps":["trace[22198833] 'agreement among raft nodes before linearized reading' (duration: 1.590425704s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:46.845899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:45.255228Z","time spent":"1.590667211s","remote":"127.0.0.1:56976","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-02-20T15:23:47.351895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279373166,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T15:23:47.717289Z","caller":"traceutil/trace.go:171","msg":"trace[353868483] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"866.073062ms","start":"2026-02-20T15:23:46.85119Z","end":"2026-02-20T15:23:47.717263Z","steps":["trace[353868483] 'read index received' (duration: 813.412688ms)","trace[353868483] 'applied index is now lower than readState.Index' (duration: 52.659504ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:23:47.717638Z","caller":"traceutil/trace.go:171","msg":"trace[1450350979] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"868.831699ms","start":"2026-02-20T15:23:46.848784Z","end":"2026-02-20T15:23:47.717615Z","steps":["trace[1450350979] 'process raft request' (duration: 815.807275ms)","trace[1450350979] 'compare' (duration: 52.295044ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:23:47.717649Z","caller":"traceutil/trace.go:171","msg":"trace[2086818173] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"868.113529ms","start":"2026-02-20T15:23:46.849506Z","end":"2026-02-20T15:23:47.717619Z","steps":["trace[2086818173] 'process raft request' (duration: 867.684277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:47.717672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.464373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:23:47.71783Z","caller":"traceutil/trace.go:171","msg":"trace[278061478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"866.663159ms","start":"2026-02-20T15:23:46.851154Z","end":"2026-02-20T15:23:47.717817Z","steps":["trace[278061478] 'agreement among raft nodes before linearized reading' (duration: 866.465173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:47.717854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:46.849497Z","time spent":"868.253383ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:47.717929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:46.851138Z","time spent":"866.744701ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T15:23:47.717761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:46.848768Z","time spent":"868.927551ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:23:47.718427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.41891ms","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-02-20T15:23:47.718507Z","caller":"traceutil/trace.go:171","msg":"trace[1882478477] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:882; }","duration":"808.530033ms","start":"2026-02-20T15:23:46.909965Z","end":"2026-02-20T15:23:47.718495Z","steps":["trace[1882478477] 'agreement among raft nodes before linearized reading' (duration: 808.366238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:47.718495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.488964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T15:23:47.718561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.293472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-20T15:23:47.718588Z","caller":"traceutil/trace.go:171","msg":"trace[1491141604] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:882; }","duration":"272.652539ms","start":"2026-02-20T15:23:47.445917Z","end":"2026-02-20T15:23:47.718569Z","steps":["trace[1491141604] 'agreement among raft nodes before linearized reading' (duration: 272.442673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:47.718595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:46.909952Z","time spent":"808.602355ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T15:23:47.718623Z","caller":"traceutil/trace.go:171","msg":"trace[1475825505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"665.386595ms","start":"2026-02-20T15:23:47.053222Z","end":"2026-02-20T15:23:47.718608Z","steps":["trace[1475825505] 'agreement among raft nodes before linearized reading' (duration: 665.233551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:23:47.718814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:23:47.053207Z","time spent":"665.59494ms","remote":"127.0.0.1:56976","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-02-20T15:23:48.1269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.051032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T15:23:48.126957Z","caller":"traceutil/trace.go:171","msg":"trace[71499009] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:884; }","duration":"290.153065ms","start":"2026-02-20T15:23:47.836792Z","end":"2026-02-20T15:23:48.126945Z","steps":["trace[71499009] 'range keys from in-memory index tree' (duration: 289.867247ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:23:48.356269Z","caller":"traceutil/trace.go:171","msg":"trace[388385022] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"173.77644ms","start":"2026-02-20T15:23:48.182465Z","end":"2026-02-20T15:23:48.356242Z","steps":["trace[388385022] 'process raft request' (duration: 106.53392ms)","trace[388385022] 'compare' (duration: 67.081315ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:23:58.058459Z","caller":"traceutil/trace.go:171","msg":"trace[1585691582] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"118.934574ms","start":"2026-02-20T15:23:57.939499Z","end":"2026-02-20T15:23:58.058433Z","steps":["trace[1585691582] 'process raft request' (duration: 49.494673ms)","trace[1585691582] 'compare' (duration: 69.328158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:24:04.332585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279373300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T15:24:04.575928Z","caller":"traceutil/trace.go:171","msg":"trace[1396270742] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"743.629192ms","start":"2026-02-20T15:24:03.832282Z","end":"2026-02-20T15:24:04.575911Z","steps":["trace[1396270742] 'read index received' (duration: 743.464418ms)","trace[1396270742] 'applied index is now lower than readState.Index' (duration: 164.234µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:24:04.576008Z","caller":"traceutil/trace.go:171","msg":"trace[344582089] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"784.795323ms","start":"2026-02-20T15:24:03.791205Z","end":"2026-02-20T15:24:04.576001Z","steps":["trace[344582089] 'process raft request' (duration: 784.605248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:04.576079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:03.791189Z","time spent":"784.834144ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:04.576201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.229309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-20T15:24:04.576217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.811625ms","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-02-20T15:24:04.576253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.972902ms","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-02-20T15:24:04.57627Z","caller":"traceutil/trace.go:171","msg":"trace[298655024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"739.341102ms","start":"2026-02-20T15:24:03.836913Z","end":"2026-02-20T15:24:04.576254Z","steps":["trace[298655024] 'agreement among raft nodes before linearized reading' (duration: 739.126476ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:04.576294Z","caller":"traceutil/trace.go:171","msg":"trace[2007898342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"744.055164ms","start":"2026-02-20T15:24:03.832228Z","end":"2026-02-20T15:24:04.576283Z","steps":["trace[2007898342] 'agreement among raft nodes before linearized reading' (duration: 743.942691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:04.576293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.386893ms","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-02-20T15:24:04.576297Z","caller":"traceutil/trace.go:171","msg":"trace[765670471] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:927; }","duration":"622.902499ms","start":"2026-02-20T15:24:03.953375Z","end":"2026-02-20T15:24:04.576277Z","steps":["trace[765670471] 'agreement among raft nodes before linearized reading' (duration: 622.771915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:04.576317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:03.832209Z","time spent":"744.103126ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T15:24:04.576218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.928052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T15:24:04.576314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:03.836896Z","time spent":"739.408814ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T15:24:04.576318Z","caller":"traceutil/trace.go:171","msg":"trace[817257270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"671.438375ms","start":"2026-02-20T15:24:03.904872Z","end":"2026-02-20T15:24:04.57631Z","steps":["trace[817257270] 'agreement among raft nodes before linearized reading' (duration: 671.353932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:04.576351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:03.953318Z","time spent":"623.023611ms","remote":"127.0.0.1:57166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-20T15:24:04.576366Z","caller":"traceutil/trace.go:171","msg":"trace[1869822034] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:927; }","duration":"110.126148ms","start":"2026-02-20T15:24:04.466219Z","end":"2026-02-20T15:24:04.576345Z","steps":["trace[1869822034] 'agreement among raft nodes before linearized reading' (duration: 109.898621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:04.576401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:03.904852Z","time spent":"671.537387ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T15:24:04.781168Z","caller":"traceutil/trace.go:171","msg":"trace[1839720628] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"200.804911ms","start":"2026-02-20T15:24:04.580346Z","end":"2026-02-20T15:24:04.781151Z","steps":["trace[1839720628] 'process raft request' (duration: 200.690488ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:04.805184Z","caller":"traceutil/trace.go:171","msg":"trace[700008800] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"224.562395ms","start":"2026-02-20T15:24:04.580605Z","end":"2026-02-20T15:24:04.805168Z","steps":["trace[700008800] 'process raft request' (duration: 224.440432ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:04.805315Z","caller":"traceutil/trace.go:171","msg":"trace[1310606015] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"224.428541ms","start":"2026-02-20T15:24:04.580881Z","end":"2026-02-20T15:24:04.805309Z","steps":["trace[1310606015] 'process raft request' (duration: 224.256066ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:06.699486Z","caller":"traceutil/trace.go:171","msg":"trace[1970563512] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"116.525107ms","start":"2026-02-20T15:24:06.582938Z","end":"2026-02-20T15:24:06.699463Z","steps":["trace[1970563512] 'process raft request' (duration: 116.373412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.679664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.869919846s","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-02-20T15:24:08.679736Z","caller":"traceutil/trace.go:171","msg":"trace[538406625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"1.870017189s","start":"2026-02-20T15:24:06.809703Z","end":"2026-02-20T15:24:08.67972Z","steps":["trace[538406625] 'range keys from in-memory index tree' (duration: 1.869781292s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.679771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:06.809556Z","time spent":"1.870207514s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T15:24:08.679805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660441903s","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-02-20T15:24:08.679858Z","caller":"traceutil/trace.go:171","msg":"trace[229465807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"1.660534575s","start":"2026-02-20T15:24:07.01931Z","end":"2026-02-20T15:24:08.679844Z","steps":["trace[229465807] 'range keys from in-memory index tree' (duration: 1.660306919s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.679884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:07.019293Z","time spent":"1.660585606s","remote":"127.0.0.1:56976","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-02-20T15:24:08.68004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888593309s","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-02-20T15:24:08.680068Z","caller":"traceutil/trace.go:171","msg":"trace[1452647975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"1.88864598s","start":"2026-02-20T15:24:06.791416Z","end":"2026-02-20T15:24:08.680062Z","steps":["trace[1452647975] 'range keys from in-memory index tree' (duration: 1.888496706s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.680088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:06.791402Z","time spent":"1.888680791s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T15:24:08.680102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.87184305s","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-02-20T15:24:08.680119Z","caller":"traceutil/trace.go:171","msg":"trace[536288203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"1.871872001s","start":"2026-02-20T15:24:06.808243Z","end":"2026-02-20T15:24:08.680115Z","steps":["trace[536288203] 'range keys from in-memory index tree' (duration: 1.871775289s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.680139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:06.808238Z","time spent":"1.871897271s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T15:24:08.680246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080366511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:08.680324Z","caller":"traceutil/trace.go:171","msg":"trace[1938566571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"1.080502445s","start":"2026-02-20T15:24:07.599801Z","end":"2026-02-20T15:24:08.680303Z","steps":["trace[1938566571] 'range keys from in-memory index tree' (duration: 1.080259499s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.680394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:07.599771Z","time spent":"1.080613518s","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T15:24:08.680374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.116083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T15:24:08.680541Z","caller":"traceutil/trace.go:171","msg":"trace[2110229571] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:932; }","duration":"842.342439ms","start":"2026-02-20T15:24:07.838164Z","end":"2026-02-20T15:24:08.680507Z","steps":["trace[2110229571] 'range keys from in-memory index tree' (duration: 841.769193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.680648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:07.838133Z","time spent":"842.499753ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-02-20T15:24:08.680759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163516134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:08.680932Z","caller":"traceutil/trace.go:171","msg":"trace[879185503] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:932; }","duration":"1.164176763s","start":"2026-02-20T15:24:07.516732Z","end":"2026-02-20T15:24:08.680908Z","steps":["trace[879185503] 'count revisions from in-memory index tree' (duration: 1.163426732s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:08.681065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:07.516715Z","time spent":"1.164332508s","remote":"127.0.0.1:56844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-20T15:24:09.011743Z","caller":"traceutil/trace.go:171","msg":"trace[845909116] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"326.619808ms","start":"2026-02-20T15:24:08.685105Z","end":"2026-02-20T15:24:09.011725Z","steps":["trace[845909116] 'process raft request' (duration: 326.548716ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:09.011746Z","caller":"traceutil/trace.go:171","msg":"trace[1407396324] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"326.67075ms","start":"2026-02-20T15:24:08.685039Z","end":"2026-02-20T15:24:09.01171Z","steps":["trace[1407396324] 'process raft request' (duration: 318.317576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:09.011877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:08.685079Z","time spent":"326.731581ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:09.011962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:08.685025Z","time spent":"326.817884ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:09.012094Z","caller":"traceutil/trace.go:171","msg":"trace[994243018] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"326.930657ms","start":"2026-02-20T15:24:08.685139Z","end":"2026-02-20T15:24:09.01207Z","steps":["trace[994243018] 'process raft request' (duration: 326.552876ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:09.01224Z","caller":"traceutil/trace.go:171","msg":"trace[1879396074] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:971; }","duration":"325.340782ms","start":"2026-02-20T15:24:08.686891Z","end":"2026-02-20T15:24:09.012231Z","steps":["trace[1879396074] 'read index received' (duration: 132.318098ms)","trace[1879396074] 'applied index is now lower than readState.Index' (duration: 193.022054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:24:09.01223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:08.685116Z","time spent":"327.038949ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:09.012327Z","caller":"traceutil/trace.go:171","msg":"trace[601515030] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"326.236897ms","start":"2026-02-20T15:24:08.686084Z","end":"2026-02-20T15:24:09.012321Z","steps":["trace[601515030] 'process raft request' (duration: 325.889597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:09.012365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:08.686076Z","time spent":"326.267788ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:09.012429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.717203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:09.012451Z","caller":"traceutil/trace.go:171","msg":"trace[999285415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"325.750353ms","start":"2026-02-20T15:24:08.686694Z","end":"2026-02-20T15:24:09.012444Z","steps":["trace[999285415] 'agreement among raft nodes before linearized reading' (duration: 325.715842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:09.012469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:08.686687Z","time spent":"325.778084ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T15:24:09.090694Z","caller":"traceutil/trace.go:171","msg":"trace[944433338] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"270.330895ms","start":"2026-02-20T15:24:08.820331Z","end":"2026-02-20T15:24:09.090662Z","steps":["trace[944433338] 'process raft request' (duration: 270.193702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:09.090778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.391681ms","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-02-20T15:24:09.091298Z","caller":"traceutil/trace.go:171","msg":"trace[1425362671] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"380.459843ms","start":"2026-02-20T15:24:08.710356Z","end":"2026-02-20T15:24:09.090816Z","steps":["trace[1425362671] 'agreement among raft nodes before linearized reading' (duration: 380.276388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:09.091362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:08.710349Z","time spent":"381.000188ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T15:24:09.238289Z","caller":"traceutil/trace.go:171","msg":"trace[265643646] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"141.243877ms","start":"2026-02-20T15:24:09.097024Z","end":"2026-02-20T15:24:09.238268Z","steps":["trace[265643646] 'read index received' (duration: 89.382918ms)","trace[265643646] 'applied index is now lower than readState.Index' (duration: 51.859739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:24:09.238456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.404672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T15:24:09.238493Z","caller":"traceutil/trace.go:171","msg":"trace[1061897475] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:938; }","duration":"141.474133ms","start":"2026-02-20T15:24:09.097008Z","end":"2026-02-20T15:24:09.238482Z","steps":["trace[1061897475] 'agreement among raft nodes before linearized reading' (duration: 141.34165ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:09.238493Z","caller":"traceutil/trace.go:171","msg":"trace[1021884251] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"142.271165ms","start":"2026-02-20T15:24:09.096179Z","end":"2026-02-20T15:24:09.23845Z","steps":["trace[1021884251] 'process raft request' (duration: 90.283093ms)","trace[1021884251] 'compare' (duration: 51.685744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:24:11.525276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279373341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T15:24:11.708488Z","caller":"traceutil/trace.go:171","msg":"trace[659738421] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"688.037018ms","start":"2026-02-20T15:24:11.020425Z","end":"2026-02-20T15:24:11.708462Z","steps":["trace[659738421] 'process raft request' (duration: 687.886814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:11.708637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:11.020399Z","time spent":"688.159542ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:11.723872Z","caller":"traceutil/trace.go:171","msg":"trace[580770363] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:979; }","duration":"699.005105ms","start":"2026-02-20T15:24:11.024856Z","end":"2026-02-20T15:24:11.723861Z","steps":["trace[580770363] 'read index received' (duration: 683.386848ms)","trace[580770363] 'applied index is now lower than readState.Index' (duration: 15.617627ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:24:11.723927Z","caller":"traceutil/trace.go:171","msg":"trace[677495381] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"703.415759ms","start":"2026-02-20T15:24:11.020506Z","end":"2026-02-20T15:24:11.723922Z","steps":["trace[677495381] 'process raft request' (duration: 703.276715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:11.723978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:11.02049Z","time spent":"703.455319ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:11.724127Z","caller":"traceutil/trace.go:171","msg":"trace[1475396451] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"703.287575ms","start":"2026-02-20T15:24:11.020834Z","end":"2026-02-20T15:24:11.724122Z","steps":["trace[1475396451] 'process raft request' (duration: 703.006917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:11.72416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:11.02081Z","time spent":"703.333596ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:11.724265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.417986ms","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-02-20T15:24:11.724294Z","caller":"traceutil/trace.go:171","msg":"trace[826868203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"699.458878ms","start":"2026-02-20T15:24:11.024822Z","end":"2026-02-20T15:24:11.724281Z","steps":["trace[826868203] 'agreement among raft nodes before linearized reading' (duration: 699.391436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:11.724315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:11.024811Z","time spent":"699.498488ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T15:24:11.724462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.539727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:11.724481Z","caller":"traceutil/trace.go:171","msg":"trace[667256932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"126.594448ms","start":"2026-02-20T15:24:11.597882Z","end":"2026-02-20T15:24:11.724476Z","steps":["trace[667256932] 'agreement among raft nodes before linearized reading' (duration: 126.567747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:11.725315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.365041ms","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-02-20T15:24:11.725446Z","caller":"traceutil/trace.go:171","msg":"trace[342953368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:942; }","duration":"482.506275ms","start":"2026-02-20T15:24:11.242922Z","end":"2026-02-20T15:24:11.725428Z","steps":["trace[342953368] 'agreement among raft nodes before linearized reading' (duration: 481.455345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:11.725492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:11.242875Z","time spent":"482.605137ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T15:24:12.705885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.193257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:24:12.706037Z","caller":"traceutil/trace.go:171","msg":"trace[1134499636] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"953.380763ms","start":"2026-02-20T15:24:11.752631Z","end":"2026-02-20T15:24:12.706012Z","steps":["trace[1134499636] 'process raft request' (duration: 256.9498ms)","trace[1134499636] 'compare' (duration: 696.067013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:24:12.706138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:11.752605Z","time spent":"953.486906ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:12.747072Z","caller":"traceutil/trace.go:171","msg":"trace[1014357098] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"174.167017ms","start":"2026-02-20T15:24:12.572888Z","end":"2026-02-20T15:24:12.747055Z","steps":["trace[1014357098] 'read index received' (duration: 173.973872ms)","trace[1014357098] 'applied index is now lower than readState.Index' (duration: 192.115µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:24:12.747113Z","caller":"traceutil/trace.go:171","msg":"trace[106011956] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"973.906477ms","start":"2026-02-20T15:24:11.773176Z","end":"2026-02-20T15:24:12.747083Z","steps":["trace[106011956] 'process raft request' (duration: 973.745932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:12.747261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.357203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T15:24:12.74726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:11.773156Z","time spent":"974.029111ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T15:24:12.747306Z","caller":"traceutil/trace.go:171","msg":"trace[1118644101] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:945; }","duration":"174.428835ms","start":"2026-02-20T15:24:12.572863Z","end":"2026-02-20T15:24:12.747292Z","steps":["trace[1118644101] 'agreement among raft nodes before linearized reading' (duration: 174.299281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:12.747283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.539905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:12.747358Z","caller":"traceutil/trace.go:171","msg":"trace[1453581968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"146.647908ms","start":"2026-02-20T15:24:12.600691Z","end":"2026-02-20T15:24:12.747339Z","steps":["trace[1453581968] 'agreement among raft nodes before linearized reading' (duration: 146.500124ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:18.222567Z","caller":"traceutil/trace.go:171","msg":"trace[559537525] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"385.03676ms","start":"2026-02-20T15:24:17.837504Z","end":"2026-02-20T15:24:18.222541Z","steps":["trace[559537525] 'read index received' (duration: 384.802214ms)","trace[559537525] 'applied index is now lower than readState.Index' (duration: 233.246µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:24:18.22264Z","caller":"traceutil/trace.go:171","msg":"trace[1147308813] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"472.943957ms","start":"2026-02-20T15:24:17.749658Z","end":"2026-02-20T15:24:18.222602Z","steps":["trace[1147308813] 'process raft request' (duration: 472.732321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:18.22281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.289818ms","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-02-20T15:24:18.222863Z","caller":"traceutil/trace.go:171","msg":"trace[823799379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"385.394281ms","start":"2026-02-20T15:24:17.837451Z","end":"2026-02-20T15:24:18.222846Z","steps":["trace[823799379] 'agreement among raft nodes before linearized reading' (duration: 385.225396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:18.222939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:17.837431Z","time spent":"385.494603ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T15:24:18.223003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.429954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T15:24:18.223064Z","caller":"traceutil/trace.go:171","msg":"trace[1633762041] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:983; }","duration":"384.516216ms","start":"2026-02-20T15:24:17.838534Z","end":"2026-02-20T15:24:18.22305Z","steps":["trace[1633762041] 'agreement among raft nodes before linearized reading' (duration: 384.373912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:18.223111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:17.838522Z","time spent":"384.577578ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-02-20T15:24:18.223127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.266323ms","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-02-20T15:24:18.223205Z","caller":"traceutil/trace.go:171","msg":"trace[1188831114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:983; }","duration":"338.360996ms","start":"2026-02-20T15:24:17.884827Z","end":"2026-02-20T15:24:18.223188Z","steps":["trace[1188831114] 'agreement among raft nodes before linearized reading' (duration: 338.11716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:18.223217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.388971ms","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-02-20T15:24:18.223286Z","caller":"traceutil/trace.go:171","msg":"trace[1885701182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"380.488593ms","start":"2026-02-20T15:24:17.842789Z","end":"2026-02-20T15:24:18.223277Z","steps":["trace[1885701182] 'agreement among raft nodes before linearized reading' (duration: 380.251156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:18.223313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:17.84278Z","time spent":"380.526065ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T15:24:18.223248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:17.884789Z","time spent":"338.449809ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T15:24:18.223567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:17.74963Z","time spent":"473.167524ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:18.458143Z","caller":"traceutil/trace.go:171","msg":"trace[150981137] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"229.881645ms","start":"2026-02-20T15:24:18.228233Z","end":"2026-02-20T15:24:18.458114Z","steps":["trace[150981137] 'process raft request' (duration: 229.833244ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:18.458221Z","caller":"traceutil/trace.go:171","msg":"trace[958194409] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"231.412487ms","start":"2026-02-20T15:24:18.226779Z","end":"2026-02-20T15:24:18.458191Z","steps":["trace[958194409] 'process raft request' (duration: 231.126699ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:18.458395Z","caller":"traceutil/trace.go:171","msg":"trace[2042449161] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"230.254126ms","start":"2026-02-20T15:24:18.22813Z","end":"2026-02-20T15:24:18.458384Z","steps":["trace[2042449161] 'process raft request' (duration: 229.900376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:20.730629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279373437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T15:24:21.230811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255266279373437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T15:24:21.29274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.320787352s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T15:24:21.293083Z","caller":"traceutil/trace.go:171","msg":"trace[1449439459] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"1.063171932s","start":"2026-02-20T15:24:20.22989Z","end":"2026-02-20T15:24:21.293062Z","steps":["trace[1449439459] 'read index received' (duration: 1.062931595s)","trace[1449439459] 'applied index is now lower than readState.Index' (duration: 239.377µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:24:21.293416Z","caller":"traceutil/trace.go:171","msg":"trace[1681024794] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.321596175s","start":"2026-02-20T15:24:19.971809Z","end":"2026-02-20T15:24:21.293406Z","steps":["trace[1681024794] 'process raft request' (duration: 1.321108801s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:21.293523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:19.971775Z","time spent":"1.321670077s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:21.293977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.266347ms","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-02-20T15:24:21.294124Z","caller":"traceutil/trace.go:171","msg":"trace[2123522084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"828.478093ms","start":"2026-02-20T15:24:20.465619Z","end":"2026-02-20T15:24:21.294097Z","steps":["trace[2123522084] 'agreement among raft nodes before linearized reading' (duration: 827.987759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:21.294185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:20.4656Z","time spent":"828.573036ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T15:24:21.294201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.838631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:21.29429Z","caller":"traceutil/trace.go:171","msg":"trace[1203241829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"698.987845ms","start":"2026-02-20T15:24:20.595282Z","end":"2026-02-20T15:24:21.294269Z","steps":["trace[1203241829] 'agreement among raft nodes before linearized reading' (duration: 698.80712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:21.294351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:20.595263Z","time spent":"699.079697ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T15:24:21.294401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.057221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-20T15:24:21.294422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.453933ms","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-02-20T15:24:21.294447Z","caller":"traceutil/trace.go:171","msg":"trace[1283444909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"828.142833ms","start":"2026-02-20T15:24:20.466292Z","end":"2026-02-20T15:24:21.294435Z","steps":["trace[1283444909] 'agreement among raft nodes before linearized reading' (duration: 828.00583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:21.294486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:20.466278Z","time spent":"828.201546ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T15:24:21.294492Z","caller":"traceutil/trace.go:171","msg":"trace[949090754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"531.564336ms","start":"2026-02-20T15:24:20.762909Z","end":"2026-02-20T15:24:21.294474Z","steps":["trace[949090754] 'agreement among raft nodes before linearized reading' (duration: 531.34882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:21.294543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:20.762889Z","time spent":"531.641578ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T15:24:21.294623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.218196ms","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-02-20T15:24:21.294773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064874339s","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-02-20T15:24:21.294686Z","caller":"traceutil/trace.go:171","msg":"trace[2034042605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"828.290818ms","start":"2026-02-20T15:24:20.46638Z","end":"2026-02-20T15:24:21.294671Z","steps":["trace[2034042605] 'agreement among raft nodes before linearized reading' (duration: 827.885967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:21.295225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:20.466375Z","time spent":"828.831953ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T15:24:21.294935Z","caller":"traceutil/trace.go:171","msg":"trace[1680422762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"1.065066975s","start":"2026-02-20T15:24:20.22985Z","end":"2026-02-20T15:24:21.294917Z","steps":["trace[1680422762] 'agreement among raft nodes before linearized reading' (duration: 1.064353375s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:21.295387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:20.229833Z","time spent":"1.065498758s","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T15:24:22.162051Z","caller":"traceutil/trace.go:171","msg":"trace[1940743130] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"862.34411ms","start":"2026-02-20T15:24:21.299686Z","end":"2026-02-20T15:24:22.16203Z","steps":["trace[1940743130] 'process raft request' (duration: 783.065114ms)","trace[1940743130] 'compare' (duration: 79.139912ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:24:22.162444Z","caller":"traceutil/trace.go:171","msg":"trace[363270962] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"860.22379ms","start":"2026-02-20T15:24:21.302216Z","end":"2026-02-20T15:24:22.162439Z","steps":["trace[363270962] 'process raft request' (duration: 860.022884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:22.162465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.497986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:22.162295Z","caller":"traceutil/trace.go:171","msg":"trace[900382897] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"862.575285ms","start":"2026-02-20T15:24:21.2997Z","end":"2026-02-20T15:24:22.162276Z","steps":["trace[900382897] 'process raft request' (duration: 862.302338ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:22.162516Z","caller":"traceutil/trace.go:171","msg":"trace[2101020653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"564.588368ms","start":"2026-02-20T15:24:21.597914Z","end":"2026-02-20T15:24:22.162502Z","steps":["trace[2101020653] 'agreement among raft nodes before linearized reading' (duration: 564.435464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:22.162524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:21.302209Z","time spent":"860.264191ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:22.162558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:21.597897Z","time spent":"564.65015ms","remote":"127.0.0.1:56702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T15:24:22.162674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:21.299668Z","time spent":"862.895325ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:22.162808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:21.299669Z","time spent":"862.780371ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:22.162312Z","caller":"traceutil/trace.go:171","msg":"trace[344787712] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1031; }","duration":"564.345072ms","start":"2026-02-20T15:24:21.597947Z","end":"2026-02-20T15:24:22.162292Z","steps":["trace[344787712] 'read index received' (duration: 484.752548ms)","trace[344787712] 'applied index is now lower than readState.Index' (duration: 79.591424ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:24:22.162343Z","caller":"traceutil/trace.go:171","msg":"trace[930727137] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"861.31404ms","start":"2026-02-20T15:24:21.301024Z","end":"2026-02-20T15:24:22.162338Z","steps":["trace[930727137] 'process raft request' (duration: 861.171056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:22.163573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:21.301007Z","time spent":"862.492404ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:22.162365Z","caller":"traceutil/trace.go:171","msg":"trace[1480767868] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"861.920618ms","start":"2026-02-20T15:24:21.30044Z","end":"2026-02-20T15:24:22.162361Z","steps":["trace[1480767868] 'process raft request' (duration: 861.671221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:22.163786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:21.300427Z","time spent":"863.291976ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:24.458685Z","caller":"traceutil/trace.go:171","msg":"trace[787629734] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"288.42211ms","start":"2026-02-20T15:24:24.170239Z","end":"2026-02-20T15:24:24.458661Z","steps":["trace[787629734] 'process raft request' (duration: 288.267786ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:24.757021Z","caller":"traceutil/trace.go:171","msg":"trace[1608250682] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"585.645196ms","start":"2026-02-20T15:24:24.171348Z","end":"2026-02-20T15:24:24.756994Z","steps":["trace[1608250682] 'process raft request' (duration: 585.357768ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:24.757155Z","caller":"traceutil/trace.go:171","msg":"trace[1201001142] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"584.601587ms","start":"2026-02-20T15:24:24.172523Z","end":"2026-02-20T15:24:24.757124Z","steps":["trace[1201001142] 'process raft request' (duration: 584.34356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:24.757307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:24.171302Z","time spent":"585.79404ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:24.757312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:24.172491Z","time spent":"584.745601ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:24.758318Z","caller":"traceutil/trace.go:171","msg":"trace[1523911978] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"585.37761ms","start":"2026-02-20T15:24:24.172871Z","end":"2026-02-20T15:24:24.758249Z","steps":["trace[1523911978] 'process raft request' (duration: 584.054463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:24.7585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:24.172861Z","time spent":"585.552034ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:24:24.758546Z","caller":"traceutil/trace.go:171","msg":"trace[1091530391] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"584.909366ms","start":"2026-02-20T15:24:24.1736Z","end":"2026-02-20T15:24:24.75851Z","steps":["trace[1091530391] 'process raft request' (duration: 584.250097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:24.758669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:24.173591Z","time spent":"585.007279ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:24.875499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.577356ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T15:24:26.87542Z","caller":"traceutil/trace.go:171","msg":"trace[1888860980] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"372.251533ms","start":"2026-02-20T15:24:26.503152Z","end":"2026-02-20T15:24:26.875404Z","steps":["trace[1888860980] 'read index received' (duration: 372.096848ms)","trace[1888860980] 'applied index is now lower than readState.Index' (duration: 153.975µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:24:26.87555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.376216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:24:26.875584Z","caller":"traceutil/trace.go:171","msg":"trace[1084271558] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1002; }","duration":"372.449619ms","start":"2026-02-20T15:24:26.503126Z","end":"2026-02-20T15:24:26.875576Z","steps":["trace[1084271558] 'agreement among raft nodes before linearized reading' (duration: 372.367876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:26.875609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:26.503114Z","time spent":"372.488089ms","remote":"127.0.0.1:54968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-02-20T15:24:26.875535Z","caller":"traceutil/trace.go:171","msg":"trace[1284338041] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"403.057064ms","start":"2026-02-20T15:24:26.472422Z","end":"2026-02-20T15:24:26.875479Z","steps":["trace[1284338041] 'process raft request' (duration: 402.870019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:26.875718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.849774ms","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-02-20T15:24:26.875732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.659658ms","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-02-20T15:24:26.875775Z","caller":"traceutil/trace.go:171","msg":"trace[726248572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"112.964517ms","start":"2026-02-20T15:24:26.762798Z","end":"2026-02-20T15:24:26.875762Z","steps":["trace[726248572] 'agreement among raft nodes before linearized reading' (duration: 112.783832ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:24:26.875786Z","caller":"traceutil/trace.go:171","msg":"trace[1576314388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"112.72673ms","start":"2026-02-20T15:24:26.763046Z","end":"2026-02-20T15:24:26.875773Z","steps":["trace[1576314388] 'agreement among raft nodes before linearized reading' (duration: 112.564926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:26.875718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.964817ms","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-02-20T15:24:26.875866Z","caller":"traceutil/trace.go:171","msg":"trace[2028168957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"113.169612ms","start":"2026-02-20T15:24:26.762688Z","end":"2026-02-20T15:24:26.875858Z","steps":["trace[2028168957] 'agreement among raft nodes before linearized reading' (duration: 112.902335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:24:26.875871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:24:26.472374Z","time spent":"403.313822ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:24:26.876563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.909712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T15:24:26.876806Z","caller":"traceutil/trace.go:171","msg":"trace[1837790457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"115.205559ms","start":"2026-02-20T15:24:26.761544Z","end":"2026-02-20T15:24:26.876749Z","steps":["trace[1837790457] 'agreement among raft nodes before linearized reading' (duration: 113.987585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:25:09.304798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.947218ms","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-02-20T15:25:09.304896Z","caller":"traceutil/trace.go:171","msg":"trace[341898420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"103.087902ms","start":"2026-02-20T15:25:09.201791Z","end":"2026-02-20T15:25:09.304879Z","steps":["trace[341898420] 'range keys from in-memory index tree' (duration: 102.796994ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:26:23.227477Z","caller":"traceutil/trace.go:171","msg":"trace[2100432612] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"152.950468ms","start":"2026-02-20T15:26:23.07451Z","end":"2026-02-20T15:26:23.22746Z","steps":["trace[2100432612] 'process raft request' (duration: 152.688061ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:26:45.718523Z","caller":"traceutil/trace.go:171","msg":"trace[718727371] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"111.629832ms","start":"2026-02-20T15:26:45.60686Z","end":"2026-02-20T15:26:45.71849Z","steps":["trace[718727371] 'process raft request' (duration: 111.21868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:26:47.833591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.291919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-20T15:26:47.833655Z","caller":"traceutil/trace.go:171","msg":"trace[681012135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1602; }","duration":"112.399642ms","start":"2026-02-20T15:26:47.721242Z","end":"2026-02-20T15:26:47.833642Z","steps":["trace[681012135] 'range keys from in-memory index tree' (duration: 112.135735ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:26:48.190668Z","caller":"traceutil/trace.go:171","msg":"trace[989763257] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"352.344464ms","start":"2026-02-20T15:26:47.838298Z","end":"2026-02-20T15:26:48.190642Z","steps":["trace[989763257] 'process raft request' (duration: 293.110914ms)","trace[989763257] 'compare' (duration: 58.813108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:26:48.190874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:26:47.838281Z","time spent":"352.518468ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:26:48.191069Z","caller":"traceutil/trace.go:171","msg":"trace[2115860555] linearizableReadLoop","detail":"{readStateIndex:1677; appliedIndex:1673; }","duration":"346.842632ms","start":"2026-02-20T15:26:47.844203Z","end":"2026-02-20T15:26:48.191046Z","steps":["trace[2115860555] 'read index received' (duration: 60.492424ms)","trace[2115860555] 'applied index is now lower than readState.Index' (duration: 286.349138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:26:48.191393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.177711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-20T15:26:48.191463Z","caller":"traceutil/trace.go:171","msg":"trace[51999654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1606; }","duration":"347.273394ms","start":"2026-02-20T15:26:47.844173Z","end":"2026-02-20T15:26:48.191446Z","steps":["trace[51999654] 'agreement among raft nodes before linearized reading' (duration: 347.10375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:26:48.191556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:26:47.84416Z","time spent":"347.381797ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T15:26:48.191577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.329815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T15:26:48.191628Z","caller":"traceutil/trace.go:171","msg":"trace[333413514] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1606; }","duration":"347.398268ms","start":"2026-02-20T15:26:47.844215Z","end":"2026-02-20T15:26:48.191613Z","steps":["trace[333413514] 'agreement among raft nodes before linearized reading' (duration: 347.218843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:26:48.191672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:26:47.844204Z","time spent":"347.457469ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-20T15:26:48.19168Z","caller":"traceutil/trace.go:171","msg":"trace[451196961] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"352.20978ms","start":"2026-02-20T15:26:47.839455Z","end":"2026-02-20T15:26:48.191664Z","steps":["trace[451196961] 'process raft request' (duration: 350.986097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:26:48.191767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:26:47.83944Z","time spent":"352.278572ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:26:48.19199Z","caller":"traceutil/trace.go:171","msg":"trace[1938051322] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"351.697316ms","start":"2026-02-20T15:26:47.840268Z","end":"2026-02-20T15:26:48.191965Z","steps":["trace[1938051322] 'process raft request' (duration: 350.686328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:26:48.192103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:26:47.840264Z","time spent":"351.786729ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:26:48.19217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.861818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-02-20T15:26:48.192224Z","caller":"traceutil/trace.go:171","msg":"trace[1732774692] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1606; }","duration":"312.914429ms","start":"2026-02-20T15:26:47.879294Z","end":"2026-02-20T15:26:48.192209Z","steps":["trace[1732774692] 'agreement among raft nodes before linearized reading' (duration: 312.472037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:26:48.192268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:26:47.87924Z","time spent":"313.018002ms","remote":"127.0.0.1:56792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":6,"response size":363661,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-20T15:26:48.192624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.637049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T15:26:48.192677Z","caller":"traceutil/trace.go:171","msg":"trace[1013201941] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:1606; }","duration":"345.705531ms","start":"2026-02-20T15:26:47.84696Z","end":"2026-02-20T15:26:48.192665Z","steps":["trace[1013201941] 'agreement among raft nodes before linearized reading' (duration: 345.571017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:26:48.192715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:26:47.846953Z","time spent":"345.753982ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"info","ts":"2026-02-20T15:26:48.379274Z","caller":"traceutil/trace.go:171","msg":"trace[493203980] transaction","detail":"{read_only:false; response_revision:1609; number_of_response:1; }","duration":"164.385033ms","start":"2026-02-20T15:26:48.214865Z","end":"2026-02-20T15:26:48.37925Z","steps":["trace[493203980] 'process raft request' (duration: 101.334008ms)","trace[493203980] 'compare' (duration: 62.914571ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:26:48.653967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.358654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:26:48.654064Z","caller":"traceutil/trace.go:171","msg":"trace[1836632875] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1681; }","duration":"270.669256ms","start":"2026-02-20T15:26:48.383379Z","end":"2026-02-20T15:26:48.654048Z","steps":["trace[1836632875] 'read index received' (duration: 6.384305ms)","trace[1836632875] 'applied index is now lower than readState.Index' (duration: 264.283891ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:26:48.654087Z","caller":"traceutil/trace.go:171","msg":"trace[1256653447] transaction","detail":"{read_only:false; response_revision:1611; number_of_response:1; }","duration":"271.036087ms","start":"2026-02-20T15:26:48.383038Z","end":"2026-02-20T15:26:48.654074Z","steps":["trace[1256653447] 'process raft request' (duration: 127.477897ms)","trace[1256653447] 'compare' (duration: 143.232681ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:26:48.654127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.750719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-20T15:26:48.654151Z","caller":"traceutil/trace.go:171","msg":"trace[987519209] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1611; }","duration":"270.834911ms","start":"2026-02-20T15:26:48.383309Z","end":"2026-02-20T15:26:48.654143Z","steps":["trace[987519209] 'agreement among raft nodes before linearized reading' (duration: 270.782269ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:26:51.02433Z","caller":"traceutil/trace.go:171","msg":"trace[1856992776] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"197.699149ms","start":"2026-02-20T15:26:50.826605Z","end":"2026-02-20T15:26:51.024304Z","steps":["trace[1856992776] 'process raft request' (duration: 197.567146ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:26:58.552264Z","caller":"traceutil/trace.go:171","msg":"trace[1436923470] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"105.925535ms","start":"2026-02-20T15:26:58.44632Z","end":"2026-02-20T15:26:58.552246Z","steps":["trace[1436923470] 'process raft request' (duration: 105.799111ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:26:58.591744Z","caller":"traceutil/trace.go:171","msg":"trace[569719629] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"144.841075ms","start":"2026-02-20T15:26:58.446879Z","end":"2026-02-20T15:26:58.59172Z","steps":["trace[569719629] 'process raft request' (duration: 144.787153ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:26:58.591837Z","caller":"traceutil/trace.go:171","msg":"trace[2092626495] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"145.319458ms","start":"2026-02-20T15:26:58.446482Z","end":"2026-02-20T15:26:58.591801Z","steps":["trace[2092626495] 'process raft request' (duration: 145.047541ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:14.257166Z","caller":"traceutil/trace.go:171","msg":"trace[1007076482] transaction","detail":"{read_only:false; response_revision:1711; number_of_response:1; }","duration":"114.67385ms","start":"2026-02-20T15:27:14.142445Z","end":"2026-02-20T15:27:14.257118Z","steps":["trace[1007076482] 'process raft request' (duration: 114.511406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:14.710062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.964971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:27:14.710371Z","caller":"traceutil/trace.go:171","msg":"trace[421731048] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"178.878304ms","start":"2026-02-20T15:27:14.53147Z","end":"2026-02-20T15:27:14.710348Z","steps":["trace[421731048] 'process raft request' (duration: 76.552323ms)","trace[421731048] 'compare' (duration: 101.663363ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:14.710405Z","caller":"traceutil/trace.go:171","msg":"trace[1068849463] linearizableReadLoop","detail":"{readStateIndex:1792; appliedIndex:1790; }","duration":"113.068356ms","start":"2026-02-20T15:27:14.59732Z","end":"2026-02-20T15:27:14.710388Z","steps":["trace[1068849463] 'read index received' (duration: 10.714274ms)","trace[1068849463] 'applied index is now lower than readState.Index' (duration: 102.353162ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:14.710581Z","caller":"traceutil/trace.go:171","msg":"trace[865634142] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"179.019089ms","start":"2026-02-20T15:27:14.531538Z","end":"2026-02-20T15:27:14.710557Z","steps":["trace[865634142] 'process raft request' (duration: 178.664239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:14.710603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.233371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:27:14.710691Z","caller":"traceutil/trace.go:171","msg":"trace[1148102788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1715; }","duration":"113.317924ms","start":"2026-02-20T15:27:14.597312Z","end":"2026-02-20T15:27:14.71063Z","steps":["trace[1148102788] 'agreement among raft nodes before linearized reading' (duration: 113.17941ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:14.915714Z","caller":"traceutil/trace.go:171","msg":"trace[110322492] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"115.908044ms","start":"2026-02-20T15:27:14.799777Z","end":"2026-02-20T15:27:14.915685Z","steps":["trace[110322492] 'process raft request' (duration: 63.646758ms)","trace[110322492] 'compare' (duration: 52.141563ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:16.261513Z","caller":"traceutil/trace.go:171","msg":"trace[1890798442] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"148.228983ms","start":"2026-02-20T15:27:16.113259Z","end":"2026-02-20T15:27:16.261488Z","steps":["trace[1890798442] 'process raft request' (duration: 148.019777ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:16.428854Z","caller":"traceutil/trace.go:171","msg":"trace[313406581] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"163.142382ms","start":"2026-02-20T15:27:16.265681Z","end":"2026-02-20T15:27:16.428824Z","steps":["trace[313406581] 'process raft request' (duration: 128.287254ms)","trace[313406581] 'compare' (duration: 34.744175ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:16.677812Z","caller":"traceutil/trace.go:171","msg":"trace[1231682737] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"160.419437ms","start":"2026-02-20T15:27:16.517348Z","end":"2026-02-20T15:27:16.677767Z","steps":["trace[1231682737] 'process raft request' (duration: 63.446133ms)","trace[1231682737] 'compare' (duration: 96.734058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:16.983927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.195546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:27:16.98402Z","caller":"traceutil/trace.go:171","msg":"trace[349703105] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"266.301796ms","start":"2026-02-20T15:27:16.7177Z","end":"2026-02-20T15:27:16.984002Z","steps":["trace[349703105] 'process raft request' (duration: 107.960107ms)","trace[349703105] 'compare' (duration: 157.944388ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:16.999315Z","caller":"traceutil/trace.go:171","msg":"trace[629560426] linearizableReadLoop","detail":"{readStateIndex:1799; appliedIndex:1797; }","duration":"199.078119ms","start":"2026-02-20T15:27:16.800219Z","end":"2026-02-20T15:27:16.999298Z","steps":["trace[629560426] 'read index received' (duration: 25.515392ms)","trace[629560426] 'applied index is now lower than readState.Index' (duration: 173.561707ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:16.999381Z","caller":"traceutil/trace.go:171","msg":"trace[1039887279] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"281.532384ms","start":"2026-02-20T15:27:16.717828Z","end":"2026-02-20T15:27:16.999361Z","steps":["trace[1039887279] 'process raft request' (duration: 281.37904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:16.999628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.393908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T15:27:16.999675Z","caller":"traceutil/trace.go:171","msg":"trace[1279605918] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1722; }","duration":"199.48464ms","start":"2026-02-20T15:27:16.800177Z","end":"2026-02-20T15:27:16.999662Z","steps":["trace[1279605918] 'agreement among raft nodes before linearized reading' (duration: 199.252844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:16.999823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.531964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:27:17.000009Z","caller":"traceutil/trace.go:171","msg":"trace[1661409119] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1722; }","duration":"135.713449ms","start":"2026-02-20T15:27:16.864227Z","end":"2026-02-20T15:27:16.999941Z","steps":["trace[1661409119] 'agreement among raft nodes before linearized reading' (duration: 135.529244ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:17.944001Z","caller":"traceutil/trace.go:171","msg":"trace[1951898795] linearizableReadLoop","detail":"{readStateIndex:1803; appliedIndex:1802; }","duration":"103.542185ms","start":"2026-02-20T15:27:17.840431Z","end":"2026-02-20T15:27:17.943973Z","steps":["trace[1951898795] 'read index received' (duration: 15.972809ms)","trace[1951898795] 'applied index is now lower than readState.Index' (duration: 87.568446ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:17.944156Z","caller":"traceutil/trace.go:171","msg":"trace[372702364] transaction","detail":"{read_only:false; response_revision:1726; number_of_response:1; }","duration":"162.755152ms","start":"2026-02-20T15:27:17.781369Z","end":"2026-02-20T15:27:17.944124Z","steps":["trace[372702364] 'process raft request' (duration: 75.125374ms)","trace[372702364] 'compare' (duration: 87.389411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:17.944387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.948576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-02-20T15:27:17.944472Z","caller":"traceutil/trace.go:171","msg":"trace[1704914168] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1726; }","duration":"104.07576ms","start":"2026-02-20T15:27:17.840376Z","end":"2026-02-20T15:27:17.944452Z","steps":["trace[1704914168] 'agreement among raft nodes before linearized reading' (duration: 103.70428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:18.331352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.233606ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-20T15:27:18.331463Z","caller":"traceutil/trace.go:171","msg":"trace[1357817728] linearizableReadLoop","detail":"{readStateIndex:1804; appliedIndex:1803; }","duration":"340.439222ms","start":"2026-02-20T15:27:17.991006Z","end":"2026-02-20T15:27:18.331445Z","steps":["trace[1357817728] 'read index received' (duration: 217.108434ms)","trace[1357817728] 'applied index is now lower than readState.Index' (duration: 123.329318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:18.331546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:17.945622Z","time spent":"385.921851ms","remote":"127.0.0.1:56722","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T15:27:18.331831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.803533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T15:27:18.331941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.386743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-02-20T15:27:18.331982Z","caller":"traceutil/trace.go:171","msg":"trace[2041900415] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1726; }","duration":"284.465635ms","start":"2026-02-20T15:27:18.047506Z","end":"2026-02-20T15:27:18.331972Z","steps":["trace[2041900415] 'agreement among raft nodes before linearized reading' (duration: 284.208148ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:18.331918Z","caller":"traceutil/trace.go:171","msg":"trace[1209694872] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1726; }","duration":"340.943027ms","start":"2026-02-20T15:27:17.990955Z","end":"2026-02-20T15:27:18.331898Z","steps":["trace[1209694872] 'agreement among raft nodes before linearized reading' (duration: 340.802353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:18.332284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:17.990937Z","time spent":"341.209714ms","remote":"127.0.0.1:57076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-20T15:27:19.039828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.118472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-20T15:27:19.039926Z","caller":"traceutil/trace.go:171","msg":"trace[1800448261] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1730; }","duration":"230.271716ms","start":"2026-02-20T15:27:18.809633Z","end":"2026-02-20T15:27:19.039905Z","steps":["trace[1800448261] 'range keys from in-memory index tree' (duration: 229.941737ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:29.324057Z","caller":"traceutil/trace.go:171","msg":"trace[2077425874] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"177.299391ms","start":"2026-02-20T15:27:29.146728Z","end":"2026-02-20T15:27:29.324027Z","steps":["trace[2077425874] 'process raft request' (duration: 141.343074ms)","trace[2077425874] 'compare' (duration: 35.758031ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:29.324613Z","caller":"traceutil/trace.go:171","msg":"trace[406781157] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"177.832416ms","start":"2026-02-20T15:27:29.146764Z","end":"2026-02-20T15:27:29.324597Z","steps":["trace[406781157] 'process raft request' (duration: 177.193488ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:29.324934Z","caller":"traceutil/trace.go:171","msg":"trace[1520642080] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"177.685021ms","start":"2026-02-20T15:27:29.147199Z","end":"2026-02-20T15:27:29.324884Z","steps":["trace[1520642080] 'process raft request' (duration: 177.461965ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:29.324907Z","caller":"traceutil/trace.go:171","msg":"trace[809693504] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"177.925108ms","start":"2026-02-20T15:27:29.146803Z","end":"2026-02-20T15:27:29.324728Z","steps":["trace[809693504] 'process raft request' (duration: 177.449945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:50.154605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.353277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:27:50.154701Z","caller":"traceutil/trace.go:171","msg":"trace[688111024] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"408.302427ms","start":"2026-02-20T15:27:49.746376Z","end":"2026-02-20T15:27:50.154679Z","steps":["trace[688111024] 'read index received' (duration: 129.710233ms)","trace[688111024] 'applied index is now lower than readState.Index' (duration: 278.591044ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:50.154776Z","caller":"traceutil/trace.go:171","msg":"trace[1721659265] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"418.381264ms","start":"2026-02-20T15:27:49.736386Z","end":"2026-02-20T15:27:50.154767Z","steps":["trace[1721659265] 'process raft request' (duration: 139.76115ms)","trace[1721659265] 'compare' (duration: 277.906055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:50.154843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:49.73637Z","time spent":"418.449286ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:27:50.155118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.724749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T15:27:50.155183Z","caller":"traceutil/trace.go:171","msg":"trace[1432491360] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1887; }","duration":"408.825031ms","start":"2026-02-20T15:27:49.746342Z","end":"2026-02-20T15:27:50.155167Z","steps":["trace[1432491360] 'agreement among raft nodes before linearized reading' (duration: 408.630286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:50.155184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.681682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-20T15:27:50.155222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:49.746331Z","time spent":"408.884794ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-20T15:27:50.155251Z","caller":"traceutil/trace.go:171","msg":"trace[248064773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1887; }","duration":"406.768735ms","start":"2026-02-20T15:27:49.748466Z","end":"2026-02-20T15:27:50.155234Z","steps":["trace[248064773] 'agreement among raft nodes before linearized reading' (duration: 406.316493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:50.155297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:49.748458Z","time spent":"406.830847ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T15:27:50.448629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.085225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:27:50.448848Z","caller":"traceutil/trace.go:171","msg":"trace[946756100] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1973; }","duration":"105.132099ms","start":"2026-02-20T15:27:50.343696Z","end":"2026-02-20T15:27:50.448828Z","steps":["trace[946756100] 'read index received' (duration: 57.13105ms)","trace[946756100] 'applied index is now lower than readState.Index' (duration: 47.999639ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:50.448858Z","caller":"traceutil/trace.go:171","msg":"trace[890173563] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"289.218975ms","start":"2026-02-20T15:27:50.15961Z","end":"2026-02-20T15:27:50.448829Z","steps":["trace[890173563] 'process raft request' (duration: 170.808851ms)","trace[890173563] 'compare' (duration: 117.946341ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:50.448933Z","caller":"traceutil/trace.go:171","msg":"trace[9319480] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"288.172756ms","start":"2026-02-20T15:27:50.160715Z","end":"2026-02-20T15:27:50.448888Z","steps":["trace[9319480] 'process raft request' (duration: 288.026932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:50.448974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.252742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:27:50.449159Z","caller":"traceutil/trace.go:171","msg":"trace[602386601] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1889; }","duration":"105.529269ms","start":"2026-02-20T15:27:50.343611Z","end":"2026-02-20T15:27:50.44914Z","steps":["trace[602386601] 'agreement among raft nodes before linearized reading' (duration: 105.286523ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:50.925489Z","caller":"traceutil/trace.go:171","msg":"trace[1846364479] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"199.402787ms","start":"2026-02-20T15:27:50.72606Z","end":"2026-02-20T15:27:50.925463Z","steps":["trace[1846364479] 'process raft request' (duration: 199.198392ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:53.046565Z","caller":"traceutil/trace.go:171","msg":"trace[1067035790] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"113.93642ms","start":"2026-02-20T15:27:52.932586Z","end":"2026-02-20T15:27:53.046522Z","steps":["trace[1067035790] 'process raft request' (duration: 113.769746ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:56.499599Z","caller":"traceutil/trace.go:171","msg":"trace[1326268468] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"127.148153ms","start":"2026-02-20T15:27:56.372437Z","end":"2026-02-20T15:27:56.499585Z","steps":["trace[1326268468] 'process raft request' (duration: 127.057661ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:56.499624Z","caller":"traceutil/trace.go:171","msg":"trace[723253954] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2001; }","duration":"108.041058ms","start":"2026-02-20T15:27:56.391557Z","end":"2026-02-20T15:27:56.499598Z","steps":["trace[723253954] 'read index received' (duration: 108.013757ms)","trace[723253954] 'applied index is now lower than readState.Index' (duration: 25.501µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:56.499975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.390918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T15:27:56.500024Z","caller":"traceutil/trace.go:171","msg":"trace[2143992142] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1915; }","duration":"108.4809ms","start":"2026-02-20T15:27:56.391531Z","end":"2026-02-20T15:27:56.500012Z","steps":["trace[2143992142] 'agreement among raft nodes before linearized reading' (duration: 108.197542ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:57.157948Z","caller":"traceutil/trace.go:171","msg":"trace[576899479] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2004; }","duration":"312.549886ms","start":"2026-02-20T15:27:56.845379Z","end":"2026-02-20T15:27:57.157929Z","steps":["trace[576899479] 'read index received' (duration: 312.399332ms)","trace[576899479] 'applied index is now lower than readState.Index' (duration: 149.484µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:57.158155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.826274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:979"} {"level":"info","ts":"2026-02-20T15:27:57.158176Z","caller":"traceutil/trace.go:171","msg":"trace[517023813] range","detail":"{range_begin:/registry/clusterroles/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1918; }","duration":"312.921517ms","start":"2026-02-20T15:27:56.845247Z","end":"2026-02-20T15:27:57.158169Z","steps":["trace[517023813] 'agreement among raft nodes before linearized reading' (duration: 312.799203ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:57.158132Z","caller":"traceutil/trace.go:171","msg":"trace[1034280844] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"358.339894ms","start":"2026-02-20T15:27:56.799708Z","end":"2026-02-20T15:27:57.158048Z","steps":["trace[1034280844] 'process raft request' (duration: 358.089008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:57.158396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:56.799685Z","time spent":"358.573531ms","remote":"127.0.0.1:56882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6151,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:27:57.158198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:56.845205Z","time spent":"312.988518ms","remote":"127.0.0.1:57052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":1002,"request content":"key:\"/registry/clusterroles/ceph-csi-rbd-nodeplugin\" "} {"level":"warn","ts":"2026-02-20T15:27:57.158818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.968636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:1708"} {"level":"info","ts":"2026-02-20T15:27:57.158847Z","caller":"traceutil/trace.go:171","msg":"trace[1951934275] range","detail":"{range_begin:/registry/clusterroles/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1918; }","duration":"294.043988ms","start":"2026-02-20T15:27:56.864796Z","end":"2026-02-20T15:27:57.15884Z","steps":["trace[1951934275] 'agreement among raft nodes before linearized reading' (duration: 293.951896ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:57.158943Z","caller":"traceutil/trace.go:171","msg":"trace[506247199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1918; }","duration":"100.008837ms","start":"2026-02-20T15:27:57.058908Z","end":"2026-02-20T15:27:57.158917Z","steps":["trace[506247199] 'agreement among raft nodes before linearized reading' (duration: 99.901894ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:57.577492Z","caller":"traceutil/trace.go:171","msg":"trace[156833374] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2006; }","duration":"290.272064ms","start":"2026-02-20T15:27:57.287193Z","end":"2026-02-20T15:27:57.577465Z","steps":["trace[156833374] 'read index received' (duration: 289.960485ms)","trace[156833374] 'applied index is now lower than readState.Index' (duration: 310.249µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:57.577547Z","caller":"traceutil/trace.go:171","msg":"trace[1105326567] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"308.877525ms","start":"2026-02-20T15:27:57.268642Z","end":"2026-02-20T15:27:57.57752Z","steps":["trace[1105326567] 'process raft request' (duration: 308.574907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:57.577699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:57.268618Z","time spent":"309.007789ms","remote":"127.0.0.1:56882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:27:57.577728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.519401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-20T15:27:57.577765Z","caller":"traceutil/trace.go:171","msg":"trace[1655187703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1920; }","duration":"290.620934ms","start":"2026-02-20T15:27:57.287135Z","end":"2026-02-20T15:27:57.577756Z","steps":["trace[1655187703] 'agreement among raft nodes before linearized reading' (duration: 290.461669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:57.57785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.817088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-02-20T15:27:57.577989Z","caller":"traceutil/trace.go:171","msg":"trace[1075606564] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:1920; }","duration":"256.028713ms","start":"2026-02-20T15:27:57.321928Z","end":"2026-02-20T15:27:57.577957Z","steps":["trace[1075606564] 'agreement among raft nodes before linearized reading' (duration: 255.750236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:57.577857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.590469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-02-20T15:27:57.578236Z","caller":"traceutil/trace.go:171","msg":"trace[1417940385] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1920; }","duration":"253.026731ms","start":"2026-02-20T15:27:57.325169Z","end":"2026-02-20T15:27:57.578195Z","steps":["trace[1417940385] 'agreement among raft nodes before linearized reading' (duration: 252.541257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:57.881397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.423509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:27:57.88153Z","caller":"traceutil/trace.go:171","msg":"trace[1110787351] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"297.516663ms","start":"2026-02-20T15:27:57.583994Z","end":"2026-02-20T15:27:57.881511Z","steps":["trace[1110787351] 'read index received' (duration: 109.814207ms)","trace[1110787351] 'applied index is now lower than readState.Index' (duration: 187.701276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:57.881703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.325831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"warn","ts":"2026-02-20T15:27:57.881733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.733539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5913"} {"level":"info","ts":"2026-02-20T15:27:57.881748Z","caller":"traceutil/trace.go:171","msg":"trace[1662890212] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:1921; }","duration":"296.428084ms","start":"2026-02-20T15:27:57.585309Z","end":"2026-02-20T15:27:57.881737Z","steps":["trace[1662890212] 'agreement among raft nodes before linearized reading' (duration: 296.330171ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:57.881766Z","caller":"traceutil/trace.go:171","msg":"trace[1845912240] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1921; }","duration":"297.76771ms","start":"2026-02-20T15:27:57.583986Z","end":"2026-02-20T15:27:57.881754Z","steps":["trace[1845912240] 'agreement among raft nodes before linearized reading' (duration: 297.617796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:57.881831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.156646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-02-20T15:27:57.881931Z","caller":"traceutil/trace.go:171","msg":"trace[1664405234] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1921; }","duration":"193.27835ms","start":"2026-02-20T15:27:57.688606Z","end":"2026-02-20T15:27:57.881884Z","steps":["trace[1664405234] 'agreement among raft nodes before linearized reading' (duration: 193.136886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:57.881902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.742612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-20T15:27:57.882003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.81875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:27:57.88202Z","caller":"traceutil/trace.go:171","msg":"trace[974506451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1921; }","duration":"118.935968ms","start":"2026-02-20T15:27:57.763067Z","end":"2026-02-20T15:27:57.882003Z","steps":["trace[974506451] 'agreement among raft nodes before linearized reading' (duration: 118.66206ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:57.882047Z","caller":"traceutil/trace.go:171","msg":"trace[418693580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"286.900322ms","start":"2026-02-20T15:27:57.595134Z","end":"2026-02-20T15:27:57.882034Z","steps":["trace[418693580] 'agreement among raft nodes before linearized reading' (duration: 286.82696ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:57.881581Z","caller":"traceutil/trace.go:171","msg":"trace[325100747] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"297.939325ms","start":"2026-02-20T15:27:57.583588Z","end":"2026-02-20T15:27:57.881527Z","steps":["trace[325100747] 'process raft request' (duration: 110.238539ms)","trace[325100747] 'compare' (duration: 187.274754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:58.104876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.574435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:27:58.104994Z","caller":"traceutil/trace.go:171","msg":"trace[1252508387] linearizableReadLoop","detail":"{readStateIndex:2013; appliedIndex:2010; }","duration":"141.106687ms","start":"2026-02-20T15:27:57.96387Z","end":"2026-02-20T15:27:58.104977Z","steps":["trace[1252508387] 'read index received' (duration: 1.172922ms)","trace[1252508387] 'applied index is now lower than readState.Index' (duration: 139.932605ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:58.105065Z","caller":"traceutil/trace.go:171","msg":"trace[887451227] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"163.999886ms","start":"2026-02-20T15:27:57.941034Z","end":"2026-02-20T15:27:58.105033Z","steps":["trace[887451227] 'process raft request' (duration: 40.163883ms)","trace[887451227] 'compare' (duration: 123.402041ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:58.105133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.174066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-20T15:27:58.105162Z","caller":"traceutil/trace.go:171","msg":"trace[549302593] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1925; }","duration":"138.218468ms","start":"2026-02-20T15:27:57.966933Z","end":"2026-02-20T15:27:58.105152Z","steps":["trace[549302593] 'agreement among raft nodes before linearized reading' (duration: 138.146926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:58.10519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.307072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-02-20T15:27:58.105295Z","caller":"traceutil/trace.go:171","msg":"trace[135605420] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1925; }","duration":"141.453326ms","start":"2026-02-20T15:27:57.963811Z","end":"2026-02-20T15:27:58.105265Z","steps":["trace[135605420] 'agreement among raft nodes before linearized reading' (duration: 141.24954ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:58.308642Z","caller":"traceutil/trace.go:171","msg":"trace[1661102118] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"108.571853ms","start":"2026-02-20T15:27:58.200016Z","end":"2026-02-20T15:27:58.308587Z","steps":["trace[1661102118] 'process raft request' (duration: 44.595645ms)","trace[1661102118] 'compare' (duration: 63.816304ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:58.710004Z","caller":"traceutil/trace.go:171","msg":"trace[1743798162] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"175.766009ms","start":"2026-02-20T15:27:58.534213Z","end":"2026-02-20T15:27:58.709979Z","steps":["trace[1743798162] 'process raft request' (duration: 175.551003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:58.886421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.486672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:27:58.886607Z","caller":"traceutil/trace.go:171","msg":"trace[400952080] linearizableReadLoop","detail":"{readStateIndex:2016; appliedIndex:2014; }","duration":"290.633734ms","start":"2026-02-20T15:27:58.595839Z","end":"2026-02-20T15:27:58.886473Z","steps":["trace[400952080] 'read index received' (duration: 113.981391ms)","trace[400952080] 'applied index is now lower than readState.Index' (duration: 176.651693ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:58.886613Z","caller":"traceutil/trace.go:171","msg":"trace[223178294] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"351.829035ms","start":"2026-02-20T15:27:58.534734Z","end":"2026-02-20T15:27:58.886563Z","steps":["trace[223178294] 'process raft request' (duration: 228.130387ms)","trace[223178294] 'compare' (duration: 123.366269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:58.886713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:27:58.534707Z","time spent":"351.960559ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T15:27:58.886735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.892902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:27:58.886756Z","caller":"traceutil/trace.go:171","msg":"trace[1732464634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1928; }","duration":"290.945452ms","start":"2026-02-20T15:27:58.595804Z","end":"2026-02-20T15:27:58.886749Z","steps":["trace[1732464634] 'agreement among raft nodes before linearized reading' (duration: 290.902841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:58.886981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.56073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"warn","ts":"2026-02-20T15:27:58.887062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.470863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:1 size:1930"} {"level":"info","ts":"2026-02-20T15:27:58.887104Z","caller":"traceutil/trace.go:171","msg":"trace[662417442] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1928; }","duration":"283.779676ms","start":"2026-02-20T15:27:58.603296Z","end":"2026-02-20T15:27:58.887076Z","steps":["trace[662417442] 'agreement among raft nodes before linearized reading' (duration: 283.488528ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:58.887116Z","caller":"traceutil/trace.go:171","msg":"trace[649259327] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:1; response_revision:1928; }","duration":"277.526524ms","start":"2026-02-20T15:27:58.609565Z","end":"2026-02-20T15:27:58.887091Z","steps":["trace[649259327] 'agreement among raft nodes before linearized reading' (duration: 277.442451ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:59.045355Z","caller":"traceutil/trace.go:171","msg":"trace[729901944] linearizableReadLoop","detail":"{readStateIndex:2017; appliedIndex:2016; }","duration":"153.127217ms","start":"2026-02-20T15:27:58.892179Z","end":"2026-02-20T15:27:59.045306Z","steps":["trace[729901944] 'read index received' (duration: 100.644115ms)","trace[729901944] 'applied index is now lower than readState.Index' (duration: 52.481882ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:27:59.045462Z","caller":"traceutil/trace.go:171","msg":"trace[1397802012] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"153.323932ms","start":"2026-02-20T15:27:58.892126Z","end":"2026-02-20T15:27:59.04545Z","steps":["trace[1397802012] 'process raft request' (duration: 100.749688ms)","trace[1397802012] 'compare' (duration: 52.341278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:27:59.04553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.337643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2025"} {"level":"info","ts":"2026-02-20T15:27:59.045559Z","caller":"traceutil/trace.go:171","msg":"trace[1618412080] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1929; }","duration":"153.381534ms","start":"2026-02-20T15:27:58.892171Z","end":"2026-02-20T15:27:59.045553Z","steps":["trace[1618412080] 'agreement among raft nodes before linearized reading' (duration: 153.312852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:27:59.132881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.817734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:auth-delegator\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-02-20T15:27:59.132955Z","caller":"traceutil/trace.go:171","msg":"trace[1337408499] range","detail":"{range_begin:/registry/clusterroles/system:auth-delegator; range_end:; response_count:1; response_revision:1930; }","duration":"162.940427ms","start":"2026-02-20T15:27:58.969997Z","end":"2026-02-20T15:27:59.132938Z","steps":["trace[1337408499] 'agreement among raft nodes before linearized reading' (duration: 162.805323ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:27:59.597523Z","caller":"traceutil/trace.go:171","msg":"trace[220479034] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"120.658295ms","start":"2026-02-20T15:27:59.476829Z","end":"2026-02-20T15:27:59.597487Z","steps":["trace[220479034] 'process raft request' (duration: 120.419789ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:03.111414Z","caller":"traceutil/trace.go:171","msg":"trace[1831600942] linearizableReadLoop","detail":"{readStateIndex:2032; appliedIndex:2031; }","duration":"114.787044ms","start":"2026-02-20T15:28:02.996605Z","end":"2026-02-20T15:28:03.111392Z","steps":["trace[1831600942] 'read index received' (duration: 114.557607ms)","trace[1831600942] 'applied index is now lower than readState.Index' (duration: 228.547µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:03.111548Z","caller":"traceutil/trace.go:171","msg":"trace[296282284] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"144.346996ms","start":"2026-02-20T15:28:02.967176Z","end":"2026-02-20T15:28:03.111523Z","steps":["trace[296282284] 'process raft request' (duration: 144.056738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:03.111636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.785257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:1 size:638"} {"level":"info","ts":"2026-02-20T15:28:03.111693Z","caller":"traceutil/trace.go:171","msg":"trace[1573613665] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:1; response_revision:1944; }","duration":"109.88403ms","start":"2026-02-20T15:28:03.001794Z","end":"2026-02-20T15:28:03.111678Z","steps":["trace[1573613665] 'agreement among raft nodes before linearized reading' (duration: 109.796077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:03.11186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.254517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T15:28:03.111902Z","caller":"traceutil/trace.go:171","msg":"trace[879720113] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1944; }","duration":"115.335939ms","start":"2026-02-20T15:28:02.996553Z","end":"2026-02-20T15:28:03.111889Z","steps":["trace[879720113] 'agreement among raft nodes before linearized reading' (duration: 115.252047ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:04.2149Z","caller":"traceutil/trace.go:171","msg":"trace[2004332600] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"186.799002ms","start":"2026-02-20T15:28:04.028068Z","end":"2026-02-20T15:28:04.214867Z","steps":["trace[2004332600] 'process raft request' (duration: 103.586686ms)","trace[2004332600] 'compare' (duration: 83.099283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:28:04.214997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.505624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:635"} {"level":"info","ts":"2026-02-20T15:28:04.214906Z","caller":"traceutil/trace.go:171","msg":"trace[980420919] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2036; }","duration":"186.35538ms","start":"2026-02-20T15:28:04.028487Z","end":"2026-02-20T15:28:04.214842Z","steps":["trace[980420919] 'read index received' (duration: 103.156774ms)","trace[980420919] 'applied index is now lower than readState.Index' (duration: 83.197726ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:04.215051Z","caller":"traceutil/trace.go:171","msg":"trace[903174414] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:1948; }","duration":"186.587247ms","start":"2026-02-20T15:28:04.028453Z","end":"2026-02-20T15:28:04.215041Z","steps":["trace[903174414] 'agreement among raft nodes before linearized reading' (duration: 186.494664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:04.215213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.291504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T15:28:04.215291Z","caller":"traceutil/trace.go:171","msg":"trace[1179381306] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1948; }","duration":"168.400157ms","start":"2026-02-20T15:28:04.04687Z","end":"2026-02-20T15:28:04.21527Z","steps":["trace[1179381306] 'agreement among raft nodes before linearized reading' (duration: 168.246793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:04.215352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.535032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:949"} {"level":"warn","ts":"2026-02-20T15:28:04.215371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.124995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-20T15:28:04.215396Z","caller":"traceutil/trace.go:171","msg":"trace[744271332] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1948; }","duration":"112.616304ms","start":"2026-02-20T15:28:04.102768Z","end":"2026-02-20T15:28:04.215384Z","steps":["trace[744271332] 'agreement among raft nodes before linearized reading' (duration: 112.480311ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:04.215405Z","caller":"traceutil/trace.go:171","msg":"trace[766797005] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1948; }","duration":"166.166526ms","start":"2026-02-20T15:28:04.049227Z","end":"2026-02-20T15:28:04.215394Z","steps":["trace[766797005] 'agreement among raft nodes before linearized reading' (duration: 166.063123ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:06.228744Z","caller":"traceutil/trace.go:171","msg":"trace[1756254094] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"103.871074ms","start":"2026-02-20T15:28:06.124843Z","end":"2026-02-20T15:28:06.228714Z","steps":["trace[1756254094] 'process raft request' (duration: 103.492453ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:07.3206Z","caller":"traceutil/trace.go:171","msg":"trace[2035110095] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"166.063253ms","start":"2026-02-20T15:28:07.154511Z","end":"2026-02-20T15:28:07.320574Z","steps":["trace[2035110095] 'read index received' (duration: 165.821906ms)","trace[2035110095] 'applied index is now lower than readState.Index' (duration: 240.207µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:07.320675Z","caller":"traceutil/trace.go:171","msg":"trace[1999333159] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"177.058355ms","start":"2026-02-20T15:28:07.143586Z","end":"2026-02-20T15:28:07.320644Z","steps":["trace[1999333159] 'process raft request' (duration: 176.849359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:07.320751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.238647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-20T15:28:07.320808Z","caller":"traceutil/trace.go:171","msg":"trace[1721657148] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1961; }","duration":"166.32636ms","start":"2026-02-20T15:28:07.154471Z","end":"2026-02-20T15:28:07.320797Z","steps":["trace[1721657148] 'agreement among raft nodes before linearized reading' (duration: 166.235647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:08.77107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.802602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-02-20T15:28:08.77113Z","caller":"traceutil/trace.go:171","msg":"trace[367475879] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:1; response_revision:1967; }","duration":"107.929795ms","start":"2026-02-20T15:28:08.663187Z","end":"2026-02-20T15:28:08.771117Z","steps":["trace[367475879] 'range keys from in-memory index tree' (duration: 107.661878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:19.566632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.227789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-20T15:28:19.566726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.026046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-20T15:28:19.566752Z","caller":"traceutil/trace.go:171","msg":"trace[577246478] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:2012; }","duration":"116.370193ms","start":"2026-02-20T15:28:19.450364Z","end":"2026-02-20T15:28:19.566734Z","steps":["trace[577246478] 'range keys from in-memory index tree' (duration: 116.109556ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:19.566787Z","caller":"traceutil/trace.go:171","msg":"trace[1952968224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:2012; }","duration":"119.129109ms","start":"2026-02-20T15:28:19.447646Z","end":"2026-02-20T15:28:19.566775Z","steps":["trace[1952968224] 'range keys from in-memory index tree' (duration: 118.918454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:20.053883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.042919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363638"} {"level":"info","ts":"2026-02-20T15:28:20.053937Z","caller":"traceutil/trace.go:171","msg":"trace[1442109993] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2015; }","duration":"144.137441ms","start":"2026-02-20T15:28:19.909786Z","end":"2026-02-20T15:28:20.053923Z","steps":["trace[1442109993] 'range keys from in-memory index tree' (duration: 143.709339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:21.297155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.458387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-20T15:28:21.297244Z","caller":"traceutil/trace.go:171","msg":"trace[1099323765] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:2019; }","duration":"189.592191ms","start":"2026-02-20T15:28:21.107634Z","end":"2026-02-20T15:28:21.297226Z","steps":["trace[1099323765] 'range keys from in-memory index tree' (duration: 189.344205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:21.297259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.79058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-20T15:28:21.297468Z","caller":"traceutil/trace.go:171","msg":"trace[1404104360] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:2019; }","duration":"180.034746ms","start":"2026-02-20T15:28:21.117387Z","end":"2026-02-20T15:28:21.297422Z","steps":["trace[1404104360] 'range keys from in-memory index tree' (duration: 179.652126ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:22.495201Z","caller":"traceutil/trace.go:171","msg":"trace[1753591145] linearizableReadLoop","detail":"{readStateIndex:2115; appliedIndex:2114; }","duration":"157.023148ms","start":"2026-02-20T15:28:22.338157Z","end":"2026-02-20T15:28:22.49518Z","steps":["trace[1753591145] 'read index received' (duration: 156.720439ms)","trace[1753591145] 'applied index is now lower than readState.Index' (duration: 301.669µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:22.495319Z","caller":"traceutil/trace.go:171","msg":"trace[550356788] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"272.927818ms","start":"2026-02-20T15:28:22.222355Z","end":"2026-02-20T15:28:22.495283Z","steps":["trace[550356788] 'process raft request' (duration: 272.621089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:22.49538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.198932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-20T15:28:22.49542Z","caller":"traceutil/trace.go:171","msg":"trace[161039557] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:2023; }","duration":"157.288995ms","start":"2026-02-20T15:28:22.338117Z","end":"2026-02-20T15:28:22.495406Z","steps":["trace[161039557] 'agreement among raft nodes before linearized reading' (duration: 157.199332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:22.495678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.158604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-20T15:28:22.495825Z","caller":"traceutil/trace.go:171","msg":"trace[650013639] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:2023; }","duration":"117.342179ms","start":"2026-02-20T15:28:22.37846Z","end":"2026-02-20T15:28:22.495802Z","steps":["trace[650013639] 'agreement among raft nodes before linearized reading' (duration: 117.131563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.038887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.376348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:100934"} {"level":"info","ts":"2026-02-20T15:28:23.038973Z","caller":"traceutil/trace.go:171","msg":"trace[1505386209] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:2025; }","duration":"205.508862ms","start":"2026-02-20T15:28:22.833446Z","end":"2026-02-20T15:28:23.038955Z","steps":["trace[1505386209] 'range keys from in-memory index tree' (duration: 204.568646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.039075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.346212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:100934"} {"level":"info","ts":"2026-02-20T15:28:23.039152Z","caller":"traceutil/trace.go:171","msg":"trace[1775058687] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:2025; }","duration":"207.466236ms","start":"2026-02-20T15:28:22.831669Z","end":"2026-02-20T15:28:23.039135Z","steps":["trace[1775058687] 'range keys from in-memory index tree' (duration: 206.431978ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:23.389482Z","caller":"traceutil/trace.go:171","msg":"trace[1930561194] linearizableReadLoop","detail":"{readStateIndex:2118; appliedIndex:2117; }","duration":"261.654416ms","start":"2026-02-20T15:28:23.1278Z","end":"2026-02-20T15:28:23.389454Z","steps":["trace[1930561194] 'read index received' (duration: 261.462261ms)","trace[1930561194] 'applied index is now lower than readState.Index' (duration: 191.125µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:28:23.389652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.839192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-02-20T15:28:23.389715Z","caller":"traceutil/trace.go:171","msg":"trace[640795043] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:2026; }","duration":"261.952915ms","start":"2026-02-20T15:28:23.12775Z","end":"2026-02-20T15:28:23.389703Z","steps":["trace[640795043] 'agreement among raft nodes before linearized reading' (duration: 261.813521ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:23.389525Z","caller":"traceutil/trace.go:171","msg":"trace[421141563] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"346.621279ms","start":"2026-02-20T15:28:23.04288Z","end":"2026-02-20T15:28:23.389501Z","steps":["trace[421141563] 'process raft request' (duration: 346.377003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.390089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:28:23.042856Z","time spent":"347.177615ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T15:28:23.493415Z","caller":"traceutil/trace.go:171","msg":"trace[2042896322] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2118; }","duration":"103.831496ms","start":"2026-02-20T15:28:23.389555Z","end":"2026-02-20T15:28:23.493387Z","steps":["trace[2042896322] 'read index received' (duration: 4.022951ms)","trace[2042896322] 'applied index is now lower than readState.Index' (duration: 99.806785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T15:28:23.493493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.022809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:28:23.49355Z","caller":"traceutil/trace.go:171","msg":"trace[823790189] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2026; }","duration":"313.114032ms","start":"2026-02-20T15:28:23.180418Z","end":"2026-02-20T15:28:23.493532Z","steps":["trace[823790189] 'agreement among raft nodes before linearized reading' (duration: 313.012169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.493563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.959326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-02-20T15:28:23.493582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:28:23.180406Z","time spent":"313.169373ms","remote":"127.0.0.1:41536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-02-20T15:28:23.493598Z","caller":"traceutil/trace.go:171","msg":"trace[655298409] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:2026; }","duration":"357.029878ms","start":"2026-02-20T15:28:23.136557Z","end":"2026-02-20T15:28:23.493587Z","steps":["trace[655298409] 'agreement among raft nodes before linearized reading' (duration: 356.929775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.493632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:28:23.136548Z","time spent":"357.076099ms","remote":"127.0.0.1:56910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"warn","ts":"2026-02-20T15:28:23.493667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.890689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-20T15:28:23.493727Z","caller":"traceutil/trace.go:171","msg":"trace[1193112909] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2026; }","duration":"341.973841ms","start":"2026-02-20T15:28:23.151739Z","end":"2026-02-20T15:28:23.493713Z","steps":["trace[1193112909] 'agreement among raft nodes before linearized reading' (duration: 341.782735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.493773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T15:28:23.151729Z","time spent":"342.033953ms","remote":"127.0.0.1:56976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-20T15:28:23.900636Z","caller":"traceutil/trace.go:171","msg":"trace[722935554] linearizableReadLoop","detail":"{readStateIndex:2121; appliedIndex:2120; }","duration":"221.102484ms","start":"2026-02-20T15:28:23.679506Z","end":"2026-02-20T15:28:23.900609Z","steps":["trace[722935554] 'read index received' (duration: 221.011441ms)","trace[722935554] 'applied index is now lower than readState.Index' (duration: 89.953µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:23.900756Z","caller":"traceutil/trace.go:171","msg":"trace[378945556] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"221.362131ms","start":"2026-02-20T15:28:23.679381Z","end":"2026-02-20T15:28:23.900743Z","steps":["trace[378945556] 'process raft request' (duration: 221.056092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.900973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.418353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-20T15:28:23.901036Z","caller":"traceutil/trace.go:171","msg":"trace[547538799] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:2028; }","duration":"221.538986ms","start":"2026-02-20T15:28:23.67948Z","end":"2026-02-20T15:28:23.901019Z","steps":["trace[547538799] 'agreement among raft nodes before linearized reading' (duration: 221.399252ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:23.945148Z","caller":"traceutil/trace.go:171","msg":"trace[1778286785] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"265.522904ms","start":"2026-02-20T15:28:23.679597Z","end":"2026-02-20T15:28:23.94512Z","steps":["trace[1778286785] 'process raft request' (duration: 265.39065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:23.945543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.22011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-02-20T15:28:23.945625Z","caller":"traceutil/trace.go:171","msg":"trace[1576378401] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:2029; }","duration":"172.358564ms","start":"2026-02-20T15:28:23.773249Z","end":"2026-02-20T15:28:23.945608Z","steps":["trace[1576378401] 'agreement among raft nodes before linearized reading' (duration: 172.176519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:27.759062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.561005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T15:28:27.759147Z","caller":"traceutil/trace.go:171","msg":"trace[1278646869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2043; }","duration":"161.693109ms","start":"2026-02-20T15:28:27.59744Z","end":"2026-02-20T15:28:27.759133Z","steps":["trace[1278646869] 'range keys from in-memory index tree' (duration: 161.502853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:27.75935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.879552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-nw8vj\" ","response":"range_response_count:1 size:13268"} {"level":"info","ts":"2026-02-20T15:28:27.759374Z","caller":"traceutil/trace.go:171","msg":"trace[1088068372] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-nw8vj; range_end:; response_count:1; response_revision:2043; }","duration":"205.929583ms","start":"2026-02-20T15:28:27.553435Z","end":"2026-02-20T15:28:27.759365Z","steps":["trace[1088068372] 'range keys from in-memory index tree' (duration: 205.769439ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:28.041745Z","caller":"traceutil/trace.go:171","msg":"trace[862592477] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"101.204213ms","start":"2026-02-20T15:28:27.940505Z","end":"2026-02-20T15:28:28.04171Z","steps":["trace[862592477] 'process raft request' (duration: 96.214724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:28.847581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.550035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T15:28:28.847784Z","caller":"traceutil/trace.go:171","msg":"trace[515481741] transaction","detail":"{read_only:false; response_revision:2049; number_of_response:1; }","duration":"151.569858ms","start":"2026-02-20T15:28:28.696127Z","end":"2026-02-20T15:28:28.847697Z","steps":["trace[515481741] 'process raft request' (duration: 50.809497ms)","trace[515481741] 'compare' (duration: 100.446092ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:30.840764Z","caller":"traceutil/trace.go:171","msg":"trace[295145512] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2150; }","duration":"111.759405ms","start":"2026-02-20T15:28:30.728981Z","end":"2026-02-20T15:28:30.84074Z","steps":["trace[295145512] 'read index received' (duration: 111.58529ms)","trace[295145512] 'applied index is now lower than readState.Index' (duration: 172.915µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:30.840882Z","caller":"traceutil/trace.go:171","msg":"trace[657211842] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"150.21213ms","start":"2026-02-20T15:28:30.690643Z","end":"2026-02-20T15:28:30.840856Z","steps":["trace[657211842] 'process raft request' (duration: 149.920892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:30.841034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.038394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mr65s\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-02-20T15:28:30.841085Z","caller":"traceutil/trace.go:171","msg":"trace[381258091] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mr65s; range_end:; response_count:1; response_revision:2057; }","duration":"112.136206ms","start":"2026-02-20T15:28:30.728935Z","end":"2026-02-20T15:28:30.841071Z","steps":["trace[381258091] 'agreement among raft nodes before linearized reading' (duration: 111.90267ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:32.311386Z","caller":"traceutil/trace.go:171","msg":"trace[1222992441] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"218.490981ms","start":"2026-02-20T15:28:32.092872Z","end":"2026-02-20T15:28:32.311363Z","steps":["trace[1222992441] 'read index received' (duration: 218.278505ms)","trace[1222992441] 'applied index is now lower than readState.Index' (duration: 211.696µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T15:28:32.311462Z","caller":"traceutil/trace.go:171","msg":"trace[307546255] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"232.86801ms","start":"2026-02-20T15:28:32.078545Z","end":"2026-02-20T15:28:32.311413Z","steps":["trace[307546255] 'process raft request' (duration: 232.655304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T15:28:32.311707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.707025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-vbndj\" ","response":"range_response_count:1 size:4844"} {"level":"warn","ts":"2026-02-20T15:28:32.311702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.80131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-20T15:28:32.311776Z","caller":"traceutil/trace.go:171","msg":"trace[226739472] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-vbndj; range_end:; response_count:1; response_revision:2062; }","duration":"215.798796ms","start":"2026-02-20T15:28:32.095957Z","end":"2026-02-20T15:28:32.311755Z","steps":["trace[226739472] 'agreement among raft nodes before linearized reading' (duration: 215.664983ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T15:28:32.311786Z","caller":"traceutil/trace.go:171","msg":"trace[957849967] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2062; }","duration":"218.930014ms","start":"2026-02-20T15:28:32.092839Z","end":"2026-02-20T15:28:32.311769Z","steps":["trace[957849967] 'agreement among raft nodes before linearized reading' (duration: 218.658566ms)"],"step_count":1}