{"level":"warn","ts":"2026-02-20T10:09:44.105914Z","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-20T10:09:44.106112Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-20T10:09:44.10629Z","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-20T10:09:44.106379Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-02-20T10:09:44.106487Z","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-20T10:09:44.107497Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-02-20T10:09:44.107656Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-20T10:09:44.112704Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.684969ms"} {"level":"info","ts":"2026-02-20T10:09:44.118976Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-02-20T10:09:44.119053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T10:09:44.119087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-02-20T10:09:44.119102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T10:09:44.119111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-02-20T10:09:44.119181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-02-20T10:09:44.128967Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T10:09:44.130608Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T10:09:44.131876Z","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-20T10:09:44.134027Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T10:09:44.134344Z","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-20T10:09:44.134438Z","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-20T10:09:44.134451Z","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-20T10:09:44.134443Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T10:09:44.135371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-02-20T10:09:44.135674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-02-20T10:09:44.144045Z","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-20T10:09:44.144232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-02-20T10:09:44.144308Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T10:09:44.144355Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T10:09:44.144345Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-02-20T10:09:44.520117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T10:09:44.520177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T10:09:44.5202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-02-20T10:09:44.520221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-02-20T10:09:44.520239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-02-20T10:09:44.520257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-02-20T10:09:44.520298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-02-20T10:09:44.521191Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T10:09:44.521215Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:09:44.521495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T10:09:44.521606Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T10:09:44.521663Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T10:09:44.521913Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T10:09:44.527672Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:09:44.52883Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T10:09:44.531978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-02-20T10:09:44.5337Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:09:44.533763Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T10:09:47.683859Z","caller":"traceutil/trace.go:171","msg":"trace[1269372395] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"159.18356ms","start":"2026-02-20T10:09:47.524653Z","end":"2026-02-20T10:09:47.683836Z","steps":["trace[1269372395] 'process raft request' (duration: 121.542506ms)","trace[1269372395] 'compare' (duration: 37.551522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:09:50.877808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.654113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-20T10:09:50.877935Z","caller":"traceutil/trace.go:171","msg":"trace[1114885261] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:314; }","duration":"175.859927ms","start":"2026-02-20T10:09:50.702055Z","end":"2026-02-20T10:09:50.877915Z","steps":["trace[1114885261] 'range keys from in-memory index tree' (duration: 175.52041ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:14.456732Z","caller":"traceutil/trace.go:171","msg":"trace[332904980] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"244.834152ms","start":"2026-02-20T10:10:14.211863Z","end":"2026-02-20T10:10:14.456697Z","steps":["trace[332904980] 'read index received' (duration: 244.609807ms)","trace[332904980] 'applied index is now lower than readState.Index' (duration: 223.015µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:10:14.456817Z","caller":"traceutil/trace.go:171","msg":"trace[1874920812] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"371.170599ms","start":"2026-02-20T10:10:14.085602Z","end":"2026-02-20T10:10:14.456772Z","steps":["trace[1874920812] 'process raft request' (duration: 370.962074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:14.456873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.999496ms","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-20T10:10:14.456919Z","caller":"traceutil/trace.go:171","msg":"trace[1087203660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"245.086508ms","start":"2026-02-20T10:10:14.211817Z","end":"2026-02-20T10:10:14.456904Z","steps":["trace[1087203660] 'agreement among raft nodes before linearized reading' (duration: 245.001766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:14.45727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:14.085591Z","time spent":"371.289192ms","remote":"127.0.0.1:57314","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-20T10:10:15.372097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:14.942473Z","time spent":"429.616789ms","remote":"127.0.0.1:47100","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T10:10:15.372487Z","caller":"traceutil/trace.go:171","msg":"trace[1062637647] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"912.294173ms","start":"2026-02-20T10:10:14.460174Z","end":"2026-02-20T10:10:15.372468Z","steps":["trace[1062637647] 'process raft request' (duration: 909.680132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:15.372609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:14.460155Z","time spent":"912.364855ms","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:10:16.956192Z","caller":"traceutil/trace.go:171","msg":"trace[1346173019] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"172.060323ms","start":"2026-02-20T10:10:16.784109Z","end":"2026-02-20T10:10:16.956169Z","steps":["trace[1346173019] 'process raft request' (duration: 171.817437ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:17.734644Z","caller":"traceutil/trace.go:171","msg":"trace[480067719] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"357.235888ms","start":"2026-02-20T10:10:17.377387Z","end":"2026-02-20T10:10:17.734623Z","steps":["trace[480067719] 'read index received' (duration: 357.129565ms)","trace[480067719] 'applied index is now lower than readState.Index' (duration: 105.193µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:17.734676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:17.351292Z","time spent":"383.377481ms","remote":"127.0.0.1:57064","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T10:10:17.734782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.378851ms","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-20T10:10:17.734806Z","caller":"traceutil/trace.go:171","msg":"trace[1898604440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"357.445242ms","start":"2026-02-20T10:10:17.377356Z","end":"2026-02-20T10:10:17.734801Z","steps":["trace[1898604440] 'agreement among raft nodes before linearized reading' (duration: 357.37557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:17.734827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:17.377339Z","time spent":"357.483533ms","remote":"127.0.0.1:57314","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-20T10:10:17.735024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.940745ms","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-20T10:10:17.735044Z","caller":"traceutil/trace.go:171","msg":"trace[1204979648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"108.993686ms","start":"2026-02-20T10:10:17.626046Z","end":"2026-02-20T10:10:17.735039Z","steps":["trace[1204979648] 'agreement among raft nodes before linearized reading' (duration: 108.956876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:18.310107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538745708,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:10:18.790672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.509065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:10:18.790933Z","caller":"traceutil/trace.go:171","msg":"trace[1637750381] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:530; }","duration":"981.14938ms","start":"2026-02-20T10:10:17.809763Z","end":"2026-02-20T10:10:18.790912Z","steps":["trace[1637750381] 'read index received' (duration: 820.238215ms)","trace[1637750381] 'applied index is now lower than readState.Index' (duration: 160.910045ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:10:18.791066Z","caller":"traceutil/trace.go:171","msg":"trace[2004730672] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.054458073s","start":"2026-02-20T10:10:17.736597Z","end":"2026-02-20T10:10:18.791055Z","steps":["trace[2004730672] 'process raft request' (duration: 893.308373ms)","trace[2004730672] 'compare' (duration: 160.177088ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:18.791132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:17.736583Z","time spent":"1.054514504s","remote":"127.0.0.1:57064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:10:18.791375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.549629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:18.791395Z","caller":"traceutil/trace.go:171","msg":"trace[779552400] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.052054497s","start":"2026-02-20T10:10:17.73931Z","end":"2026-02-20T10:10:18.791365Z","steps":["trace[779552400] 'process raft request' (duration: 1.051553175s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:18.791424Z","caller":"traceutil/trace.go:171","msg":"trace[1138288750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"495.642531ms","start":"2026-02-20T10:10:18.295771Z","end":"2026-02-20T10:10:18.791414Z","steps":["trace[1138288750] 'agreement among raft nodes before linearized reading' (duration: 495.549659ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:18.791407Z","caller":"traceutil/trace.go:171","msg":"trace[1725805556] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.052496588s","start":"2026-02-20T10:10:17.738854Z","end":"2026-02-20T10:10:18.791351Z","steps":["trace[1725805556] 'process raft request' (duration: 1.051934945s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:18.791456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:18.29575Z","time spent":"495.700383ms","remote":"127.0.0.1:57028","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-20T10:10:18.791478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.689433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T10:10:18.791516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:17.7393Z","time spent":"1.052149409s","remote":"127.0.0.1:57314","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-20T10:10:18.791535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:17.738831Z","time spent":"1.052640921s","remote":"127.0.0.1:57314","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-20T10:10:18.791535Z","caller":"traceutil/trace.go:171","msg":"trace[304245856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"981.764515ms","start":"2026-02-20T10:10:17.809756Z","end":"2026-02-20T10:10:18.79152Z","steps":["trace[304245856] 'agreement among raft nodes before linearized reading' (duration: 981.536339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:18.791576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:17.809686Z","time spent":"981.883257ms","remote":"127.0.0.1:57314","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-20T10:10:18.791658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.516877ms","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-20T10:10:18.791697Z","caller":"traceutil/trace.go:171","msg":"trace[376244737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"287.608649ms","start":"2026-02-20T10:10:18.50408Z","end":"2026-02-20T10:10:18.791688Z","steps":["trace[376244737] 'agreement among raft nodes before linearized reading' (duration: 287.540797ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:22.941701Z","caller":"traceutil/trace.go:171","msg":"trace[1372169553] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"465.26247ms","start":"2026-02-20T10:10:22.476415Z","end":"2026-02-20T10:10:22.941677Z","steps":["trace[1372169553] 'process raft request' (duration: 465.112757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:22.941846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:22.476392Z","time spent":"465.383343ms","remote":"127.0.0.1:57222","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-20T10:10:24.001861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.959796ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T10:10:24.002056Z","caller":"traceutil/trace.go:171","msg":"trace[865714311] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"1.197298861s","start":"2026-02-20T10:10:22.804655Z","end":"2026-02-20T10:10:24.001954Z","steps":["trace[865714311] 'read index received' (duration: 136.844149ms)","trace[865714311] 'applied index is now lower than readState.Index' (duration: 1.060453062s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:24.002266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197596837s","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-20T10:10:24.002316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195048699s","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-20T10:10:24.00234Z","caller":"traceutil/trace.go:171","msg":"trace[395739189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"1.19771977s","start":"2026-02-20T10:10:22.804603Z","end":"2026-02-20T10:10:24.002323Z","steps":["trace[395739189] 'agreement among raft nodes before linearized reading' (duration: 1.197496405s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:24.002387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:22.804568Z","time spent":"1.197810553s","remote":"127.0.0.1:57314","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-20T10:10:24.002364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.889006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T10:10:24.002468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193010832s","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-20T10:10:24.002495Z","caller":"traceutil/trace.go:171","msg":"trace[1797722612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"1.193074572s","start":"2026-02-20T10:10:22.809415Z","end":"2026-02-20T10:10:24.002489Z","steps":["trace[1797722612] 'agreement among raft nodes before linearized reading' (duration: 1.193019771s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:24.002489Z","caller":"traceutil/trace.go:171","msg":"trace[769380154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"706.05273ms","start":"2026-02-20T10:10:23.296398Z","end":"2026-02-20T10:10:24.002451Z","steps":["trace[769380154] 'agreement among raft nodes before linearized reading' (duration: 705.838615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:24.002524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:22.809393Z","time spent":"1.193125204s","remote":"127.0.0.1:57314","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-20T10:10:24.002352Z","caller":"traceutil/trace.go:171","msg":"trace[1898607118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"1.19511646s","start":"2026-02-20T10:10:22.807224Z","end":"2026-02-20T10:10:24.00234Z","steps":["trace[1898607118] 'agreement among raft nodes before linearized reading' (duration: 1.194944066s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:24.002597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:22.807213Z","time spent":"1.195374465s","remote":"127.0.0.1:57314","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-20T10:10:24.002419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191369223s","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-20T10:10:24.002649Z","caller":"traceutil/trace.go:171","msg":"trace[809074222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"1.191607319s","start":"2026-02-20T10:10:22.811031Z","end":"2026-02-20T10:10:24.002638Z","steps":["trace[809074222] 'agreement among raft nodes before linearized reading' (duration: 1.191335802s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:24.002687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:22.81102Z","time spent":"1.191657849s","remote":"127.0.0.1:57314","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-20T10:10:24.002552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:23.296374Z","time spent":"706.167932ms","remote":"127.0.0.1:57028","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-20T10:10:25.621694Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.547310172s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T10:10:25.74057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.604078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:10:25.74076Z","caller":"traceutil/trace.go:171","msg":"trace[1269813669] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:546; }","duration":"445.326801ms","start":"2026-02-20T10:10:25.295418Z","end":"2026-02-20T10:10:25.740744Z","steps":["trace[1269813669] 'read index received' (duration: 326.417466ms)","trace[1269813669] 'applied index is now lower than readState.Index' (duration: 118.908445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:25.740832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.417154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:25.740864Z","caller":"traceutil/trace.go:171","msg":"trace[119597452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"445.501665ms","start":"2026-02-20T10:10:25.295352Z","end":"2026-02-20T10:10:25.740854Z","steps":["trace[119597452] 'agreement among raft nodes before linearized reading' (duration: 445.437824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:25.7409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:25.295335Z","time spent":"445.559026ms","remote":"127.0.0.1:57028","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-20T10:10:25.741091Z","caller":"traceutil/trace.go:171","msg":"trace[967192998] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.697617652s","start":"2026-02-20T10:10:24.043457Z","end":"2026-02-20T10:10:25.741075Z","steps":["trace[967192998] 'process raft request' (duration: 1.697253084s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:25.741185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:24.043443Z","time spent":"1.697699774s","remote":"127.0.0.1:57314","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-20T10:10:25.741358Z","caller":"traceutil/trace.go:171","msg":"trace[1030863528] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.733560512s","start":"2026-02-20T10:10:24.007226Z","end":"2026-02-20T10:10:25.740786Z","steps":["trace[1030863528] 'process raft request' (duration: 1.733431069s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:25.741395Z","caller":"traceutil/trace.go:171","msg":"trace[1729315406] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.733986002s","start":"2026-02-20T10:10:24.00679Z","end":"2026-02-20T10:10:25.740776Z","steps":["trace[1729315406] 'process raft request' (duration: 1.615096707s)","trace[1729315406] 'compare' (duration: 118.303071ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:25.741532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:24.007219Z","time spent":"1.734235008s","remote":"127.0.0.1:57314","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-20T10:10:25.741679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:24.006781Z","time spent":"1.734818152s","remote":"127.0.0.1:57314","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-20T10:10:26.566497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.415833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:26.566589Z","caller":"traceutil/trace.go:171","msg":"trace[1497389391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"254.559487ms","start":"2026-02-20T10:10:26.312012Z","end":"2026-02-20T10:10:26.566572Z","steps":["trace[1497389391] 'range keys from in-memory index tree' (duration: 254.350692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:26.566813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.02989ms","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-20T10:10:26.566883Z","caller":"traceutil/trace.go:171","msg":"trace[1816221940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"489.159533ms","start":"2026-02-20T10:10:26.077707Z","end":"2026-02-20T10:10:26.566866Z","steps":["trace[1816221940] 'range keys from in-memory index tree' (duration: 488.862355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:26.566918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:26.077684Z","time spent":"489.227264ms","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T10:10:27.355785Z","caller":"traceutil/trace.go:171","msg":"trace[1920572704] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"105.496606ms","start":"2026-02-20T10:10:27.250254Z","end":"2026-02-20T10:10:27.355751Z","steps":["trace[1920572704] 'process raft request' (duration: 105.168828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:29.116119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.422403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:29.116234Z","caller":"traceutil/trace.go:171","msg":"trace[1188670674] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"147.587517ms","start":"2026-02-20T10:10:28.968625Z","end":"2026-02-20T10:10:29.116213Z","steps":["trace[1188670674] 'range keys from in-memory index tree' (duration: 147.346601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:29.116375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.000474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:29.116458Z","caller":"traceutil/trace.go:171","msg":"trace[801420905] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:567; }","duration":"348.133207ms","start":"2026-02-20T10:10:28.768305Z","end":"2026-02-20T10:10:29.116438Z","steps":["trace[801420905] 'range keys from in-memory index tree' (duration: 347.925112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:29.116496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:28.768287Z","time spent":"348.202228ms","remote":"127.0.0.1:57088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-02-20T10:10:37.432937Z","caller":"traceutil/trace.go:171","msg":"trace[1818495486] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"137.030433ms","start":"2026-02-20T10:10:37.295881Z","end":"2026-02-20T10:10:37.432911Z","steps":["trace[1818495486] 'read index received' (duration: 136.846249ms)","trace[1818495486] 'applied index is now lower than readState.Index' (duration: 183.394µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:10:37.433019Z","caller":"traceutil/trace.go:171","msg":"trace[1187814219] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"254.362232ms","start":"2026-02-20T10:10:37.178628Z","end":"2026-02-20T10:10:37.43299Z","steps":["trace[1187814219] 'process raft request' (duration: 254.167628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:37.433147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.243138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:37.433199Z","caller":"traceutil/trace.go:171","msg":"trace[334661482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"137.349391ms","start":"2026-02-20T10:10:37.295838Z","end":"2026-02-20T10:10:37.433187Z","steps":["trace[334661482] 'agreement among raft nodes before linearized reading' (duration: 137.195157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:37.99463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.279708ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:37.994757Z","caller":"traceutil/trace.go:171","msg":"trace[1668699510] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:649; }","duration":"386.418251ms","start":"2026-02-20T10:10:37.608321Z","end":"2026-02-20T10:10:37.994739Z","steps":["trace[1668699510] 'range keys from in-memory index tree' (duration: 386.267248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:37.995332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.23726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:10:37.995433Z","caller":"traceutil/trace.go:171","msg":"trace[1001857593] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"541.151164ms","start":"2026-02-20T10:10:37.454263Z","end":"2026-02-20T10:10:37.995415Z","steps":["trace[1001857593] 'process raft request' (duration: 40.729881ms)","trace[1001857593] 'compare' (duration: 499.505182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:37.995498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:37.454239Z","time spent":"541.224155ms","remote":"127.0.0.1:57064","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-20T10:10:38.01003Z","caller":"traceutil/trace.go:171","msg":"trace[1643347468] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"196.175419ms","start":"2026-02-20T10:10:37.813831Z","end":"2026-02-20T10:10:38.010007Z","steps":["trace[1643347468] 'read index received' (duration: 196.040446ms)","trace[1643347468] 'applied index is now lower than readState.Index' (duration: 133.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:10:38.010086Z","caller":"traceutil/trace.go:171","msg":"trace[857108993] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"344.003572ms","start":"2026-02-20T10:10:37.666053Z","end":"2026-02-20T10:10:38.010057Z","steps":["trace[857108993] 'process raft request' (duration: 343.774087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:38.010235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:37.666032Z","time spent":"344.122015ms","remote":"127.0.0.1:57314","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-20T10:10:38.010298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.457396ms","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-20T10:10:38.010368Z","caller":"traceutil/trace.go:171","msg":"trace[1089210604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"196.569528ms","start":"2026-02-20T10:10:37.81378Z","end":"2026-02-20T10:10:38.01035Z","steps":["trace[1089210604] 'agreement among raft nodes before linearized reading' (duration: 196.370053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:38.010576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.846453ms","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-20T10:10:38.010777Z","caller":"traceutil/trace.go:171","msg":"trace[558822381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"190.099369ms","start":"2026-02-20T10:10:37.820657Z","end":"2026-02-20T10:10:38.010756Z","steps":["trace[558822381] 'agreement among raft nodes before linearized reading' (duration: 189.536206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:38.010831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.975366ms","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-20T10:10:38.011072Z","caller":"traceutil/trace.go:171","msg":"trace[132089665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"161.223392ms","start":"2026-02-20T10:10:37.849825Z","end":"2026-02-20T10:10:38.011048Z","steps":["trace[132089665] 'agreement among raft nodes before linearized reading' (duration: 160.900254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:45.422295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384305168s","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-20T10:10:45.422383Z","caller":"traceutil/trace.go:171","msg":"trace[444285453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:708; }","duration":"1.384430421s","start":"2026-02-20T10:10:44.037932Z","end":"2026-02-20T10:10:45.422363Z","steps":["trace[444285453] 'range keys from in-memory index tree' (duration: 1.384146724s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:45.422421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:44.037914Z","time spent":"1.384499162s","remote":"127.0.0.1:57314","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-20T10:10:45.422623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379004766s","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-20T10:10:45.422652Z","caller":"traceutil/trace.go:171","msg":"trace[37030963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:708; }","duration":"1.379049456s","start":"2026-02-20T10:10:44.043594Z","end":"2026-02-20T10:10:45.422643Z","steps":["trace[37030963] 'range keys from in-memory index tree' (duration: 1.378935944s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:45.422676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:44.043588Z","time spent":"1.379081977s","remote":"127.0.0.1:57314","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-20T10:10:45.422812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379254321s","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-20T10:10:45.422859Z","caller":"traceutil/trace.go:171","msg":"trace[1768777151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:708; }","duration":"1.379330943s","start":"2026-02-20T10:10:44.043516Z","end":"2026-02-20T10:10:45.422847Z","steps":["trace[1768777151] 'range keys from in-memory index tree' (duration: 1.379091278s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:45.422879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:44.043503Z","time spent":"1.379371263s","remote":"127.0.0.1:57314","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-20T10:10:45.423169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.245204ms","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-20T10:10:45.423183Z","caller":"traceutil/trace.go:171","msg":"trace[143157490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:708; }","duration":"978.283835ms","start":"2026-02-20T10:10:44.444895Z","end":"2026-02-20T10:10:45.423179Z","steps":["trace[143157490] 'range keys from in-memory index tree' (duration: 978.127612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:45.423199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:44.444881Z","time spent":"978.314426ms","remote":"127.0.0.1:57314","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-20T10:10:45.42331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.295001ms","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-20T10:10:45.423322Z","caller":"traceutil/trace.go:171","msg":"trace[885100981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:708; }","duration":"779.331942ms","start":"2026-02-20T10:10:44.643987Z","end":"2026-02-20T10:10:45.423319Z","steps":["trace[885100981] 'range keys from in-memory index tree' (duration: 779.206329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:45.423333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:44.643971Z","time spent":"779.359462ms","remote":"127.0.0.1:57314","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-20T10:10:45.423478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.112335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:45.423494Z","caller":"traceutil/trace.go:171","msg":"trace[303473940] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:708; }","duration":"542.167537ms","start":"2026-02-20T10:10:44.88132Z","end":"2026-02-20T10:10:45.423488Z","steps":["trace[303473940] 'count revisions from in-memory index tree' (duration: 542.037353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:45.423509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:44.881301Z","time spent":"542.203867ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:10:45.423498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.494064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:45.423556Z","caller":"traceutil/trace.go:171","msg":"trace[1357595812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"127.567485ms","start":"2026-02-20T10:10:45.295959Z","end":"2026-02-20T10:10:45.423526Z","steps":["trace[1357595812] 'range keys from in-memory index tree' (duration: 127.435562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:46.795273Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.36747688s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T10:10:46.795619Z","caller":"traceutil/trace.go:171","msg":"trace[1546805614] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"1.36789544s","start":"2026-02-20T10:10:45.427701Z","end":"2026-02-20T10:10:46.795596Z","steps":["trace[1546805614] 'process raft request' (duration: 1.367758377s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:46.795744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:45.427679Z","time spent":"1.367993322s","remote":"127.0.0.1:57314","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-20T10:10:47.140341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.453854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:10:47.140561Z","caller":"traceutil/trace.go:171","msg":"trace[589556370] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:733; }","duration":"826.895821ms","start":"2026-02-20T10:10:46.313649Z","end":"2026-02-20T10:10:47.140544Z","steps":["trace[589556370] 'read index received' (duration: 481.817334ms)","trace[589556370] 'applied index is now lower than readState.Index' (duration: 345.077367ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:10:47.140642Z","caller":"traceutil/trace.go:171","msg":"trace[866854655] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"1.712644839s","start":"2026-02-20T10:10:45.427984Z","end":"2026-02-20T10:10:47.140629Z","steps":["trace[866854655] 'process raft request' (duration: 1.522816246s)","trace[866854655] 'compare' (duration: 189.359472ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:47.14071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:45.42797Z","time spent":"1.712708231s","remote":"127.0.0.1:57314","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-20T10:10:47.140832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.182037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:47.14084Z","caller":"traceutil/trace.go:171","msg":"trace[488805693] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"1.709976577s","start":"2026-02-20T10:10:45.430844Z","end":"2026-02-20T10:10:47.140821Z","steps":["trace[488805693] 'process raft request' (duration: 1.709637059s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:47.140837Z","caller":"traceutil/trace.go:171","msg":"trace[360377572] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"1.711893152s","start":"2026-02-20T10:10:45.428918Z","end":"2026-02-20T10:10:47.140812Z","steps":["trace[360377572] 'process raft request' (duration: 1.711509213s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:47.140923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:45.430837Z","time spent":"1.710045049s","remote":"127.0.0.1:57314","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-20T10:10:47.140921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.153301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T10:10:47.140958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:45.428906Z","time spent":"1.711991804s","remote":"127.0.0.1:57314","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-20T10:10:47.140858Z","caller":"traceutil/trace.go:171","msg":"trace[734883872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"827.232258ms","start":"2026-02-20T10:10:46.313615Z","end":"2026-02-20T10:10:47.140848Z","steps":["trace[734883872] 'agreement among raft nodes before linearized reading' (duration: 827.192787ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:47.140996Z","caller":"traceutil/trace.go:171","msg":"trace[2041375547] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:713; }","duration":"628.281085ms","start":"2026-02-20T10:10:46.512696Z","end":"2026-02-20T10:10:47.140977Z","steps":["trace[2041375547] 'agreement among raft nodes before linearized reading' (duration: 628.130811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:47.141024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:46.313601Z","time spent":"827.416573ms","remote":"127.0.0.1:57028","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-20T10:10:47.141041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:46.51268Z","time spent":"628.349747ms","remote":"127.0.0.1:57236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-20T10:10:47.141524Z","caller":"traceutil/trace.go:171","msg":"trace[1474369407] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"1.708883542s","start":"2026-02-20T10:10:45.431833Z","end":"2026-02-20T10:10:47.140716Z","steps":["trace[1474369407] 'process raft request' (duration: 1.708680217s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:47.141685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:45.431828Z","time spent":"1.709787973s","remote":"127.0.0.1:57314","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-20T10:10:48.880313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.374731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:48.880385Z","caller":"traceutil/trace.go:171","msg":"trace[764167595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:713; }","duration":"584.491383ms","start":"2026-02-20T10:10:48.295876Z","end":"2026-02-20T10:10:48.880368Z","steps":["trace[764167595] 'range keys from in-memory index tree' (duration: 584.291659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:48.880348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273430348s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:48.880459Z","caller":"traceutil/trace.go:171","msg":"trace[1430453862] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:713; }","duration":"1.273554861s","start":"2026-02-20T10:10:47.60689Z","end":"2026-02-20T10:10:48.880445Z","steps":["trace[1430453862] 'range keys from in-memory index tree' (duration: 1.273413838s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:48.880422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:48.295857Z","time spent":"584.557035ms","remote":"127.0.0.1:57028","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-20T10:10:48.880652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528328313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T10:10:48.880752Z","caller":"traceutil/trace.go:171","msg":"trace[1056685644] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:713; }","duration":"1.528453515s","start":"2026-02-20T10:10:47.352265Z","end":"2026-02-20T10:10:48.880719Z","steps":["trace[1056685644] 'range keys from in-memory index tree' (duration: 1.528171619s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:48.880794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:47.352247Z","time spent":"1.528537898s","remote":"127.0.0.1:57064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-02-20T10:10:48.880799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.056348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:10:48.880994Z","caller":"traceutil/trace.go:171","msg":"trace[2140796588] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"505.862649ms","start":"2026-02-20T10:10:48.375117Z","end":"2026-02-20T10:10:48.880979Z","steps":["trace[2140796588] 'process raft request' (duration: 179.572246ms)","trace[2140796588] 'compare' (duration: 325.966335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:48.881081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:48.375064Z","time spent":"505.961211ms","remote":"127.0.0.1:57314","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-20T10:10:49.628296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.883686ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-20T10:10:49.628373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:48.883034Z","time spent":"745.328557ms","remote":"127.0.0.1:57064","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T10:10:49.635576Z","caller":"traceutil/trace.go:171","msg":"trace[1784278859] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"750.904056ms","start":"2026-02-20T10:10:48.884659Z","end":"2026-02-20T10:10:49.635563Z","steps":["trace[1784278859] 'process raft request' (duration: 750.822064ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:49.635612Z","caller":"traceutil/trace.go:171","msg":"trace[1550481256] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:741; }","duration":"489.711656ms","start":"2026-02-20T10:10:49.145875Z","end":"2026-02-20T10:10:49.635587Z","steps":["trace[1550481256] 'read index received' (duration: 489.701446ms)","trace[1550481256] 'applied index is now lower than readState.Index' (duration: 8.12µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:49.635657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:48.884643Z","time spent":"750.962827ms","remote":"127.0.0.1:57314","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-20T10:10:49.635747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.87189ms","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-20T10:10:49.635769Z","caller":"traceutil/trace.go:171","msg":"trace[2101406360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"489.922291ms","start":"2026-02-20T10:10:49.14584Z","end":"2026-02-20T10:10:49.635763Z","steps":["trace[2101406360] 'agreement among raft nodes before linearized reading' (duration: 489.841349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:49.635793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:49.145821Z","time spent":"489.967762ms","remote":"127.0.0.1:57314","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-20T10:10:49.915844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.355404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:10:49.916002Z","caller":"traceutil/trace.go:171","msg":"trace[328174868] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"277.708002ms","start":"2026-02-20T10:10:49.638278Z","end":"2026-02-20T10:10:49.915986Z","steps":["trace[328174868] 'process raft request' (duration: 277.65394ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:49.915973Z","caller":"traceutil/trace.go:171","msg":"trace[840103541] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"286.579937ms","start":"2026-02-20T10:10:49.629363Z","end":"2026-02-20T10:10:49.915943Z","steps":["trace[840103541] 'process raft request' (duration: 161.054378ms)","trace[840103541] 'compare' (duration: 125.267773ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:10:49.916025Z","caller":"traceutil/trace.go:171","msg":"trace[923096561] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"280.312411ms","start":"2026-02-20T10:10:49.635696Z","end":"2026-02-20T10:10:49.916008Z","steps":["trace[923096561] 'read index received' (duration: 154.714181ms)","trace[923096561] 'applied index is now lower than readState.Index' (duration: 125.59614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:49.916171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.269143ms","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-20T10:10:49.916206Z","caller":"traceutil/trace.go:171","msg":"trace[1821439287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"770.307124ms","start":"2026-02-20T10:10:49.14589Z","end":"2026-02-20T10:10:49.916197Z","steps":["trace[1821439287] 'agreement among raft nodes before linearized reading' (duration: 770.165171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:49.916256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:49.145872Z","time spent":"770.366205ms","remote":"127.0.0.1:57314","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-20T10:10:49.916356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.368003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T10:10:49.916372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.626268ms","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-20T10:10:49.916393Z","caller":"traceutil/trace.go:171","msg":"trace[298257358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"620.453715ms","start":"2026-02-20T10:10:49.295928Z","end":"2026-02-20T10:10:49.916382Z","steps":["trace[298257358] 'agreement among raft nodes before linearized reading' (duration: 620.379553ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:49.9164Z","caller":"traceutil/trace.go:171","msg":"trace[1495856038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"769.665009ms","start":"2026-02-20T10:10:49.146727Z","end":"2026-02-20T10:10:49.916392Z","steps":["trace[1495856038] 'agreement among raft nodes before linearized reading' (duration: 769.589247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:49.916428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:49.146721Z","time spent":"769.70033ms","remote":"127.0.0.1:57314","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-20T10:10:49.916471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.216445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T10:10:49.916497Z","caller":"traceutil/trace.go:171","msg":"trace[457553506] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:717; }","duration":"496.278247ms","start":"2026-02-20T10:10:49.420212Z","end":"2026-02-20T10:10:49.91649Z","steps":["trace[457553506] 'agreement among raft nodes before linearized reading' (duration: 496.230696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:49.916511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.080078ms","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-20T10:10:49.916423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:49.295908Z","time spent":"620.508605ms","remote":"127.0.0.1:57028","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-20T10:10:49.916522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:49.420192Z","time spent":"496.323179ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-20T10:10:49.916544Z","caller":"traceutil/trace.go:171","msg":"trace[932076035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"770.120809ms","start":"2026-02-20T10:10:49.146414Z","end":"2026-02-20T10:10:49.916534Z","steps":["trace[932076035] 'agreement among raft nodes before linearized reading' (duration: 770.042377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:49.91657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:49.146408Z","time spent":"770.155301ms","remote":"127.0.0.1:57314","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-20T10:10:50.189514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.590509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:10:50.189668Z","caller":"traceutil/trace.go:171","msg":"trace[2004795712] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:743; }","duration":"268.801666ms","start":"2026-02-20T10:10:49.920856Z","end":"2026-02-20T10:10:50.189658Z","steps":["trace[2004795712] 'read index received' (duration: 101.004712ms)","trace[2004795712] 'applied index is now lower than readState.Index' (duration: 167.796474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:50.189721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.862577ms","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-20T10:10:50.189739Z","caller":"traceutil/trace.go:171","msg":"trace[516703779] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:720; }","duration":"268.892028ms","start":"2026-02-20T10:10:49.920842Z","end":"2026-02-20T10:10:50.189734Z","steps":["trace[516703779] 'agreement among raft nodes before linearized reading' (duration: 268.847797ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:50.189887Z","caller":"traceutil/trace.go:171","msg":"trace[1796400840] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"270.67908ms","start":"2026-02-20T10:10:49.9192Z","end":"2026-02-20T10:10:50.189879Z","steps":["trace[1796400840] 'process raft request' (duration: 102.64609ms)","trace[1796400840] 'compare' (duration: 167.482757ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:10:50.189982Z","caller":"traceutil/trace.go:171","msg":"trace[822742337] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"269.878961ms","start":"2026-02-20T10:10:49.920097Z","end":"2026-02-20T10:10:50.189976Z","steps":["trace[822742337] 'process raft request' (duration: 269.543483ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:50.189964Z","caller":"traceutil/trace.go:171","msg":"trace[2056938245] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"270.659529ms","start":"2026-02-20T10:10:49.919276Z","end":"2026-02-20T10:10:50.189935Z","steps":["trace[2056938245] 'process raft request' (duration: 270.330671ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:50.672413Z","caller":"traceutil/trace.go:171","msg":"trace[1039559650] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"246.92306ms","start":"2026-02-20T10:10:50.425467Z","end":"2026-02-20T10:10:50.67239Z","steps":["trace[1039559650] 'process raft request' (duration: 246.817468ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:10:51.556686Z","caller":"traceutil/trace.go:171","msg":"trace[588634666] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"339.217281ms","start":"2026-02-20T10:10:51.217444Z","end":"2026-02-20T10:10:51.556661Z","steps":["trace[588634666] 'process raft request' (duration: 339.044337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:10:51.55683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:10:51.217424Z","time spent":"339.334663ms","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4727,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:10:51.557252Z","caller":"traceutil/trace.go:171","msg":"trace[2044257898] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:748; }","duration":"261.787932ms","start":"2026-02-20T10:10:51.295436Z","end":"2026-02-20T10:10:51.557224Z","steps":["trace[2044257898] 'read index received' (duration: 261.779312ms)","trace[2044257898] 'applied index is now lower than readState.Index' (duration: 6.69µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:10:51.55735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.899475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:10:51.557374Z","caller":"traceutil/trace.go:171","msg":"trace[1710890602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"261.969786ms","start":"2026-02-20T10:10:51.295398Z","end":"2026-02-20T10:10:51.557368Z","steps":["trace[1710890602] 'agreement among raft nodes before linearized reading' (duration: 261.898355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:08.239049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746276,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:08.527247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.650873ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T10:11:08.527353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.141047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:08.527399Z","caller":"traceutil/trace.go:171","msg":"trace[2036605928] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:770; }","duration":"920.815637ms","start":"2026-02-20T10:11:07.606561Z","end":"2026-02-20T10:11:08.527376Z","steps":["trace[2036605928] 'range keys from in-memory index tree' (duration: 920.616642ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:08.527446Z","caller":"traceutil/trace.go:171","msg":"trace[2107770931] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"789.396874ms","start":"2026-02-20T10:11:07.738032Z","end":"2026-02-20T10:11:08.527429Z","steps":["trace[2107770931] 'read index received' (duration: 665.149904ms)","trace[2107770931] 'applied index is now lower than readState.Index' (duration: 124.24556ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:08.527472Z","caller":"traceutil/trace.go:171","msg":"trace[973433373] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"1.030433514s","start":"2026-02-20T10:11:07.497004Z","end":"2026-02-20T10:11:08.527437Z","steps":["trace[973433373] 'process raft request' (duration: 906.126083ms)","trace[973433373] 'compare' (duration: 123.977294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:08.527596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:07.49698Z","time spent":"1.030550567s","remote":"127.0.0.1:57064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:11:08.527708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.349807ms","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-20T10:11:08.527708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.750982ms","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-20T10:11:08.527726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.682221ms","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-20T10:11:08.527764Z","caller":"traceutil/trace.go:171","msg":"trace[2086566395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"538.407028ms","start":"2026-02-20T10:11:07.98934Z","end":"2026-02-20T10:11:08.527747Z","steps":["trace[2086566395] 'agreement among raft nodes before linearized reading' (duration: 538.201054ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:08.527776Z","caller":"traceutil/trace.go:171","msg":"trace[963316530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"154.853634ms","start":"2026-02-20T10:11:08.372904Z","end":"2026-02-20T10:11:08.527758Z","steps":["trace[963316530] 'agreement among raft nodes before linearized reading' (duration: 154.681641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:08.527791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.509403ms","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-20T10:11:08.527803Z","caller":"traceutil/trace.go:171","msg":"trace[2079112057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"789.805154ms","start":"2026-02-20T10:11:07.737978Z","end":"2026-02-20T10:11:08.527783Z","steps":["trace[2079112057] 'agreement among raft nodes before linearized reading' (duration: 789.545888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:08.52781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:07.98928Z","time spent":"538.51866ms","remote":"127.0.0.1:57314","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-20T10:11:08.527846Z","caller":"traceutil/trace.go:171","msg":"trace[1852554706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"238.604704ms","start":"2026-02-20T10:11:08.289226Z","end":"2026-02-20T10:11:08.527831Z","steps":["trace[1852554706] 'agreement among raft nodes before linearized reading' (duration: 238.4293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:08.527849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:07.737958Z","time spent":"789.881845ms","remote":"127.0.0.1:57314","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-20T10:11:08.52785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.844174ms","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-20T10:11:08.527941Z","caller":"traceutil/trace.go:171","msg":"trace[930286762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"232.989707ms","start":"2026-02-20T10:11:08.294936Z","end":"2026-02-20T10:11:08.527926Z","steps":["trace[930286762] 'agreement among raft nodes before linearized reading' (duration: 232.68659ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:08.920353Z","caller":"traceutil/trace.go:171","msg":"trace[733557642] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"387.629386ms","start":"2026-02-20T10:11:08.5327Z","end":"2026-02-20T10:11:08.92033Z","steps":["trace[733557642] 'process raft request' (duration: 387.431241ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:08.920678Z","caller":"traceutil/trace.go:171","msg":"trace[572852433] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"387.37461ms","start":"2026-02-20T10:11:08.533281Z","end":"2026-02-20T10:11:08.920656Z","steps":["trace[572852433] 'process raft request' (duration: 387.188476ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:08.920778Z","caller":"traceutil/trace.go:171","msg":"trace[1043272650] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"386.44204ms","start":"2026-02-20T10:11:08.534276Z","end":"2026-02-20T10:11:08.920718Z","steps":["trace[1043272650] 'process raft request' (duration: 386.306017ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:08.920872Z","caller":"traceutil/trace.go:171","msg":"trace[1777878543] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"387.983474ms","start":"2026-02-20T10:11:08.532875Z","end":"2026-02-20T10:11:08.920858Z","steps":["trace[1777878543] 'process raft request' (duration: 387.405041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:08.920992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:08.532693Z","time spent":"387.834691ms","remote":"127.0.0.1:57314","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-20T10:11:08.921025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:08.53427Z","time spent":"386.633594ms","remote":"127.0.0.1:57314","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-20T10:11:08.92067Z","caller":"traceutil/trace.go:171","msg":"trace[1306434636] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:802; }","duration":"387.584835ms","start":"2026-02-20T10:11:08.533058Z","end":"2026-02-20T10:11:08.920643Z","steps":["trace[1306434636] 'read index received' (duration: 39.539117ms)","trace[1306434636] 'applied index is now lower than readState.Index' (duration: 348.044278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:08.920823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:08.53327Z","time spent":"387.501223ms","remote":"127.0.0.1:57314","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-20T10:11:08.921317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:08.532868Z","time spent":"388.120728ms","remote":"127.0.0.1:57314","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-20T10:11:08.920791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.706908ms","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-20T10:11:08.921624Z","caller":"traceutil/trace.go:171","msg":"trace[601236697] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:776; }","duration":"388.574058ms","start":"2026-02-20T10:11:08.533031Z","end":"2026-02-20T10:11:08.921605Z","steps":["trace[601236697] 'agreement among raft nodes before linearized reading' (duration: 387.691888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:08.92168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:08.533019Z","time spent":"388.644799ms","remote":"127.0.0.1:57318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T10:11:11.425663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:11.926458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:12.427291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746294,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:12.575793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.994834121s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T10:11:12.576179Z","caller":"traceutil/trace.go:171","msg":"trace[81336771] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"1.995340812s","start":"2026-02-20T10:11:10.580814Z","end":"2026-02-20T10:11:12.576155Z","steps":["trace[81336771] 'process raft request' (duration: 1.995199519s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:12.576302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:10.580792Z","time spent":"1.995438604s","remote":"127.0.0.1:57314","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-20T10:11:13.255304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.409303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:13.255529Z","caller":"traceutil/trace.go:171","msg":"trace[488458964] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"2.329913983s","start":"2026-02-20T10:11:10.925604Z","end":"2026-02-20T10:11:13.255518Z","steps":["trace[488458964] 'read index received' (duration: 1.650430094s)","trace[488458964] 'applied index is now lower than readState.Index' (duration: 679.482959ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:13.255581Z","caller":"traceutil/trace.go:171","msg":"trace[834731532] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"2.255569356s","start":"2026-02-20T10:11:10.999974Z","end":"2026-02-20T10:11:13.255543Z","steps":["trace[834731532] 'process raft request' (duration: 1.829852716s)","trace[834731532] 'compare' (duration: 425.093366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:13.255759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.330150229s","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-20T10:11:13.25576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:10.999951Z","time spent":"2.25571352s","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:11:13.25581Z","caller":"traceutil/trace.go:171","msg":"trace[1319840666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"2.330241361s","start":"2026-02-20T10:11:10.925561Z","end":"2026-02-20T10:11:13.255802Z","steps":["trace[1319840666] 'agreement among raft nodes before linearized reading' (duration: 2.330059817s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:13.255881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:10.925544Z","time spent":"2.330330352s","remote":"127.0.0.1:57314","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-20T10:11:13.537254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.858633ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T10:11:13.537389Z","caller":"traceutil/trace.go:171","msg":"trace[897447343] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"281.770574ms","start":"2026-02-20T10:11:13.255584Z","end":"2026-02-20T10:11:13.537354Z","steps":["trace[897447343] 'read index received' (duration: 4.790989ms)","trace[897447343] 'applied index is now lower than readState.Index' (duration: 276.978125ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:13.537711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.610619234s","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-20T10:11:13.53771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.611543125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T10:11:13.537724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216748162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T10:11:13.537816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.300076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T10:11:13.537843Z","caller":"traceutil/trace.go:171","msg":"trace[175395903] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:779; }","duration":"430.367287ms","start":"2026-02-20T10:11:13.107468Z","end":"2026-02-20T10:11:13.537835Z","steps":["trace[175395903] 'agreement among raft nodes before linearized reading' (duration: 430.323576ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:13.537754Z","caller":"traceutil/trace.go:171","msg":"trace[791011988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"2.610687885s","start":"2026-02-20T10:11:10.927056Z","end":"2026-02-20T10:11:13.537744Z","steps":["trace[791011988] 'agreement among raft nodes before linearized reading' (duration: 2.610529692s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:13.537887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:13.107448Z","time spent":"430.429688ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:13.537874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241975085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:13.537981Z","caller":"traceutil/trace.go:171","msg":"trace[957060414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"1.242122398s","start":"2026-02-20T10:11:12.295837Z","end":"2026-02-20T10:11:13.53796Z","steps":["trace[957060414] 'agreement among raft nodes before linearized reading' (duration: 1.241918144s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:13.53803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:12.295811Z","time spent":"1.24220682s","remote":"127.0.0.1:57028","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-20T10:11:13.538088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.612008825s","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-20T10:11:13.537836Z","caller":"traceutil/trace.go:171","msg":"trace[1640252361] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:779; }","duration":"1.216948877s","start":"2026-02-20T10:11:12.320866Z","end":"2026-02-20T10:11:13.537815Z","steps":["trace[1640252361] 'agreement among raft nodes before linearized reading' (duration: 1.216701771s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:13.538162Z","caller":"traceutil/trace.go:171","msg":"trace[285424420] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"278.370188ms","start":"2026-02-20T10:11:13.259777Z","end":"2026-02-20T10:11:13.538147Z","steps":["trace[285424420] 'process raft request' (duration: 277.832695ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:13.537765Z","caller":"traceutil/trace.go:171","msg":"trace[701502758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"2.611615186s","start":"2026-02-20T10:11:10.926135Z","end":"2026-02-20T10:11:13.53775Z","steps":["trace[701502758] 'agreement among raft nodes before linearized reading' (duration: 2.611477583s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:13.538318Z","caller":"traceutil/trace.go:171","msg":"trace[1966645524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"2.61217889s","start":"2026-02-20T10:11:10.926023Z","end":"2026-02-20T10:11:13.538202Z","steps":["trace[1966645524] 'agreement among raft nodes before linearized reading' (duration: 2.61173898s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:13.538404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:10.926015Z","time spent":"2.612374334s","remote":"127.0.0.1:57314","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-20T10:11:13.538381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:10.926129Z","time spent":"2.61221462s","remote":"127.0.0.1:57314","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-20T10:11:13.537922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:10.927047Z","time spent":"2.610860489s","remote":"127.0.0.1:57314","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-20T10:11:13.538257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:12.320819Z","time spent":"1.217419788s","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:13.820822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.263661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T10:11:13.820909Z","caller":"traceutil/trace.go:171","msg":"trace[59161932] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:780; }","duration":"384.420004ms","start":"2026-02-20T10:11:13.436467Z","end":"2026-02-20T10:11:13.820887Z","steps":["trace[59161932] 'agreement among raft nodes before linearized reading' (duration: 101.78465ms)","trace[59161932] 'count revisions from in-memory index tree' (duration: 282.494671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:13.820956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:13.436443Z","time spent":"384.502646ms","remote":"127.0.0.1:57394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:13.82109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.167944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-02-20T10:11:13.821051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.497388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:13.821182Z","caller":"traceutil/trace.go:171","msg":"trace[1812269866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"281.702423ms","start":"2026-02-20T10:11:13.539456Z","end":"2026-02-20T10:11:13.821158Z","steps":["trace[1812269866] 'range keys from in-memory index tree' (duration: 281.402476ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:13.821268Z","caller":"traceutil/trace.go:171","msg":"trace[736310518] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"278.992292ms","start":"2026-02-20T10:11:13.542244Z","end":"2026-02-20T10:11:13.821236Z","steps":["trace[736310518] 'process raft request' (duration: 33.614133ms)","trace[736310518] 'compare' (duration: 245.065822ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:13.82128Z","caller":"traceutil/trace.go:171","msg":"trace[1215169608] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"277.324493ms","start":"2026-02-20T10:11:13.543937Z","end":"2026-02-20T10:11:13.821262Z","steps":["trace[1215169608] 'process raft request' (duration: 277.285703ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:13.821372Z","caller":"traceutil/trace.go:171","msg":"trace[1211217503] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"279.029233ms","start":"2026-02-20T10:11:13.542327Z","end":"2026-02-20T10:11:13.821357Z","steps":["trace[1211217503] 'process raft request' (duration: 278.849738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:23.852373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.519798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:23.852478Z","caller":"traceutil/trace.go:171","msg":"trace[1981483936] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"262.334242ms","start":"2026-02-20T10:11:23.590127Z","end":"2026-02-20T10:11:23.852461Z","steps":["trace[1981483936] 'process raft request' (duration: 125.65581ms)","trace[1981483936] 'compare' (duration: 136.397506ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:24.288987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.699139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:24.289169Z","caller":"traceutil/trace.go:171","msg":"trace[412441088] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"354.794911ms","start":"2026-02-20T10:11:23.934359Z","end":"2026-02-20T10:11:24.289154Z","steps":["trace[412441088] 'read index received' (duration: 25.817145ms)","trace[412441088] 'applied index is now lower than readState.Index' (duration: 328.976686ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:24.289181Z","caller":"traceutil/trace.go:171","msg":"trace[1356020682] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"357.664316ms","start":"2026-02-20T10:11:23.931493Z","end":"2026-02-20T10:11:24.289157Z","steps":["trace[1356020682] 'process raft request' (duration: 28.713471ms)","trace[1356020682] 'compare' (duration: 328.575537ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:24.289267Z","caller":"traceutil/trace.go:171","msg":"trace[963547364] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"357.534573ms","start":"2026-02-20T10:11:23.931724Z","end":"2026-02-20T10:11:24.289258Z","steps":["trace[963547364] 'process raft request' (duration: 357.37234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:24.289281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:23.931474Z","time spent":"357.758109ms","remote":"127.0.0.1:57314","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-20T10:11:24.28933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:23.931708Z","time spent":"357.585235ms","remote":"127.0.0.1:57314","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-20T10:11:24.289345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.616975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:24.289619Z","caller":"traceutil/trace.go:171","msg":"trace[1309498249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"286.740928ms","start":"2026-02-20T10:11:24.002661Z","end":"2026-02-20T10:11:24.289401Z","steps":["trace[1309498249] 'agreement among raft nodes before linearized reading' (duration: 286.611835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:24.289635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.272783ms","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-20T10:11:24.289693Z","caller":"traceutil/trace.go:171","msg":"trace[1861825991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:862; }","duration":"355.346524ms","start":"2026-02-20T10:11:23.934335Z","end":"2026-02-20T10:11:24.289682Z","steps":["trace[1861825991] 'agreement among raft nodes before linearized reading' (duration: 355.229001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:24.289726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:23.934325Z","time spent":"355.393645ms","remote":"127.0.0.1:57314","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-20T10:11:24.289752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.009597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:24.289807Z","caller":"traceutil/trace.go:171","msg":"trace[1775802826] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:862; }","duration":"255.095ms","start":"2026-02-20T10:11:24.034698Z","end":"2026-02-20T10:11:24.289793Z","steps":["trace[1775802826] 'agreement among raft nodes before linearized reading' (duration: 255.011058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:24.795682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746474,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T10:11:25.113962Z","caller":"traceutil/trace.go:171","msg":"trace[941740139] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"818.905914ms","start":"2026-02-20T10:11:24.295034Z","end":"2026-02-20T10:11:25.11394Z","steps":["trace[941740139] 'read index received' (duration: 818.778111ms)","trace[941740139] 'applied index is now lower than readState.Index' (duration: 126.973µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:25.114086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.039896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:25.11406Z","caller":"traceutil/trace.go:171","msg":"trace[660114239] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"819.943578ms","start":"2026-02-20T10:11:24.294088Z","end":"2026-02-20T10:11:25.114031Z","steps":["trace[660114239] 'process raft request' (duration: 819.733543ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:25.114112Z","caller":"traceutil/trace.go:171","msg":"trace[1524234321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"819.099078ms","start":"2026-02-20T10:11:24.295005Z","end":"2026-02-20T10:11:25.114104Z","steps":["trace[1524234321] 'agreement among raft nodes before linearized reading' (duration: 819.043737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:25.114136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:24.294991Z","time spent":"819.140219ms","remote":"127.0.0.1:57034","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-20T10:11:25.114199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:24.294064Z","time spent":"820.0626ms","remote":"127.0.0.1:57314","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-20T10:11:25.114239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.986041ms","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-20T10:11:25.114286Z","caller":"traceutil/trace.go:171","msg":"trace[1290852334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:863; }","duration":"487.079603ms","start":"2026-02-20T10:11:24.627194Z","end":"2026-02-20T10:11:25.114273Z","steps":["trace[1290852334] 'agreement among raft nodes before linearized reading' (duration: 486.94585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:25.114312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:24.627169Z","time spent":"487.138275ms","remote":"127.0.0.1:57314","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-20T10:11:26.863666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:27.363863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:27.864962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:27.945307Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.64543617s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T10:11:27.945729Z","caller":"traceutil/trace.go:171","msg":"trace[699950982] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"1.645922461s","start":"2026-02-20T10:11:26.29979Z","end":"2026-02-20T10:11:27.945712Z","steps":["trace[699950982] 'process raft request' (duration: 1.645786818s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:27.945844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:26.299775Z","time spent":"1.646004183s","remote":"127.0.0.1:57314","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-20T10:11:28.744827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.860989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:28.74489Z","caller":"traceutil/trace.go:171","msg":"trace[147376534] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:901; }","duration":"2.381866132s","start":"2026-02-20T10:11:26.363013Z","end":"2026-02-20T10:11:28.744879Z","steps":["trace[147376534] 'read index received' (duration: 1.582526262s)","trace[147376534] 'applied index is now lower than readState.Index' (duration: 799.33841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:28.744997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381985395s","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":"info","ts":"2026-02-20T10:11:28.744971Z","caller":"traceutil/trace.go:171","msg":"trace[531622153] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"2.444354961s","start":"2026-02-20T10:11:26.300559Z","end":"2026-02-20T10:11:28.744914Z","steps":["trace[531622153] 'process raft request' (duration: 2.21735434s)","trace[531622153] 'compare' (duration: 226.765136ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:28.745017Z","caller":"traceutil/trace.go:171","msg":"trace[725017432] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:867; }","duration":"2.382038026s","start":"2026-02-20T10:11:26.362972Z","end":"2026-02-20T10:11:28.74501Z","steps":["trace[725017432] 'agreement among raft nodes before linearized reading' (duration: 2.381991815s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:28.745036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:26.362926Z","time spent":"2.382104148s","remote":"127.0.0.1:57540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:28.745092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:26.300547Z","time spent":"2.444480224s","remote":"127.0.0.1:57314","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-20T10:11:28.745113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626750686s","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-20T10:11:28.745135Z","caller":"traceutil/trace.go:171","msg":"trace[373763943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:867; }","duration":"1.626814868s","start":"2026-02-20T10:11:27.118314Z","end":"2026-02-20T10:11:28.745128Z","steps":["trace[373763943] 'agreement among raft nodes before linearized reading' (duration: 1.626729916s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:28.745149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:27.118288Z","time spent":"1.626857408s","remote":"127.0.0.1:57314","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-20T10:11:28.745234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371130056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:28.745251Z","caller":"traceutil/trace.go:171","msg":"trace[260156309] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:867; }","duration":"1.371172437s","start":"2026-02-20T10:11:27.374073Z","end":"2026-02-20T10:11:28.745245Z","steps":["trace[260156309] 'agreement among raft nodes before linearized reading' (duration: 1.371147557s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:28.745266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:27.374058Z","time spent":"1.371204338s","remote":"127.0.0.1:57184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:28.745281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.707361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T10:11:28.74529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.62166018s","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-20T10:11:28.745291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044291829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:28.745307Z","caller":"traceutil/trace.go:171","msg":"trace[2029180868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:867; }","duration":"1.621707442s","start":"2026-02-20T10:11:27.123595Z","end":"2026-02-20T10:11:28.745302Z","steps":["trace[2029180868] 'agreement among raft nodes before linearized reading' (duration: 1.621657201s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:28.745309Z","caller":"traceutil/trace.go:171","msg":"trace[1740518077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"451.761542ms","start":"2026-02-20T10:11:28.293538Z","end":"2026-02-20T10:11:28.7453Z","steps":["trace[1740518077] 'agreement among raft nodes before linearized reading' (duration: 451.716221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:28.745322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:27.123581Z","time spent":"1.621737592s","remote":"127.0.0.1:57314","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-20T10:11:28.745307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102607453s","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-20T10:11:28.745328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:28.293504Z","time spent":"451.820983ms","remote":"127.0.0.1:57028","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-20T10:11:28.745342Z","caller":"traceutil/trace.go:171","msg":"trace[2015167768] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:867; }","duration":"1.04434928s","start":"2026-02-20T10:11:27.700966Z","end":"2026-02-20T10:11:28.745315Z","steps":["trace[2015167768] 'agreement among raft nodes before linearized reading' (duration: 1.044292319s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:28.745351Z","caller":"traceutil/trace.go:171","msg":"trace[1396557049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:867; }","duration":"1.102677394s","start":"2026-02-20T10:11:27.64266Z","end":"2026-02-20T10:11:28.745337Z","steps":["trace[1396557049] 'agreement among raft nodes before linearized reading' (duration: 1.102553021s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:28.745349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.499334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T10:11:28.745379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:27.700951Z","time spent":"1.044416021s","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:28.745383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:27.642645Z","time spent":"1.102731415s","remote":"127.0.0.1:57314","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-20T10:11:28.745405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391481278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T10:11:28.745421Z","caller":"traceutil/trace.go:171","msg":"trace[1274514165] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:867; }","duration":"1.391520008s","start":"2026-02-20T10:11:27.353896Z","end":"2026-02-20T10:11:28.745416Z","steps":["trace[1274514165] 'agreement among raft nodes before linearized reading' (duration: 1.391477647s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:28.745433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:27.35388Z","time spent":"1.39155022s","remote":"127.0.0.1:57064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"info","ts":"2026-02-20T10:11:28.745422Z","caller":"traceutil/trace.go:171","msg":"trace[1498958174] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:867; }","duration":"235.623107ms","start":"2026-02-20T10:11:28.50978Z","end":"2026-02-20T10:11:28.745403Z","steps":["trace[1498958174] 'agreement among raft nodes before linearized reading' (duration: 235.494084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:29.195749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:28.746146Z","time spent":"449.599103ms","remote":"127.0.0.1:57064","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T10:11:29.198144Z","caller":"traceutil/trace.go:171","msg":"trace[654165064] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"449.46066ms","start":"2026-02-20T10:11:28.748667Z","end":"2026-02-20T10:11:29.198127Z","steps":["trace[654165064] 'process raft request' (duration: 449.395428ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:29.198232Z","caller":"traceutil/trace.go:171","msg":"trace[899530308] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"449.533541ms","start":"2026-02-20T10:11:28.748663Z","end":"2026-02-20T10:11:29.198197Z","steps":["trace[899530308] 'process raft request' (duration: 449.278815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:29.198261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:28.748656Z","time spent":"449.538301ms","remote":"127.0.0.1:57314","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-20T10:11:29.198331Z","caller":"traceutil/trace.go:171","msg":"trace[204575414] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"449.625433ms","start":"2026-02-20T10:11:28.748666Z","end":"2026-02-20T10:11:29.198291Z","steps":["trace[204575414] 'process raft request' (duration: 449.430649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:29.198389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:28.74865Z","time spent":"449.654424ms","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:11:29.198523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:28.748652Z","time spent":"449.772386ms","remote":"127.0.0.1:57314","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-20T10:11:41.419245Z","caller":"traceutil/trace.go:171","msg":"trace[1874106538] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"136.226981ms","start":"2026-02-20T10:11:41.282999Z","end":"2026-02-20T10:11:41.419226Z","steps":["trace[1874106538] 'read index received' (duration: 136.041257ms)","trace[1874106538] 'applied index is now lower than readState.Index' (duration: 185.004µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:41.419283Z","caller":"traceutil/trace.go:171","msg":"trace[1071770600] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"173.249421ms","start":"2026-02-20T10:11:41.246009Z","end":"2026-02-20T10:11:41.419259Z","steps":["trace[1071770600] 'process raft request' (duration: 173.102928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:41.419419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.411566ms","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-20T10:11:41.419438Z","caller":"traceutil/trace.go:171","msg":"trace[986404139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:903; }","duration":"136.530308ms","start":"2026-02-20T10:11:41.282902Z","end":"2026-02-20T10:11:41.419432Z","steps":["trace[986404139] 'agreement among raft nodes before linearized reading' (duration: 136.420586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:41.419477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.615125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T10:11:41.419546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.379962ms","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-20T10:11:41.419556Z","caller":"traceutil/trace.go:171","msg":"trace[1130074873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"123.732077ms","start":"2026-02-20T10:11:41.295801Z","end":"2026-02-20T10:11:41.419533Z","steps":["trace[1130074873] 'agreement among raft nodes before linearized reading' (duration: 123.594564ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:41.419583Z","caller":"traceutil/trace.go:171","msg":"trace[969385722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"109.466804ms","start":"2026-02-20T10:11:41.310106Z","end":"2026-02-20T10:11:41.419573Z","steps":["trace[969385722] 'agreement among raft nodes before linearized reading' (duration: 109.325671ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:41.609631Z","caller":"traceutil/trace.go:171","msg":"trace[1747110774] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"186.683135ms","start":"2026-02-20T10:11:41.422926Z","end":"2026-02-20T10:11:41.609609Z","steps":["trace[1747110774] 'process raft request' (duration: 86.789889ms)","trace[1747110774] 'compare' (duration: 99.588379ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:41.685815Z","caller":"traceutil/trace.go:171","msg":"trace[434631101] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"261.943373ms","start":"2026-02-20T10:11:41.423848Z","end":"2026-02-20T10:11:41.685791Z","steps":["trace[434631101] 'process raft request' (duration: 261.727158ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:43.621522Z","caller":"traceutil/trace.go:171","msg":"trace[1462106656] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"193.177673ms","start":"2026-02-20T10:11:43.428311Z","end":"2026-02-20T10:11:43.621489Z","steps":["trace[1462106656] 'process raft request' (duration: 192.926538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:43.821002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.334786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:43.821083Z","caller":"traceutil/trace.go:171","msg":"trace[1339732039] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"132.226331ms","start":"2026-02-20T10:11:43.688839Z","end":"2026-02-20T10:11:43.821065Z","steps":["trace[1339732039] 'read index received' (duration: 45.521µs)","trace[1339732039] 'applied index is now lower than readState.Index' (duration: 132.179879ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:43.821117Z","caller":"traceutil/trace.go:171","msg":"trace[1503122384] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"195.758772ms","start":"2026-02-20T10:11:43.625338Z","end":"2026-02-20T10:11:43.821096Z","steps":["trace[1503122384] 'process raft request' (duration: 58.088068ms)","trace[1503122384] 'compare' (duration: 137.240064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:43.821234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.393705ms","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-20T10:11:43.821261Z","caller":"traceutil/trace.go:171","msg":"trace[649897793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:909; }","duration":"132.456826ms","start":"2026-02-20T10:11:43.688797Z","end":"2026-02-20T10:11:43.821254Z","steps":["trace[649897793] 'agreement among raft nodes before linearized reading' (duration: 132.305952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:44.136089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.134766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:44.136195Z","caller":"traceutil/trace.go:171","msg":"trace[101207583] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:948; }","duration":"139.755091ms","start":"2026-02-20T10:11:43.996399Z","end":"2026-02-20T10:11:44.136154Z","steps":["trace[101207583] 'read index received' (duration: 26.12µs)","trace[101207583] 'applied index is now lower than readState.Index' (duration: 139.727911ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:44.136389Z","caller":"traceutil/trace.go:171","msg":"trace[1514395675] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"311.949749ms","start":"2026-02-20T10:11:43.824426Z","end":"2026-02-20T10:11:44.136375Z","steps":["trace[1514395675] 'process raft request' (duration: 138.947343ms)","trace[1514395675] 'compare' (duration: 172.002373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:44.136463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:43.82441Z","time spent":"312.025301ms","remote":"127.0.0.1:57314","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-20T10:11:44.13687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.473838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:44.136901Z","caller":"traceutil/trace.go:171","msg":"trace[918723371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"140.526129ms","start":"2026-02-20T10:11:43.996366Z","end":"2026-02-20T10:11:44.136892Z","steps":["trace[918723371] 'agreement among raft nodes before linearized reading' (duration: 140.473698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:44.136923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.329426ms","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-20T10:11:44.136997Z","caller":"traceutil/trace.go:171","msg":"trace[644561953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"133.409427ms","start":"2026-02-20T10:11:44.003556Z","end":"2026-02-20T10:11:44.136966Z","steps":["trace[644561953] 'agreement among raft nodes before linearized reading' (duration: 132.963657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:45.138218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.944727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:45.138321Z","caller":"traceutil/trace.go:171","msg":"trace[466425888] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"994.333555ms","start":"2026-02-20T10:11:44.14397Z","end":"2026-02-20T10:11:45.138303Z","steps":["trace[466425888] 'process raft request' (duration: 241.205814ms)","trace[466425888] 'compare' (duration: 752.701252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:45.138665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:44.14395Z","time spent":"994.675083ms","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:11:46.296198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000079009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T10:11:46.296284Z","caller":"traceutil/trace.go:171","msg":"trace[258607254] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000209961s","start":"2026-02-20T10:11:44.296058Z","end":"2026-02-20T10:11:46.296268Z","steps":["trace[258607254] 'agreement among raft nodes before linearized reading' (duration: 2.000101679s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:46.296321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:44.296042Z","time spent":"2.000270733s","remote":"127.0.0.1:57034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T10:11:47.157397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645991774s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T10:11:47.157644Z","caller":"traceutil/trace.go:171","msg":"trace[250291462] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"2.861525868s","start":"2026-02-20T10:11:44.29609Z","end":"2026-02-20T10:11:47.157616Z","steps":["trace[250291462] 'read index received' (duration: 89.098871ms)","trace[250291462] 'applied index is now lower than readState.Index' (duration: 2.772422707s)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:47.15782Z","caller":"traceutil/trace.go:171","msg":"trace[8289515] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"2.732869439s","start":"2026-02-20T10:11:44.424928Z","end":"2026-02-20T10:11:47.157797Z","steps":["trace[8289515] 'process raft request' (duration: 2.732577792s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.157966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:44.424905Z","time spent":"2.732980781s","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T10:11:47.158064Z","caller":"traceutil/trace.go:171","msg":"trace[247170023] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"858.053302ms","start":"2026-02-20T10:11:46.299986Z","end":"2026-02-20T10:11:47.158039Z","steps":["trace[247170023] 'process raft request' (duration: 857.737085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.158154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:46.299956Z","time spent":"858.157065ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T10:11:47.158162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015527387s","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-20T10:11:47.158126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.104706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:47.158209Z","caller":"traceutil/trace.go:171","msg":"trace[898171098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:913; }","duration":"1.015643069s","start":"2026-02-20T10:11:46.14255Z","end":"2026-02-20T10:11:47.158193Z","steps":["trace[898171098] 'agreement among raft nodes before linearized reading' (duration: 1.015439904s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:47.15823Z","caller":"traceutil/trace.go:171","msg":"trace[1969859003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"855.243049ms","start":"2026-02-20T10:11:46.302967Z","end":"2026-02-20T10:11:47.15821Z","steps":["trace[1969859003] 'agreement among raft nodes before linearized reading' (duration: 855.052375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.158244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:46.142532Z","time spent":"1.01570493s","remote":"127.0.0.1:57314","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-20T10:11:47.15827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:46.30293Z","time spent":"855.33209ms","remote":"127.0.0.1:57028","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-20T10:11:47.158281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.291159065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T10:11:47.158374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955147671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T10:11:47.15839Z","caller":"traceutil/trace.go:171","msg":"trace[1808264180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"2.291323419s","start":"2026-02-20T10:11:44.86704Z","end":"2026-02-20T10:11:47.158364Z","steps":["trace[1808264180] 'agreement among raft nodes before linearized reading' (duration: 2.29092939s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.158379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533336687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T10:11:47.15842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333752848s","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-20T10:11:47.158439Z","caller":"traceutil/trace.go:171","msg":"trace[1178301566] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:913; }","duration":"1.955250132s","start":"2026-02-20T10:11:45.20317Z","end":"2026-02-20T10:11:47.15842Z","steps":["trace[1178301566] 'agreement among raft nodes before linearized reading' (duration: 1.95515985s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.158453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:44.867018Z","time spent":"2.291424371s","remote":"127.0.0.1:57314","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-20T10:11:47.158467Z","caller":"traceutil/trace.go:171","msg":"trace[815574516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"1.3338321s","start":"2026-02-20T10:11:45.82462Z","end":"2026-02-20T10:11:47.158452Z","steps":["trace[815574516] 'agreement among raft nodes before linearized reading' (duration: 1.333698987s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:47.158466Z","caller":"traceutil/trace.go:171","msg":"trace[2000576588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:913; }","duration":"1.533465881s","start":"2026-02-20T10:11:45.624978Z","end":"2026-02-20T10:11:47.158444Z","steps":["trace[2000576588] 'agreement among raft nodes before linearized reading' (duration: 1.533155154s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.158485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:45.203154Z","time spent":"1.955317234s","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:47.158507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:45.824601Z","time spent":"1.333895922s","remote":"127.0.0.1:57314","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-20T10:11:47.15852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:45.624963Z","time spent":"1.533548072s","remote":"127.0.0.1:57314","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-20T10:11:47.355167Z","caller":"traceutil/trace.go:171","msg":"trace[2072077165] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"191.964917ms","start":"2026-02-20T10:11:47.163163Z","end":"2026-02-20T10:11:47.355128Z","steps":["trace[2072077165] 'process raft request' (duration: 191.626289ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:47.355406Z","caller":"traceutil/trace.go:171","msg":"trace[1458497244] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"192.141761ms","start":"2026-02-20T10:11:47.163243Z","end":"2026-02-20T10:11:47.355385Z","steps":["trace[1458497244] 'process raft request' (duration: 191.825464ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:47.355626Z","caller":"traceutil/trace.go:171","msg":"trace[1565628188] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"192.179161ms","start":"2026-02-20T10:11:47.16344Z","end":"2026-02-20T10:11:47.355619Z","steps":["trace[1565628188] 'process raft request' (duration: 191.838793ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:47.355789Z","caller":"traceutil/trace.go:171","msg":"trace[1009362968] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"192.091079ms","start":"2026-02-20T10:11:47.163605Z","end":"2026-02-20T10:11:47.355696Z","steps":["trace[1009362968] 'process raft request' (duration: 191.918075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.620024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.478167ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-20T10:11:47.620136Z","caller":"traceutil/trace.go:171","msg":"trace[462770258] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"228.975527ms","start":"2026-02-20T10:11:47.391143Z","end":"2026-02-20T10:11:47.620119Z","steps":["trace[462770258] 'read index received' (duration: 55.312696ms)","trace[462770258] 'applied index is now lower than readState.Index' (duration: 173.661571ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:47.620218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.073338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:47.62025Z","caller":"traceutil/trace.go:171","msg":"trace[536545577] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:918; }","duration":"229.15971ms","start":"2026-02-20T10:11:47.391078Z","end":"2026-02-20T10:11:47.620238Z","steps":["trace[536545577] 'agreement among raft nodes before linearized reading' (duration: 229.086339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.974469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.990943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:47.974643Z","caller":"traceutil/trace.go:171","msg":"trace[1960964865] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"353.696347ms","start":"2026-02-20T10:11:47.620926Z","end":"2026-02-20T10:11:47.974622Z","steps":["trace[1960964865] 'process raft request' (duration: 118.463958ms)","trace[1960964865] 'compare' (duration: 234.811729ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:47.974662Z","caller":"traceutil/trace.go:171","msg":"trace[2134835447] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"174.223124ms","start":"2026-02-20T10:11:47.800421Z","end":"2026-02-20T10:11:47.974644Z","steps":["trace[2134835447] 'process raft request' (duration: 174.154823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:47.974749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:47.620906Z","time spent":"353.784239ms","remote":"127.0.0.1:57064","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-20T10:11:49.463702Z","caller":"traceutil/trace.go:171","msg":"trace[2047235147] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"104.337757ms","start":"2026-02-20T10:11:49.359342Z","end":"2026-02-20T10:11:49.46368Z","steps":["trace[2047235147] 'read index received' (duration: 104.156093ms)","trace[2047235147] 'applied index is now lower than readState.Index' (duration: 180.684µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:49.463812Z","caller":"traceutil/trace.go:171","msg":"trace[426434657] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"238.38848ms","start":"2026-02-20T10:11:49.225391Z","end":"2026-02-20T10:11:49.46378Z","steps":["trace[426434657] 'process raft request' (duration: 238.169845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:49.463912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.552853ms","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-20T10:11:49.463946Z","caller":"traceutil/trace.go:171","msg":"trace[261335383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"104.636684ms","start":"2026-02-20T10:11:49.359299Z","end":"2026-02-20T10:11:49.463936Z","steps":["trace[261335383] 'agreement among raft nodes before linearized reading' (duration: 104.498801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:49.46411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.153941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-20T10:11:49.464125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.794796ms","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-20T10:11:49.464183Z","caller":"traceutil/trace.go:171","msg":"trace[1985498877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"103.179592ms","start":"2026-02-20T10:11:49.360948Z","end":"2026-02-20T10:11:49.464128Z","steps":["trace[1985498877] 'agreement among raft nodes before linearized reading' (duration: 103.10651ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:49.464187Z","caller":"traceutil/trace.go:171","msg":"trace[370789782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"103.896167ms","start":"2026-02-20T10:11:49.360267Z","end":"2026-02-20T10:11:49.464163Z","steps":["trace[370789782] 'agreement among raft nodes before linearized reading' (duration: 103.729534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:49.464382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.012551ms","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-20T10:11:49.464429Z","caller":"traceutil/trace.go:171","msg":"trace[1322824123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"104.105702ms","start":"2026-02-20T10:11:49.360309Z","end":"2026-02-20T10:11:49.464414Z","steps":["trace[1322824123] 'agreement among raft nodes before linearized reading' (duration: 103.946438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:50.421862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.984897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:50.421971Z","caller":"traceutil/trace.go:171","msg":"trace[300773499] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"953.570091ms","start":"2026-02-20T10:11:49.468383Z","end":"2026-02-20T10:11:50.421953Z","steps":["trace[300773499] 'process raft request' (duration: 609.41442ms)","trace[300773499] 'compare' (duration: 343.815633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:50.422027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:49.468357Z","time spent":"953.642082ms","remote":"127.0.0.1:57314","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-20T10:11:51.396195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.473945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:51.396435Z","caller":"traceutil/trace.go:171","msg":"trace[2011565961] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:962; }","duration":"1.472452446s","start":"2026-02-20T10:11:49.923965Z","end":"2026-02-20T10:11:51.396418Z","steps":["trace[2011565961] 'read index received' (duration: 153.933203ms)","trace[2011565961] 'applied index is now lower than readState.Index' (duration: 1.318518193s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:51.39667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472687111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T10:11:51.396736Z","caller":"traceutil/trace.go:171","msg":"trace[1020453650] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:925; }","duration":"1.472808004s","start":"2026-02-20T10:11:49.923913Z","end":"2026-02-20T10:11:51.396721Z","steps":["trace[1020453650] 'agreement among raft nodes before linearized reading' (duration: 1.472676251s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:51.396799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:49.923893Z","time spent":"1.472894097s","remote":"127.0.0.1:57614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-20T10:11:51.39681Z","caller":"traceutil/trace.go:171","msg":"trace[710868476] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.928371643s","start":"2026-02-20T10:11:49.468423Z","end":"2026-02-20T10:11:51.396795Z","steps":["trace[710868476] 'process raft request' (duration: 1.580222622s)","trace[710868476] 'compare' (duration: 346.922893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:51.396898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:49.468405Z","time spent":"1.928433354s","remote":"127.0.0.1:57314","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-20T10:11:51.397094Z","caller":"traceutil/trace.go:171","msg":"trace[580553438] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.928234879s","start":"2026-02-20T10:11:49.468841Z","end":"2026-02-20T10:11:51.397076Z","steps":["trace[580553438] 'process raft request' (duration: 1.927438031s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:51.396625Z","caller":"traceutil/trace.go:171","msg":"trace[472200782] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"1.927240817s","start":"2026-02-20T10:11:49.469358Z","end":"2026-02-20T10:11:51.396599Z","steps":["trace[472200782] 'process raft request' (duration: 1.927023902s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:51.397185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:49.468832Z","time spent":"1.928310492s","remote":"127.0.0.1:57314","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-20T10:11:51.397209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:49.469348Z","time spent":"1.92782856s","remote":"127.0.0.1:57314","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-20T10:11:51.41294Z","caller":"traceutil/trace.go:171","msg":"trace[1149143579] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.170539145s","start":"2026-02-20T10:11:50.242372Z","end":"2026-02-20T10:11:51.412911Z","steps":["trace[1149143579] 'process raft request' (duration: 1.170385951s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:51.412987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117721076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:51.413044Z","caller":"traceutil/trace.go:171","msg":"trace[1801184104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"1.117809937s","start":"2026-02-20T10:11:50.295218Z","end":"2026-02-20T10:11:51.413027Z","steps":["trace[1801184104] 'agreement among raft nodes before linearized reading' (duration: 1.117678644s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:51.413083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:50.29521Z","time spent":"1.117862599s","remote":"127.0.0.1:57028","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-20T10:11:51.413117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:50.242353Z","time spent":"1.170673167s","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:11:51.701077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.991253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:51.701504Z","caller":"traceutil/trace.go:171","msg":"trace[1907552831] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"230.064201ms","start":"2026-02-20T10:11:51.471419Z","end":"2026-02-20T10:11:51.701483Z","steps":["trace[1907552831] 'process raft request' (duration: 109.583867ms)","trace[1907552831] 'compare' (duration: 119.711267ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:52.976637Z","caller":"traceutil/trace.go:171","msg":"trace[1681422073] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"544.581909ms","start":"2026-02-20T10:11:52.432031Z","end":"2026-02-20T10:11:52.976613Z","steps":["trace[1681422073] 'process raft request' (duration: 544.419695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:52.976782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:52.432008Z","time spent":"544.698221ms","remote":"127.0.0.1:57314","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-20T10:11:53.796457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:54.297258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565200538746651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T10:11:54.651028Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381949172s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T10:11:54.746099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485902621s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T10:11:54.746479Z","caller":"traceutil/trace.go:171","msg":"trace[491775571] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:969; }","duration":"1.45106719s","start":"2026-02-20T10:11:53.295392Z","end":"2026-02-20T10:11:54.746459Z","steps":["trace[491775571] 'read index received' (duration: 1.355745077s)","trace[491775571] 'applied index is now lower than readState.Index' (duration: 95.321253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:54.746589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.451191733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:54.746617Z","caller":"traceutil/trace.go:171","msg":"trace[258783289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"1.451267474s","start":"2026-02-20T10:11:53.295344Z","end":"2026-02-20T10:11:54.746612Z","steps":["trace[258783289] 'agreement among raft nodes before linearized reading' (duration: 1.451206853s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:54.746583Z","caller":"traceutil/trace.go:171","msg":"trace[974907381] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.576016766s","start":"2026-02-20T10:11:53.170491Z","end":"2026-02-20T10:11:54.746508Z","steps":["trace[974907381] 'process raft request' (duration: 1.575829232s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:54.746732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346063517s","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-20T10:11:54.746751Z","caller":"traceutil/trace.go:171","msg":"trace[154191650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:929; }","duration":"1.346120659s","start":"2026-02-20T10:11:53.400626Z","end":"2026-02-20T10:11:54.746747Z","steps":["trace[154191650] 'agreement among raft nodes before linearized reading' (duration: 1.346022966s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:54.746763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:53.400606Z","time spent":"1.346154599s","remote":"127.0.0.1:57314","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-20T10:11:54.746882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.348474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T10:11:54.7469Z","caller":"traceutil/trace.go:171","msg":"trace[782233273] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:929; }","duration":"730.368965ms","start":"2026-02-20T10:11:54.016527Z","end":"2026-02-20T10:11:54.746896Z","steps":["trace[782233273] 'agreement among raft nodes before linearized reading' (duration: 730.339114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:54.746913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:54.016485Z","time spent":"730.424176ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:11:54.746762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:53.170469Z","time spent":"1.57620724s","remote":"127.0.0.1:57206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:11:54.747034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34617613s","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-20T10:11:54.747048Z","caller":"traceutil/trace.go:171","msg":"trace[548740974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.34621192s","start":"2026-02-20T10:11:53.400832Z","end":"2026-02-20T10:11:54.747044Z","steps":["trace[548740974] 'agreement among raft nodes before linearized reading' (duration: 1.346169919s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:54.747066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:53.400821Z","time spent":"1.346235962s","remote":"127.0.0.1:57314","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-20T10:11:54.747111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345797481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T10:11:54.746634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:53.295325Z","time spent":"1.451304606s","remote":"127.0.0.1:57028","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-20T10:11:54.747136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.537117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:54.74729Z","caller":"traceutil/trace.go:171","msg":"trace[93746619] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:929; }","duration":"785.745342ms","start":"2026-02-20T10:11:53.961535Z","end":"2026-02-20T10:11:54.74728Z","steps":["trace[93746619] 'agreement among raft nodes before linearized reading' (duration: 785.542867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:54.747266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041470585s","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-20T10:11:54.74732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:53.961518Z","time spent":"785.795162ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-20T10:11:54.747332Z","caller":"traceutil/trace.go:171","msg":"trace[1895558077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.041571947s","start":"2026-02-20T10:11:53.705745Z","end":"2026-02-20T10:11:54.747317Z","steps":["trace[1895558077] 'agreement among raft nodes before linearized reading' (duration: 1.041362013s)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:54.747192Z","caller":"traceutil/trace.go:171","msg":"trace[1107465540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.345906833s","start":"2026-02-20T10:11:53.401268Z","end":"2026-02-20T10:11:54.747174Z","steps":["trace[1107465540] 'agreement among raft nodes before linearized reading' (duration: 1.345626567s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:54.74738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:53.401261Z","time spent":"1.346111329s","remote":"127.0.0.1:57314","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-20T10:11:54.747381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:53.70573Z","time spent":"1.041643999s","remote":"127.0.0.1:57314","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-20T10:11:55.117855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.001328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:11:55.118085Z","caller":"traceutil/trace.go:171","msg":"trace[454184678] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"367.137282ms","start":"2026-02-20T10:11:54.750918Z","end":"2026-02-20T10:11:55.118056Z","steps":["trace[454184678] 'process raft request' (duration: 118.851237ms)","trace[454184678] 'compare' (duration: 247.859225ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:11:55.118217Z","caller":"traceutil/trace.go:171","msg":"trace[1421073877] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"367.252104ms","start":"2026-02-20T10:11:54.750952Z","end":"2026-02-20T10:11:55.118204Z","steps":["trace[1421073877] 'process raft request' (duration: 366.999189ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:55.118429Z","caller":"traceutil/trace.go:171","msg":"trace[1119135330] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"366.952227ms","start":"2026-02-20T10:11:54.751456Z","end":"2026-02-20T10:11:55.118408Z","steps":["trace[1119135330] 'process raft request' (duration: 366.593599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:55.118449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:54.750922Z","time spent":"367.448558ms","remote":"127.0.0.1:57314","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-20T10:11:55.118507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:54.75145Z","time spent":"367.020388ms","remote":"127.0.0.1:57314","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-20T10:11:55.118531Z","caller":"traceutil/trace.go:171","msg":"trace[615345681] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:971; }","duration":"364.060612ms","start":"2026-02-20T10:11:54.75446Z","end":"2026-02-20T10:11:55.11852Z","steps":["trace[615345681] 'read index received' (duration: 115.322147ms)","trace[615345681] 'applied index is now lower than readState.Index' (duration: 248.737505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:55.118657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.197985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:11:55.118692Z","caller":"traceutil/trace.go:171","msg":"trace[258795790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"364.243296ms","start":"2026-02-20T10:11:54.754432Z","end":"2026-02-20T10:11:55.118676Z","steps":["trace[258795790] 'agreement among raft nodes before linearized reading' (duration: 364.199025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:55.118718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:54.754426Z","time spent":"364.286527ms","remote":"127.0.0.1:57028","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-20T10:11:55.118756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.524814ms","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-20T10:11:55.118788Z","caller":"traceutil/trace.go:171","msg":"trace[1626800418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:933; }","duration":"138.596835ms","start":"2026-02-20T10:11:54.980181Z","end":"2026-02-20T10:11:55.118778Z","steps":["trace[1626800418] 'agreement among raft nodes before linearized reading' (duration: 138.474443ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:55.11888Z","caller":"traceutil/trace.go:171","msg":"trace[202777497] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"366.694352ms","start":"2026-02-20T10:11:54.752175Z","end":"2026-02-20T10:11:55.118869Z","steps":["trace[202777497] 'process raft request' (duration: 366.121519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:11:55.118933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:54.752156Z","time spent":"366.746782ms","remote":"127.0.0.1:57314","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-20T10:11:55.119082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:54.750902Z","time spent":"367.364237ms","remote":"127.0.0.1:57314","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-20T10:11:55.701245Z","caller":"traceutil/trace.go:171","msg":"trace[1725355353] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"575.238575ms","start":"2026-02-20T10:11:55.125958Z","end":"2026-02-20T10:11:55.701196Z","steps":["trace[1725355353] 'process raft request' (duration: 538.295426ms)","trace[1725355353] 'compare' (duration: 36.817976ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:11:55.701419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:11:55.125943Z","time spent":"575.383037ms","remote":"127.0.0.1:57314","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-20T10:11:59.360216Z","caller":"traceutil/trace.go:171","msg":"trace[1646322904] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"221.020656ms","start":"2026-02-20T10:11:59.139171Z","end":"2026-02-20T10:11:59.360192Z","steps":["trace[1646322904] 'process raft request' (duration: 220.795451ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:59.360422Z","caller":"traceutil/trace.go:171","msg":"trace[1719200540] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"220.774341ms","start":"2026-02-20T10:11:59.139635Z","end":"2026-02-20T10:11:59.36041Z","steps":["trace[1719200540] 'process raft request' (duration: 220.516615ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:11:59.364021Z","caller":"traceutil/trace.go:171","msg":"trace[938997057] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"155.196361ms","start":"2026-02-20T10:11:59.20881Z","end":"2026-02-20T10:11:59.364006Z","steps":["trace[938997057] 'process raft request' (duration: 154.894935ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:12:01.399436Z","caller":"traceutil/trace.go:171","msg":"trace[658279673] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1015; }","duration":"106.024446ms","start":"2026-02-20T10:12:01.293387Z","end":"2026-02-20T10:12:01.399411Z","steps":["trace[658279673] 'read index received' (duration: 105.714009ms)","trace[658279673] 'applied index is now lower than readState.Index' (duration: 309.017µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:12:01.399449Z","caller":"traceutil/trace.go:171","msg":"trace[1805403999] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"188.289533ms","start":"2026-02-20T10:12:01.211138Z","end":"2026-02-20T10:12:01.399428Z","steps":["trace[1805403999] 'process raft request' (duration: 188.018497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:01.399613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.21291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:12:01.399672Z","caller":"traceutil/trace.go:171","msg":"trace[1483792894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"106.325993ms","start":"2026-02-20T10:12:01.29333Z","end":"2026-02-20T10:12:01.399656Z","steps":["trace[1483792894] 'agreement among raft nodes before linearized reading' (duration: 106.138319ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:12:11.653267Z","caller":"traceutil/trace.go:171","msg":"trace[314180723] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"164.551111ms","start":"2026-02-20T10:12:11.488691Z","end":"2026-02-20T10:12:11.653242Z","steps":["trace[314180723] 'process raft request' (duration: 164.398618ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:12:12.377978Z","caller":"traceutil/trace.go:171","msg":"trace[74190722] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1082; }","duration":"868.061686ms","start":"2026-02-20T10:12:11.509896Z","end":"2026-02-20T10:12:12.377958Z","steps":["trace[74190722] 'read index received' (duration: 143.280549ms)","trace[74190722] 'applied index is now lower than readState.Index' (duration: 724.780167ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:12:12.378071Z","caller":"traceutil/trace.go:171","msg":"trace[952805226] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"885.92095ms","start":"2026-02-20T10:12:11.492137Z","end":"2026-02-20T10:12:12.378058Z","steps":["trace[952805226] 'process raft request' (duration: 885.791627ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:12:12.378049Z","caller":"traceutil/trace.go:171","msg":"trace[1898696468] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"887.480126ms","start":"2026-02-20T10:12:11.490512Z","end":"2026-02-20T10:12:12.377992Z","steps":["trace[1898696468] 'process raft request' (duration: 887.386324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:12.378162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.492112Z","time spent":"885.980051ms","remote":"127.0.0.1:57314","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-20T10:12:12.378271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.381783ms","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":"warn","ts":"2026-02-20T10:12:12.378262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.490499Z","time spent":"887.64676ms","remote":"127.0.0.1:57314","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-20T10:12:12.378308Z","caller":"traceutil/trace.go:171","msg":"trace[2074599020] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1041; }","duration":"868.442275ms","start":"2026-02-20T10:12:11.509853Z","end":"2026-02-20T10:12:12.378296Z","steps":["trace[2074599020] 'agreement among raft nodes before linearized reading' (duration: 868.392104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:12.37833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.509842Z","time spent":"868.482736ms","remote":"127.0.0.1:52370","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-20T10:12:12.378443Z","caller":"traceutil/trace.go:171","msg":"trace[399322511] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"888.931109ms","start":"2026-02-20T10:12:11.489493Z","end":"2026-02-20T10:12:12.378424Z","steps":["trace[399322511] 'process raft request' (duration: 888.285124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:12.378559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.489475Z","time spent":"889.055073ms","remote":"127.0.0.1:57314","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-20T10:12:12.519288Z","caller":"traceutil/trace.go:171","msg":"trace[1839233894] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"141.235721ms","start":"2026-02-20T10:12:12.378029Z","end":"2026-02-20T10:12:12.519265Z","steps":["trace[1839233894] 'read index received' (duration: 138.073888ms)","trace[1839233894] 'applied index is now lower than readState.Index' (duration: 3.160703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:12:12.519525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.186559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:12:12.519556Z","caller":"traceutil/trace.go:171","msg":"trace[1246539142] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"683.558762ms","start":"2026-02-20T10:12:11.835977Z","end":"2026-02-20T10:12:12.519536Z","steps":["trace[1246539142] 'process raft request' (duration: 680.116463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:12.519532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.204808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:12:12.519615Z","caller":"traceutil/trace.go:171","msg":"trace[428723653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"223.315253ms","start":"2026-02-20T10:12:12.296275Z","end":"2026-02-20T10:12:12.51959Z","steps":["trace[428723653] 'agreement among raft nodes before linearized reading' (duration: 223.120438ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:12:12.519627Z","caller":"traceutil/trace.go:171","msg":"trace[1583253413] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1042; }","duration":"580.350812ms","start":"2026-02-20T10:12:11.93926Z","end":"2026-02-20T10:12:12.519611Z","steps":["trace[1583253413] 'agreement among raft nodes before linearized reading' (duration: 580.164528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:12.519674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.835959Z","time spent":"683.645465ms","remote":"127.0.0.1:57314","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-20T10:12:12.519719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.939245Z","time spent":"580.461314ms","remote":"127.0.0.1:52342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-20T10:12:12.519919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.844011ms","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-20T10:12:12.520025Z","caller":"traceutil/trace.go:171","msg":"trace[1252689480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1042; }","duration":"751.051835ms","start":"2026-02-20T10:12:11.768953Z","end":"2026-02-20T10:12:12.520005Z","steps":["trace[1252689480] 'agreement among raft nodes before linearized reading' (duration: 750.734508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:12.520118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.768931Z","time spent":"751.177649ms","remote":"127.0.0.1:57314","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-20T10:12:12.51968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.697736ms","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:533"} {"level":"info","ts":"2026-02-20T10:12:12.520391Z","caller":"traceutil/trace.go:171","msg":"trace[556364522] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1042; }","duration":"997.513195ms","start":"2026-02-20T10:12:11.522864Z","end":"2026-02-20T10:12:12.520377Z","steps":["trace[556364522] 'agreement among raft nodes before linearized reading' (duration: 996.558023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:12:12.520475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:12:11.522775Z","time spent":"997.635698ms","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-20T10:13:07.478316Z","caller":"traceutil/trace.go:171","msg":"trace[287620985] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"104.999161ms","start":"2026-02-20T10:13:07.373267Z","end":"2026-02-20T10:13:07.478266Z","steps":["trace[287620985] 'process raft request' (duration: 104.639022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:13:49.076588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.122962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:13:49.076776Z","caller":"traceutil/trace.go:171","msg":"trace[586418318] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"162.488321ms","start":"2026-02-20T10:13:48.91427Z","end":"2026-02-20T10:13:49.076758Z","steps":["trace[586418318] 'process raft request' (duration: 16.059182ms)","trace[586418318] 'compare' (duration: 145.927908ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:13:49.077014Z","caller":"traceutil/trace.go:171","msg":"trace[1480848466] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"162.178284ms","start":"2026-02-20T10:13:48.914825Z","end":"2026-02-20T10:13:49.077003Z","steps":["trace[1480848466] 'process raft request' (duration: 161.884757ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:15:00.988097Z","caller":"traceutil/trace.go:171","msg":"trace[991040282] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"147.171475ms","start":"2026-02-20T10:15:00.840887Z","end":"2026-02-20T10:15:00.988059Z","steps":["trace[991040282] 'process raft request' (duration: 146.97352ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:15:08.038338Z","caller":"traceutil/trace.go:171","msg":"trace[1135960319] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"266.046564ms","start":"2026-02-20T10:15:07.772273Z","end":"2026-02-20T10:15:08.03832Z","steps":["trace[1135960319] 'process raft request' (duration: 265.937202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:15:08.40085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.519878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:15:08.401125Z","caller":"traceutil/trace.go:171","msg":"trace[1998635165] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1868; }","duration":"104.715603ms","start":"2026-02-20T10:15:08.296395Z","end":"2026-02-20T10:15:08.40111Z","steps":["trace[1998635165] 'read index received' (duration: 42.231µs)","trace[1998635165] 'applied index is now lower than readState.Index' (duration: 104.672691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T10:15:08.401206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.818525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T10:15:08.401239Z","caller":"traceutil/trace.go:171","msg":"trace[1713797106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1789; }","duration":"104.886256ms","start":"2026-02-20T10:15:08.296342Z","end":"2026-02-20T10:15:08.401229Z","steps":["trace[1713797106] 'agreement among raft nodes before linearized reading' (duration: 104.819815ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:15:08.401466Z","caller":"traceutil/trace.go:171","msg":"trace[1039308985] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"628.94351ms","start":"2026-02-20T10:15:07.772485Z","end":"2026-02-20T10:15:08.401428Z","steps":["trace[1039308985] 'process raft request' (duration: 628.49858ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T10:15:08.401475Z","caller":"traceutil/trace.go:171","msg":"trace[1340502994] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"628.98109ms","start":"2026-02-20T10:15:07.772455Z","end":"2026-02-20T10:15:08.401436Z","steps":["trace[1340502994] 'process raft request' (duration: 395.789886ms)","trace[1340502994] 'compare' (duration: 232.338064ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T10:15:08.401506Z","caller":"traceutil/trace.go:171","msg":"trace[568712984] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"627.949856ms","start":"2026-02-20T10:15:07.773542Z","end":"2026-02-20T10:15:08.401492Z","steps":["trace[568712984] 'process raft request' (duration: 627.521326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T10:15:08.401769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:15:07.772463Z","time spent":"629.112423ms","remote":"127.0.0.1:57314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T10:15:08.401853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:15:07.773532Z","time spent":"628.217852ms","remote":"127.0.0.1:57314","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-20T10:15:08.40182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T10:15:07.772436Z","time spent":"629.218696ms","remote":"127.0.0.1:57314","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-20T10:15:08.719007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.26641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T10:15:08.719333Z","caller":"traceutil/trace.go:171","msg":"trace[2061992589] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"236.04323ms","start":"2026-02-20T10:15:08.483258Z","end":"2026-02-20T10:15:08.719301Z","steps":["trace[2061992589] 'process raft request' (duration: 36.360061ms)","trace[2061992589] 'compare' (duration: 199.082145ms)"],"step_count":2}