{"level":"warn","ts":"2026-02-18T22:54:13.216758Z","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-18T22:54:13.216847Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155: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-18T22:54:13.216922Z","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-18T22:54:13.216934Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-18T22:54:13.216953Z","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-18T22:54:13.218959Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-02-18T22:54:13.219058Z","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":2,"max-cpu-available":2,"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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155: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-18T22:54:13.224115Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.832477ms"} {"level":"info","ts":"2026-02-18T22:54:13.232206Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-02-18T22:54:13.233186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:54:13.233205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-02-18T22:54:13.233216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:54:13.233225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-02-18T22:54:13.233254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-02-18T22:54:13.245819Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:54:13.258595Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:54:13.268073Z","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-18T22:54:13.27009Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:54:13.271158Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:54:13.271283Z","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-18T22:54:13.27133Z","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-18T22:54:13.271381Z","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-18T22:54:13.271569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-02-18T22:54:13.27165Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-02-18T22:54:13.27363Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-18T22:54:13.2744Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-02-18T22:54:13.27347Z","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-18T22:54:13.275057Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:54:13.275887Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:54:14.133718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:54:14.13377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:54:14.133801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-02-18T22:54:14.13382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:54:14.13383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-18T22:54:14.133846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-02-18T22:54:14.133859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-02-18T22:54:14.136643Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:54:14.139249Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:54:14.139276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:54:14.140292Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:54:14.140369Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:54:14.14039Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:54:14.140423Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:54:14.142241Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-02-18T22:54:14.142785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:54:14.142941Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:54:14.143029Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-18T22:54:23.083579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.661579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T22:54:23.083656Z","caller":"traceutil/trace.go:171","msg":"trace[2093669919] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:342; }","duration":"200.752631ms","start":"2026-02-18T22:54:22.882884Z","end":"2026-02-18T22:54:23.083637Z","steps":["trace[2093669919] 'range keys from in-memory index tree' (duration: 200.522606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.084207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.861859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-18T22:54:23.084258Z","caller":"traceutil/trace.go:171","msg":"trace[1459723512] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:342; }","duration":"157.945302ms","start":"2026-02-18T22:54:22.926293Z","end":"2026-02-18T22:54:23.084239Z","steps":["trace[1459723512] 'range keys from in-memory index tree' (duration: 157.640795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.084703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.697137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-18T22:54:23.084743Z","caller":"traceutil/trace.go:171","msg":"trace[1227400139] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:342; }","duration":"107.778029ms","start":"2026-02-18T22:54:22.976956Z","end":"2026-02-18T22:54:23.084734Z","steps":["trace[1227400139] 'range keys from in-memory index tree' (duration: 107.601295ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:24.176522Z","caller":"traceutil/trace.go:171","msg":"trace[2041257554] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"107.478264ms","start":"2026-02-18T22:54:24.069024Z","end":"2026-02-18T22:54:24.176502Z","steps":["trace[2041257554] 'read index received' (duration: 13.184073ms)","trace[2041257554] 'applied index is now lower than readState.Index' (duration: 94.293441ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:24.176545Z","caller":"traceutil/trace.go:171","msg":"trace[961889555] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"107.605626ms","start":"2026-02-18T22:54:24.068909Z","end":"2026-02-18T22:54:24.176515Z","steps":["trace[961889555] 'process raft request' (duration: 52.271949ms)","trace[961889555] 'compare' (duration: 55.195854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:24.176664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.615617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-fg79s\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-18T22:54:24.17669Z","caller":"traceutil/trace.go:171","msg":"trace[43532038] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-fg79s; range_end:; response_count:1; response_revision:375; }","duration":"107.677158ms","start":"2026-02-18T22:54:24.069006Z","end":"2026-02-18T22:54:24.176683Z","steps":["trace[43532038] 'agreement among raft nodes before linearized reading' (duration: 107.588546ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:24.34513Z","caller":"traceutil/trace.go:171","msg":"trace[231212200] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"108.619308ms","start":"2026-02-18T22:54:24.236487Z","end":"2026-02-18T22:54:24.345106Z","steps":["trace[231212200] 'process raft request' (duration: 41.414969ms)","trace[231212200] 'compare' (duration: 66.968854ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:24.34563Z","caller":"traceutil/trace.go:171","msg":"trace[597717734] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"106.310816ms","start":"2026-02-18T22:54:24.239301Z","end":"2026-02-18T22:54:24.345612Z","steps":["trace[597717734] 'process raft request' (duration: 105.738524ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:32.251602Z","caller":"traceutil/trace.go:171","msg":"trace[1129166646] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"193.841247ms","start":"2026-02-18T22:54:32.057733Z","end":"2026-02-18T22:54:32.251574Z","steps":["trace[1129166646] 'process raft request' (duration: 193.489729ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:37.99818Z","caller":"traceutil/trace.go:171","msg":"trace[1859618227] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"542.483558ms","start":"2026-02-18T22:54:37.455672Z","end":"2026-02-18T22:54:37.998155Z","steps":["trace[1859618227] 'read index received' (duration: 487.436137ms)","trace[1859618227] 'applied index is now lower than readState.Index' (duration: 55.046241ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:37.998196Z","caller":"traceutil/trace.go:171","msg":"trace[206322357] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"644.10024ms","start":"2026-02-18T22:54:37.354067Z","end":"2026-02-18T22:54:37.998167Z","steps":["trace[206322357] 'process raft request' (duration: 589.08912ms)","trace[206322357] 'compare' (duration: 54.882517ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:37.998405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.729494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:37.998452Z","caller":"traceutil/trace.go:171","msg":"trace[1094632546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"542.845496ms","start":"2026-02-18T22:54:37.455591Z","end":"2026-02-18T22:54:37.998437Z","steps":["trace[1094632546] 'agreement among raft nodes before linearized reading' (duration: 542.692543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:37.999022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:37.354047Z","time spent":"644.210183ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:37.999236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.875342ms","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-18T22:54:37.999292Z","caller":"traceutil/trace.go:171","msg":"trace[1110981470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"167.951093ms","start":"2026-02-18T22:54:37.831317Z","end":"2026-02-18T22:54:37.999268Z","steps":["trace[1110981470] 'agreement among raft nodes before linearized reading' (duration: 167.850441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:37.998482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:37.455568Z","time spent":"542.906817ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:54:47.163645Z","caller":"traceutil/trace.go:171","msg":"trace[245524514] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"165.607121ms","start":"2026-02-18T22:54:46.998018Z","end":"2026-02-18T22:54:47.163625Z","steps":["trace[245524514] 'process raft request' (duration: 165.506589ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:48.767729Z","caller":"traceutil/trace.go:171","msg":"trace[169374314] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"363.651653ms","start":"2026-02-18T22:54:48.404058Z","end":"2026-02-18T22:54:48.76771Z","steps":["trace[169374314] 'process raft request' (duration: 363.540091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:48.767849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:48.404046Z","time spent":"363.749695ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:48.801839Z","caller":"traceutil/trace.go:171","msg":"trace[1404047504] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"346.264526ms","start":"2026-02-18T22:54:48.455559Z","end":"2026-02-18T22:54:48.801823Z","steps":["trace[1404047504] 'read index received' (duration: 312.13652ms)","trace[1404047504] 'applied index is now lower than readState.Index' (duration: 34.127336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:48.801963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.39815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:48.801983Z","caller":"traceutil/trace.go:171","msg":"trace[1162562862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"346.455101ms","start":"2026-02-18T22:54:48.455521Z","end":"2026-02-18T22:54:48.801976Z","steps":["trace[1162562862] 'agreement among raft nodes before linearized reading' (duration: 346.369269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:48.802004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:48.455507Z","time spent":"346.492051ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:54:48.80212Z","caller":"traceutil/trace.go:171","msg":"trace[1655328571] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"397.289248ms","start":"2026-02-18T22:54:48.404801Z","end":"2026-02-18T22:54:48.80209Z","steps":["trace[1655328571] 'process raft request' (duration: 396.944611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:48.802224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:48.404776Z","time spent":"397.386661ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:49.589595Z","caller":"traceutil/trace.go:171","msg":"trace[196261855] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"130.694697ms","start":"2026-02-18T22:54:49.458885Z","end":"2026-02-18T22:54:49.589579Z","steps":["trace[196261855] 'read index received' (duration: 130.546644ms)","trace[196261855] 'applied index is now lower than readState.Index' (duration: 147.563µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:49.589691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.787589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:49.589707Z","caller":"traceutil/trace.go:171","msg":"trace[1792727002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"130.84438ms","start":"2026-02-18T22:54:49.458858Z","end":"2026-02-18T22:54:49.589702Z","steps":["trace[1792727002] 'agreement among raft nodes before linearized reading' (duration: 130.778379ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:49.589825Z","caller":"traceutil/trace.go:171","msg":"trace[1579863937] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"421.958325ms","start":"2026-02-18T22:54:49.167846Z","end":"2026-02-18T22:54:49.589804Z","steps":["trace[1579863937] 'process raft request' (duration: 421.629467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:49.589933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:49.167837Z","time spent":"422.034937ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:50.217726Z","caller":"traceutil/trace.go:171","msg":"trace[2055937927] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"125.246207ms","start":"2026-02-18T22:54:50.092466Z","end":"2026-02-18T22:54:50.217712Z","steps":["trace[2055937927] 'read index received' (duration: 87.714084ms)","trace[2055937927] 'applied index is now lower than readState.Index' (duration: 37.531583ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:50.217814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.341189ms","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-18T22:54:50.217833Z","caller":"traceutil/trace.go:171","msg":"trace[966608686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"125.39139ms","start":"2026-02-18T22:54:50.092435Z","end":"2026-02-18T22:54:50.217826Z","steps":["trace[966608686] 'agreement among raft nodes before linearized reading' (duration: 125.330978ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:50.218095Z","caller":"traceutil/trace.go:171","msg":"trace[2123754491] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"192.908277ms","start":"2026-02-18T22:54:50.025165Z","end":"2026-02-18T22:54:50.218073Z","steps":["trace[2123754491] 'process raft request' (duration: 155.065027ms)","trace[2123754491] 'compare' (duration: 37.057993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:50.563579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.413725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:50.563693Z","caller":"traceutil/trace.go:171","msg":"trace[708402356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"109.560389ms","start":"2026-02-18T22:54:50.454112Z","end":"2026-02-18T22:54:50.563672Z","steps":["trace[708402356] 'range keys from in-memory index tree' (duration: 109.361004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:54.956349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935141780,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:55.397617Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.00899127s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:54:55.585783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.719052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:55.585873Z","caller":"traceutil/trace.go:171","msg":"trace[1807041070] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"1.130675799s","start":"2026-02-18T22:54:54.455182Z","end":"2026-02-18T22:54:55.585858Z","steps":["trace[1807041070] 'read index received' (duration: 942.627649ms)","trace[1807041070] 'applied index is now lower than readState.Index' (duration: 188.04713ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:55.58606Z","caller":"traceutil/trace.go:171","msg":"trace[1268048541] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.19752189s","start":"2026-02-18T22:54:54.388526Z","end":"2026-02-18T22:54:55.586048Z","steps":["trace[1268048541] 'process raft request' (duration: 1.009351668s)","trace[1268048541] 'compare' (duration: 187.481107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:55.586115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:54.38851Z","time spent":"1.197576391s","remote":"127.0.0.1:52372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:55.586269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131083737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:55.586297Z","caller":"traceutil/trace.go:171","msg":"trace[1227619892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.131137028s","start":"2026-02-18T22:54:54.45515Z","end":"2026-02-18T22:54:55.586287Z","steps":["trace[1227619892] 'agreement among raft nodes before linearized reading' (duration: 1.131093157s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:55.586324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:54.455137Z","time spent":"1.13118207s","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:55.586462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.118931ms","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-18T22:54:55.586487Z","caller":"traceutil/trace.go:171","msg":"trace[2106702476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"741.177352ms","start":"2026-02-18T22:54:54.845302Z","end":"2026-02-18T22:54:55.586479Z","steps":["trace[2106702476] 'agreement among raft nodes before linearized reading' (duration: 741.132361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:55.58651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:54.845285Z","time spent":"741.219743ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:55.586781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.367151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:54:55.586808Z","caller":"traceutil/trace.go:171","msg":"trace[1355852427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"716.417832ms","start":"2026-02-18T22:54:54.870383Z","end":"2026-02-18T22:54:55.586801Z","steps":["trace[1355852427] 'agreement among raft nodes before linearized reading' (duration: 716.367061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:55.586833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:54.870373Z","time spent":"716.454114ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:54:55.899418Z","caller":"traceutil/trace.go:171","msg":"trace[1811450448] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"292.850863ms","start":"2026-02-18T22:54:55.606531Z","end":"2026-02-18T22:54:55.899382Z","steps":["trace[1811450448] 'process raft request' (duration: 194.072533ms)","trace[1811450448] 'compare' (duration: 98.603166ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:55.900801Z","caller":"traceutil/trace.go:171","msg":"trace[2115813023] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"293.339922ms","start":"2026-02-18T22:54:55.607451Z","end":"2026-02-18T22:54:55.900791Z","steps":["trace[2115813023] 'process raft request' (duration: 293.317332ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:55.901014Z","caller":"traceutil/trace.go:171","msg":"trace[1672718061] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"293.589538ms","start":"2026-02-18T22:54:55.607417Z","end":"2026-02-18T22:54:55.901007Z","steps":["trace[1672718061] 'process raft request' (duration: 293.306562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.633198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.324264Z","time spent":"308.930649ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T22:54:57.633357Z","caller":"traceutil/trace.go:171","msg":"trace[1255343092] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:566; }","duration":"248.800946ms","start":"2026-02-18T22:54:57.38454Z","end":"2026-02-18T22:54:57.633341Z","steps":["trace[1255343092] 'read index received' (duration: 248.796006ms)","trace[1255343092] 'applied index is now lower than readState.Index' (duration: 3.54µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:57.633505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.935199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7xhpw\" ","response":"range_response_count:1 size:16916"} {"level":"info","ts":"2026-02-18T22:54:57.633528Z","caller":"traceutil/trace.go:171","msg":"trace[1215452334] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7xhpw; range_end:; response_count:1; response_revision:549; }","duration":"249.016591ms","start":"2026-02-18T22:54:57.384506Z","end":"2026-02-18T22:54:57.633522Z","steps":["trace[1215452334] 'agreement among raft nodes before linearized reading' (duration: 248.901278ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:57.750107Z","caller":"traceutil/trace.go:171","msg":"trace[586745137] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"116.695937ms","start":"2026-02-18T22:54:57.633376Z","end":"2026-02-18T22:54:57.750071Z","steps":["trace[586745137] 'read index received' (duration: 100.5596ms)","trace[586745137] 'applied index is now lower than readState.Index' (duration: 16.135057ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:57.750137Z","caller":"traceutil/trace.go:171","msg":"trace[280610636] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"362.218461ms","start":"2026-02-18T22:54:57.387877Z","end":"2026-02-18T22:54:57.750095Z","steps":["trace[280610636] 'process raft request' (duration: 346.053603ms)","trace[280610636] 'compare' (duration: 16.011584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:57.750246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.82763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:57.750282Z","caller":"traceutil/trace.go:171","msg":"trace[7955775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"296.913802ms","start":"2026-02-18T22:54:57.453358Z","end":"2026-02-18T22:54:57.750271Z","steps":["trace[7955775] 'agreement among raft nodes before linearized reading' (duration: 296.81139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:57.75034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.38786Z","time spent":"362.383495ms","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:54:57.757704Z","caller":"traceutil/trace.go:171","msg":"trace[15849709] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"122.67762ms","start":"2026-02-18T22:54:57.635017Z","end":"2026-02-18T22:54:57.757695Z","steps":["trace[15849709] 'process raft request' (duration: 122.299102ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:57.757811Z","caller":"traceutil/trace.go:171","msg":"trace[2120011732] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"114.690823ms","start":"2026-02-18T22:54:57.643102Z","end":"2026-02-18T22:54:57.757793Z","steps":["trace[2120011732] 'process raft request' (duration: 114.346595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:58.501544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.183949ms","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-18T22:54:58.507989Z","caller":"traceutil/trace.go:171","msg":"trace[1186443296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"165.664863ms","start":"2026-02-18T22:54:58.342305Z","end":"2026-02-18T22:54:58.50797Z","steps":["trace[1186443296] 'range keys from in-memory index tree' (duration: 159.037386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.696552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.125086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-18T22:55:02.696835Z","caller":"traceutil/trace.go:171","msg":"trace[1729813833] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:614; }","duration":"934.461673ms","start":"2026-02-18T22:55:01.762346Z","end":"2026-02-18T22:55:02.696807Z","steps":["trace[1729813833] 'range keys from in-memory index tree' (duration: 933.86477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.696863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.762323Z","time spent":"934.533255ms","remote":"127.0.0.1:52242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:55:02.69756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.951997ms","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-18T22:55:02.697712Z","caller":"traceutil/trace.go:171","msg":"trace[1152586159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"749.128091ms","start":"2026-02-18T22:55:01.948578Z","end":"2026-02-18T22:55:02.697706Z","steps":["trace[1152586159] 'range keys from in-memory index tree' (duration: 748.885246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.697731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.948563Z","time spent":"749.161512ms","remote":"127.0.0.1:52448","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-18T22:55:02.697912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.37993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:02.697928Z","caller":"traceutil/trace.go:171","msg":"trace[1040776135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"744.454091ms","start":"2026-02-18T22:55:01.953469Z","end":"2026-02-18T22:55:02.697923Z","steps":["trace[1040776135] 'range keys from in-memory index tree' (duration: 744.280017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.697942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.953464Z","time spent":"744.473911ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:02.698084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.408787ms","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-18T22:55:02.6981Z","caller":"traceutil/trace.go:171","msg":"trace[1161893083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"742.435908ms","start":"2026-02-18T22:55:01.955659Z","end":"2026-02-18T22:55:02.698095Z","steps":["trace[1161893083] 'range keys from in-memory index tree' (duration: 742.318575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.698116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.955654Z","time spent":"742.458148ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:02.698292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.213266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-18T22:55:02.69831Z","caller":"traceutil/trace.go:171","msg":"trace[1324128176] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:614; }","duration":"737.240116ms","start":"2026-02-18T22:55:01.961063Z","end":"2026-02-18T22:55:02.698304Z","steps":["trace[1324128176] 'range keys from in-memory index tree' (duration: 737.115313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.698337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.961059Z","time spent":"737.260926ms","remote":"127.0.0.1:52242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10399,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-18T22:55:02.709443Z","caller":"traceutil/trace.go:171","msg":"trace[657861908] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"502.362365ms","start":"2026-02-18T22:55:02.207064Z","end":"2026-02-18T22:55:02.709427Z","steps":["trace[657861908] 'process raft request' (duration: 502.183081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.710089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.207029Z","time spent":"502.528048ms","remote":"127.0.0.1:52242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:55:02.710493Z","caller":"traceutil/trace.go:171","msg":"trace[312020864] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:633; }","duration":"441.557944ms","start":"2026-02-18T22:55:02.268927Z","end":"2026-02-18T22:55:02.710485Z","steps":["trace[312020864] 'read index received' (duration: 441.554554ms)","trace[312020864] 'applied index is now lower than readState.Index' (duration: 2.82µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:02.710576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.640805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:02.710604Z","caller":"traceutil/trace.go:171","msg":"trace[150062889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"441.667546ms","start":"2026-02-18T22:55:02.268921Z","end":"2026-02-18T22:55:02.710589Z","steps":["trace[150062889] 'agreement among raft nodes before linearized reading' (duration: 441.601455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.710623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.268872Z","time spent":"441.748438ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:55:02.830774Z","caller":"traceutil/trace.go:171","msg":"trace[347945303] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:633; }","duration":"120.242362ms","start":"2026-02-18T22:55:02.71051Z","end":"2026-02-18T22:55:02.830752Z","steps":["trace[347945303] 'read index received' (duration: 119.69225ms)","trace[347945303] 'applied index is now lower than readState.Index' (duration: 549.732µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:02.830865Z","caller":"traceutil/trace.go:171","msg":"trace[47383651] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"399.7871ms","start":"2026-02-18T22:55:02.431071Z","end":"2026-02-18T22:55:02.830859Z","steps":["trace[47383651] 'process raft request' (duration: 399.206008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.831498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.739472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7xhpw\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-18T22:55:02.831529Z","caller":"traceutil/trace.go:171","msg":"trace[1426977154] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7xhpw; range_end:; response_count:1; response_revision:617; }","duration":"271.812123ms","start":"2026-02-18T22:55:02.559709Z","end":"2026-02-18T22:55:02.831521Z","steps":["trace[1426977154] 'agreement among raft nodes before linearized reading' (duration: 271.330663ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:02.836268Z","caller":"traceutil/trace.go:171","msg":"trace[631492302] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"229.886625ms","start":"2026-02-18T22:55:02.606374Z","end":"2026-02-18T22:55:02.836261Z","steps":["trace[631492302] 'process raft request' (duration: 224.243665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.830928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.431038Z","time spent":"399.842041ms","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:55:02.836676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.285351ms","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-18T22:55:02.837215Z","caller":"traceutil/trace.go:171","msg":"trace[430813706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:617; }","duration":"206.850663ms","start":"2026-02-18T22:55:02.630353Z","end":"2026-02-18T22:55:02.837204Z","steps":["trace[430813706] 'agreement among raft nodes before linearized reading' (duration: 206.270731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.837289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.367667ms","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-18T22:55:02.83744Z","caller":"traceutil/trace.go:171","msg":"trace[1474960689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"303.692144ms","start":"2026-02-18T22:55:02.533712Z","end":"2026-02-18T22:55:02.837405Z","steps":["trace[1474960689] 'agreement among raft nodes before linearized reading' (duration: 303.259545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.838318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.53366Z","time spent":"304.648975ms","remote":"127.0.0.1:52448","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-18T22:55:02.83755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.070764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:02.838455Z","caller":"traceutil/trace.go:171","msg":"trace[942476229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"385.004604ms","start":"2026-02-18T22:55:02.453441Z","end":"2026-02-18T22:55:02.838446Z","steps":["trace[942476229] 'agreement among raft nodes before linearized reading' (duration: 384.082335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.839295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.453425Z","time spent":"385.745819ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:55:02.837962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.931249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-18T22:55:02.839634Z","caller":"traceutil/trace.go:171","msg":"trace[1751163878] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:617; }","duration":"477.629214ms","start":"2026-02-18T22:55:02.361995Z","end":"2026-02-18T22:55:02.839625Z","steps":["trace[1751163878] 'agreement among raft nodes before linearized reading' (duration: 475.919048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.839658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:02.361976Z","time spent":"477.676836ms","remote":"127.0.0.1:52242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-18T22:55:02.841112Z","caller":"traceutil/trace.go:171","msg":"trace[2097978787] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"112.338552ms","start":"2026-02-18T22:55:02.728764Z","end":"2026-02-18T22:55:02.841102Z","steps":["trace[2097978787] 'process raft request' (duration: 110.327879ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:02.841621Z","caller":"traceutil/trace.go:171","msg":"trace[1959142964] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"112.938985ms","start":"2026-02-18T22:55:02.728674Z","end":"2026-02-18T22:55:02.841613Z","steps":["trace[1959142964] 'process raft request' (duration: 110.258197ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:02.842315Z","caller":"traceutil/trace.go:171","msg":"trace[1861055992] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"113.508726ms","start":"2026-02-18T22:55:02.728799Z","end":"2026-02-18T22:55:02.842308Z","steps":["trace[1861055992] 'process raft request' (duration: 110.320908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.880119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.748249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-18T22:55:02.88044Z","caller":"traceutil/trace.go:171","msg":"trace[1109560104] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:621; }","duration":"119.140748ms","start":"2026-02-18T22:55:02.761283Z","end":"2026-02-18T22:55:02.880423Z","steps":["trace[1109560104] 'agreement among raft nodes before linearized reading' (duration: 118.645027ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:03.878288Z","caller":"traceutil/trace.go:171","msg":"trace[1484228150] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"143.09298ms","start":"2026-02-18T22:55:03.735174Z","end":"2026-02-18T22:55:03.878267Z","steps":["trace[1484228150] 'process raft request' (duration: 142.946327ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:04.982632Z","caller":"traceutil/trace.go:171","msg":"trace[640909886] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"123.783836ms","start":"2026-02-18T22:55:04.858811Z","end":"2026-02-18T22:55:04.982595Z","steps":["trace[640909886] 'read index received' (duration: 38.001413ms)","trace[640909886] 'applied index is now lower than readState.Index' (duration: 85.781223ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:04.982795Z","caller":"traceutil/trace.go:171","msg":"trace[930086582] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"151.601302ms","start":"2026-02-18T22:55:04.83118Z","end":"2026-02-18T22:55:04.982781Z","steps":["trace[930086582] 'process raft request' (duration: 114.56265ms)","trace[930086582] 'compare' (duration: 36.656974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:04.983207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360279ms","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-18T22:55:04.983281Z","caller":"traceutil/trace.go:171","msg":"trace[2098568481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"124.503432ms","start":"2026-02-18T22:55:04.858759Z","end":"2026-02-18T22:55:04.983263Z","steps":["trace[2098568481] 'agreement among raft nodes before linearized reading' (duration: 124.348759ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:05.142959Z","caller":"traceutil/trace.go:171","msg":"trace[1017393879] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"135.917106ms","start":"2026-02-18T22:55:05.007008Z","end":"2026-02-18T22:55:05.142925Z","steps":["trace[1017393879] 'process raft request' (duration: 115.253435ms)","trace[1017393879] 'compare' (duration: 20.524228ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:05.15388Z","caller":"traceutil/trace.go:171","msg":"trace[949933411] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"144.849157ms","start":"2026-02-18T22:55:05.009019Z","end":"2026-02-18T22:55:05.153868Z","steps":["trace[949933411] 'process raft request' (duration: 144.556461ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:05.154366Z","caller":"traceutil/trace.go:171","msg":"trace[927714504] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"136.760995ms","start":"2026-02-18T22:55:05.017594Z","end":"2026-02-18T22:55:05.154355Z","steps":["trace[927714504] 'process raft request' (duration: 136.471198ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:05.154621Z","caller":"traceutil/trace.go:171","msg":"trace[298342924] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"135.531359ms","start":"2026-02-18T22:55:05.019083Z","end":"2026-02-18T22:55:05.154615Z","steps":["trace[298342924] 'process raft request' (duration: 135.12896ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:05.15456Z","caller":"traceutil/trace.go:171","msg":"trace[199175141] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"140.007054ms","start":"2026-02-18T22:55:05.014544Z","end":"2026-02-18T22:55:05.154551Z","steps":["trace[199175141] 'process raft request' (duration: 139.290518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:09.666155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.591958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:09.666255Z","caller":"traceutil/trace.go:171","msg":"trace[1680781618] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"406.245748ms","start":"2026-02-18T22:55:09.259993Z","end":"2026-02-18T22:55:09.666238Z","steps":["trace[1680781618] 'process raft request' (duration: 40.476696ms)","trace[1680781618] 'compare' (duration: 365.401564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:09.666294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.259977Z","time spent":"406.301739ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:09.969795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142048,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:10.400801Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.100481933s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:10.40124Z","caller":"traceutil/trace.go:171","msg":"trace[154529324] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:693; }","duration":"931.770136ms","start":"2026-02-18T22:55:09.46945Z","end":"2026-02-18T22:55:10.40122Z","steps":["trace[154529324] 'read index received' (duration: 931.488799ms)","trace[154529324] 'applied index is now lower than readState.Index' (duration: 280.587µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:10.401336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.863827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:10.401377Z","caller":"traceutil/trace.go:171","msg":"trace[821601122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"931.95349ms","start":"2026-02-18T22:55:09.469417Z","end":"2026-02-18T22:55:10.401371Z","steps":["trace[821601122] 'agreement among raft nodes before linearized reading' (duration: 931.861918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:10.401403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.469401Z","time spent":"931.99726ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:55:10.401681Z","caller":"traceutil/trace.go:171","msg":"trace[309614065] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.141390409s","start":"2026-02-18T22:55:09.260284Z","end":"2026-02-18T22:55:10.401674Z","steps":["trace[309614065] 'process raft request' (duration: 1.140716514s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:10.401738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.260265Z","time spent":"1.14143379s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:10.401831Z","caller":"traceutil/trace.go:171","msg":"trace[572981390] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.141490461s","start":"2026-02-18T22:55:09.260336Z","end":"2026-02-18T22:55:10.401827Z","steps":["trace[572981390] 'process raft request' (duration: 1.140799946s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:10.401863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.260322Z","time spent":"1.141526122s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:10.401935Z","caller":"traceutil/trace.go:171","msg":"trace[208869946] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.121248838s","start":"2026-02-18T22:55:09.28068Z","end":"2026-02-18T22:55:10.401929Z","steps":["trace[208869946] 'process raft request' (duration: 1.120476981s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:10.401966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.280657Z","time spent":"1.121293628s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:10.402068Z","caller":"traceutil/trace.go:171","msg":"trace[788071009] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"1.119391658s","start":"2026-02-18T22:55:09.282671Z","end":"2026-02-18T22:55:10.402063Z","steps":["trace[788071009] 'process raft request' (duration: 1.11852162s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:10.402094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:09.282661Z","time spent":"1.119421839s","remote":"127.0.0.1:38378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:55:12.601612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.23212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:55:12.601683Z","caller":"traceutil/trace.go:171","msg":"trace[1561508620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"191.346582ms","start":"2026-02-18T22:55:12.410322Z","end":"2026-02-18T22:55:12.601668Z","steps":["trace[1561508620] 'range keys from in-memory index tree' (duration: 191.076196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:12.601735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.378222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T22:55:12.601769Z","caller":"traceutil/trace.go:171","msg":"trace[399855504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"190.423313ms","start":"2026-02-18T22:55:12.411337Z","end":"2026-02-18T22:55:12.601761Z","steps":["trace[399855504] 'range keys from in-memory index tree' (duration: 190.30982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:12.601888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.532346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:55:12.601904Z","caller":"traceutil/trace.go:171","msg":"trace[1669889959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"191.578717ms","start":"2026-02-18T22:55:12.410321Z","end":"2026-02-18T22:55:12.6019Z","steps":["trace[1669889959] 'range keys from in-memory index tree' (duration: 191.443624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:12.601939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.701989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:12.601958Z","caller":"traceutil/trace.go:171","msg":"trace[89995713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"147.74749ms","start":"2026-02-18T22:55:12.454204Z","end":"2026-02-18T22:55:12.601951Z","steps":["trace[89995713] 'range keys from in-memory index tree' (duration: 147.651268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.574895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:14.075883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:14.08764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.482013574s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:14.087861Z","caller":"traceutil/trace.go:171","msg":"trace[1171733907] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.482282731s","start":"2026-02-18T22:55:12.60556Z","end":"2026-02-18T22:55:14.087843Z","steps":["trace[1171733907] 'process raft request' (duration: 1.482164077s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:14.087966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.605549Z","time spent":"1.482340522s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:14.490797Z","caller":"traceutil/trace.go:171","msg":"trace[322154375] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.885170156s","start":"2026-02-18T22:55:12.605589Z","end":"2026-02-18T22:55:14.490759Z","steps":["trace[322154375] 'process raft request' (duration: 1.884757797s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:14.490938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.605564Z","time spent":"1.885304359s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:14.491289Z","caller":"traceutil/trace.go:171","msg":"trace[94509000] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.885606645s","start":"2026-02-18T22:55:12.605672Z","end":"2026-02-18T22:55:14.491279Z","steps":["trace[94509000] 'process raft request' (duration: 1.884810788s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:14.492604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.605667Z","time spent":"1.886891122s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:15.987295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49675443s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T22:55:15.988306Z","caller":"traceutil/trace.go:171","msg":"trace[1100919929] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:702; }","duration":"2.913410664s","start":"2026-02-18T22:55:13.074678Z","end":"2026-02-18T22:55:15.988089Z","steps":["trace[1100919929] 'read index received' (duration: 1.013584076s)","trace[1100919929] 'applied index is now lower than readState.Index' (duration: 1.899825448s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:15.988612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.913925965s","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-18T22:55:15.989421Z","caller":"traceutil/trace.go:171","msg":"trace[187451030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"2.914769282s","start":"2026-02-18T22:55:13.074638Z","end":"2026-02-18T22:55:15.989407Z","steps":["trace[187451030] 'agreement among raft nodes before linearized reading' (duration: 2.913863213s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:15.990625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:13.074621Z","time spent":"2.915937959s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:55:15.991491Z","caller":"traceutil/trace.go:171","msg":"trace[235533525] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"1.666114341s","start":"2026-02-18T22:55:14.32536Z","end":"2026-02-18T22:55:15.991474Z","steps":["trace[235533525] 'process raft request' (duration: 1.662446143s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:15.992045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:14.325334Z","time spent":"1.666251733s","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:55:17.232675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.490915755s","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-18T22:55:17.232776Z","caller":"traceutil/trace.go:171","msg":"trace[1892976866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"3.491013217s","start":"2026-02-18T22:55:13.741727Z","end":"2026-02-18T22:55:17.23274Z","steps":["trace[1892976866] 'agreement among raft nodes before linearized reading' (duration: 2.24970292s)","trace[1892976866] 'range keys from in-memory index tree' (duration: 1.241026301s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.232824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:13.741666Z","time spent":"3.49114274s","remote":"127.0.0.1:52448","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-18T22:55:17.233205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.563478282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:17.233255Z","caller":"traceutil/trace.go:171","msg":"trace[605991626] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:684; }","duration":"2.563580934s","start":"2026-02-18T22:55:14.669658Z","end":"2026-02-18T22:55:17.233239Z","steps":["trace[605991626] 'agreement among raft nodes before linearized reading' (duration: 1.322659356s)","trace[605991626] 'count revisions from in-memory index tree' (duration: 1.240852557s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.233299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:14.669636Z","time spent":"2.563650095s","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":88,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:17.233443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.671604323s","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-18T22:55:17.233547Z","caller":"traceutil/trace.go:171","msg":"trace[1951413512] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:684; }","duration":"2.671752686s","start":"2026-02-18T22:55:14.561776Z","end":"2026-02-18T22:55:17.233528Z","steps":["trace[1951413512] 'agreement among raft nodes before linearized reading' (duration: 1.430552512s)","trace[1951413512] 'count revisions from in-memory index tree' (duration: 1.241089932s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.233601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:14.561755Z","time spent":"2.671829868s","remote":"127.0.0.1:52348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:17.233848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.777797816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:17.233904Z","caller":"traceutil/trace.go:171","msg":"trace[1951142101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"2.777881748s","start":"2026-02-18T22:55:14.456006Z","end":"2026-02-18T22:55:17.233888Z","steps":["trace[1951142101] 'agreement among raft nodes before linearized reading' (duration: 1.536330935s)","trace[1951142101] 'range keys from in-memory index tree' (duration: 1.241486671s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.233953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:14.455989Z","time spent":"2.777951729s","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:55:17.235673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.190884ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-18T22:55:17.235849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:15.996202Z","time spent":"1.239643111s","remote":"127.0.0.1:52274","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T22:55:17.235924Z","caller":"traceutil/trace.go:171","msg":"trace[973440557] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:706; }","duration":"1.144393354s","start":"2026-02-18T22:55:16.091487Z","end":"2026-02-18T22:55:17.23588Z","steps":["trace[973440557] 'read index received' (duration: 250.087648ms)","trace[973440557] 'applied index is now lower than readState.Index' (duration: 894.304445ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:17.236164Z","caller":"traceutil/trace.go:171","msg":"trace[1090639080] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.239663591s","start":"2026-02-18T22:55:15.996484Z","end":"2026-02-18T22:55:17.236147Z","steps":["trace[1090639080] 'process raft request' (duration: 1.239280243s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.236279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144793181s","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-18T22:55:17.23631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:15.996472Z","time spent":"1.239731602s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:17.23633Z","caller":"traceutil/trace.go:171","msg":"trace[870170051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"1.144879714s","start":"2026-02-18T22:55:16.091435Z","end":"2026-02-18T22:55:17.236314Z","steps":["trace[870170051] 'agreement among raft nodes before linearized reading' (duration: 1.144735151s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.236373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:16.091417Z","time spent":"1.144943955s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T22:55:17.480632Z","caller":"traceutil/trace.go:171","msg":"trace[533038271] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"234.198389ms","start":"2026-02-18T22:55:17.246406Z","end":"2026-02-18T22:55:17.480605Z","steps":["trace[533038271] 'process raft request' (duration: 234.163088ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:17.482688Z","caller":"traceutil/trace.go:171","msg":"trace[675544495] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"563.136823ms","start":"2026-02-18T22:55:16.919532Z","end":"2026-02-18T22:55:17.482669Z","steps":["trace[675544495] 'process raft request' (duration: 521.667737ms)","trace[675544495] 'compare' (duration: 38.928752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:17.482812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:16.919491Z","time spent":"563.240255ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:17.482947Z","caller":"traceutil/trace.go:171","msg":"trace[1706594854] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"246.976082ms","start":"2026-02-18T22:55:17.235963Z","end":"2026-02-18T22:55:17.482939Z","steps":["trace[1706594854] 'read index received' (duration: 205.189089ms)","trace[1706594854] 'applied index is now lower than readState.Index' (duration: 41.786403ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:17.483174Z","caller":"traceutil/trace.go:171","msg":"trace[703481950] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"242.448846ms","start":"2026-02-18T22:55:17.240716Z","end":"2026-02-18T22:55:17.483165Z","steps":["trace[703481950] 'process raft request' (duration: 239.748428ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:17.483425Z","caller":"traceutil/trace.go:171","msg":"trace[312539381] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"240.036344ms","start":"2026-02-18T22:55:17.243377Z","end":"2026-02-18T22:55:17.483413Z","steps":["trace[312539381] 'process raft request' (duration: 237.159032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.483784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.159767ms","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-18T22:55:17.483832Z","caller":"traceutil/trace.go:171","msg":"trace[1186873480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"984.250349ms","start":"2026-02-18T22:55:16.499574Z","end":"2026-02-18T22:55:17.483825Z","steps":["trace[1186873480] 'agreement among raft nodes before linearized reading' (duration: 984.112837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.483856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:16.499551Z","time spent":"984.298661ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:17.484173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.595897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:17.484255Z","caller":"traceutil/trace.go:171","msg":"trace[1657157845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"984.7164ms","start":"2026-02-18T22:55:16.499513Z","end":"2026-02-18T22:55:17.484229Z","steps":["trace[1657157845] 'agreement among raft nodes before linearized reading' (duration: 984.463875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.484301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:16.4995Z","time spent":"984.790271ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:17.484481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.790037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:17.484534Z","caller":"traceutil/trace.go:171","msg":"trace[208142389] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:689; }","duration":"159.861529ms","start":"2026-02-18T22:55:17.324665Z","end":"2026-02-18T22:55:17.484526Z","steps":["trace[208142389] 'agreement among raft nodes before linearized reading' (duration: 159.749207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.484693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027861352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:17.484726Z","caller":"traceutil/trace.go:171","msg":"trace[1479202293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"1.027935913s","start":"2026-02-18T22:55:16.45678Z","end":"2026-02-18T22:55:17.484716Z","steps":["trace[1479202293] 'agreement among raft nodes before linearized reading' (duration: 1.027883002s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:17.484752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:16.456751Z","time spent":"1.027995745s","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:55:19.304186Z","caller":"traceutil/trace.go:171","msg":"trace[1319559627] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"651.8246ms","start":"2026-02-18T22:55:18.652334Z","end":"2026-02-18T22:55:19.304158Z","steps":["trace[1319559627] 'process raft request' (duration: 651.278748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.304342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:18.65232Z","time spent":"651.934082ms","remote":"127.0.0.1:52372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4972,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:20.576028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035460735s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:20.57614Z","caller":"traceutil/trace.go:171","msg":"trace[58353976] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"1.263993612s","start":"2026-02-18T22:55:19.312128Z","end":"2026-02-18T22:55:20.576121Z","steps":["trace[58353976] 'process raft request' (duration: 228.351103ms)","trace[58353976] 'compare' (duration: 1.035028335s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:20.576184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.312118Z","time spent":"1.264045593s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:20.973373Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.432969824s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:55:21.163789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.012592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:21.163954Z","caller":"traceutil/trace.go:171","msg":"trace[734774931] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:724; }","duration":"1.707304441s","start":"2026-02-18T22:55:19.456636Z","end":"2026-02-18T22:55:21.16394Z","steps":["trace[734774931] 'read index received' (duration: 83.853624ms)","trace[734774931] 'applied index is now lower than readState.Index' (duration: 1.623449457s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:21.164071Z","caller":"traceutil/trace.go:171","msg":"trace[476999977] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.851731529s","start":"2026-02-18T22:55:19.312332Z","end":"2026-02-18T22:55:21.164064Z","steps":["trace[476999977] 'process raft request' (duration: 1.661381859s)","trace[476999977] 'compare' (duration: 189.554953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:21.164106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.312328Z","time spent":"1.85175968s","remote":"127.0.0.1:52358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:21.164297Z","caller":"traceutil/trace.go:171","msg":"trace[1699539007] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"1.851864872s","start":"2026-02-18T22:55:19.312427Z","end":"2026-02-18T22:55:21.164292Z","steps":["trace[1699539007] 'process raft request' (duration: 1.851444212s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.164324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.312423Z","time spent":"1.851886502s","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1291,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:21.164438Z","caller":"traceutil/trace.go:171","msg":"trace[619206930] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"1.847494049s","start":"2026-02-18T22:55:19.316939Z","end":"2026-02-18T22:55:21.164434Z","steps":["trace[619206930] 'process raft request' (duration: 1.846968977s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.164461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.316931Z","time spent":"1.84751786s","remote":"127.0.0.1:52372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4794,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:21.16453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707897404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:21.164546Z","caller":"traceutil/trace.go:171","msg":"trace[164459603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"1.707952195s","start":"2026-02-18T22:55:19.456588Z","end":"2026-02-18T22:55:21.16454Z","steps":["trace[164459603] 'agreement among raft nodes before linearized reading' (duration: 1.707926135s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.164558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.456566Z","time spent":"1.707989476s","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:55:21.176412Z","caller":"traceutil/trace.go:171","msg":"trace[1686061809] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"476.048689ms","start":"2026-02-18T22:55:20.700344Z","end":"2026-02-18T22:55:21.176393Z","steps":["trace[1686061809] 'process raft request' (duration: 475.753843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.176508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:20.700318Z","time spent":"476.141772ms","remote":"127.0.0.1:52362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:21.176664Z","caller":"traceutil/trace.go:171","msg":"trace[430319426] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"212.29675ms","start":"2026-02-18T22:55:20.964361Z","end":"2026-02-18T22:55:21.176658Z","steps":["trace[430319426] 'process raft request' (duration: 211.812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.176743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.115339ms","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-18T22:55:21.176782Z","caller":"traceutil/trace.go:171","msg":"trace[1739141843] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:706; }","duration":"243.16354ms","start":"2026-02-18T22:55:20.933592Z","end":"2026-02-18T22:55:21.176756Z","steps":["trace[1739141843] 'agreement among raft nodes before linearized reading' (duration: 243.129049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.176891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620972735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:21.176904Z","caller":"traceutil/trace.go:171","msg":"trace[37669020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"1.621043196s","start":"2026-02-18T22:55:19.555856Z","end":"2026-02-18T22:55:21.1769Z","steps":["trace[37669020] 'agreement among raft nodes before linearized reading' (duration: 1.620990845s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.176916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.555829Z","time spent":"1.621083277s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:21.176997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621790062s","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-18T22:55:21.177008Z","caller":"traceutil/trace.go:171","msg":"trace[1196934208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:706; }","duration":"1.621817992s","start":"2026-02-18T22:55:19.555187Z","end":"2026-02-18T22:55:21.177005Z","steps":["trace[1196934208] 'agreement among raft nodes before linearized reading' (duration: 1.621778361s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.17702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.555179Z","time spent":"1.621837544s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:21.177103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68534835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-18T22:55:21.177117Z","caller":"traceutil/trace.go:171","msg":"trace[589413595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:706; }","duration":"1.685372192s","start":"2026-02-18T22:55:19.491739Z","end":"2026-02-18T22:55:21.177111Z","steps":["trace[589413595] 'agreement among raft nodes before linearized reading' (duration: 1.685332791s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.177128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.491733Z","time spent":"1.685391632s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:21.177205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685707069s","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-18T22:55:21.177216Z","caller":"traceutil/trace.go:171","msg":"trace[646223328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:706; }","duration":"1.68575004s","start":"2026-02-18T22:55:19.491462Z","end":"2026-02-18T22:55:21.177212Z","steps":["trace[646223328] 'agreement among raft nodes before linearized reading' (duration: 1.685713289s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.177229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.491444Z","time spent":"1.685782951s","remote":"127.0.0.1:52448","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-18T22:55:27.803021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142197,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:28.30382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142197,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:28.433869Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172129186s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:28.434355Z","caller":"traceutil/trace.go:171","msg":"trace[1761426379] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"1.195295102s","start":"2026-02-18T22:55:27.239022Z","end":"2026-02-18T22:55:28.434317Z","steps":["trace[1761426379] 'process raft request' (duration: 1.195129538s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.434684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.239007Z","time spent":"1.195585448s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:28.467157Z","caller":"traceutil/trace.go:171","msg":"trace[449339101] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"1.16488298s","start":"2026-02-18T22:55:27.302253Z","end":"2026-02-18T22:55:28.467136Z","steps":["trace[449339101] 'read index received' (duration: 1.132508558s)","trace[449339101] 'applied index is now lower than readState.Index' (duration: 32.373582ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:28.467411Z","caller":"traceutil/trace.go:171","msg":"trace[1512293932] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"917.630374ms","start":"2026-02-18T22:55:27.549774Z","end":"2026-02-18T22:55:28.467404Z","steps":["trace[1512293932] 'process raft request' (duration: 917.267255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.467492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.549754Z","time spent":"917.677705ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:28.467642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165384782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:28.467668Z","caller":"traceutil/trace.go:171","msg":"trace[837514609] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:747; }","duration":"1.165487574s","start":"2026-02-18T22:55:27.302174Z","end":"2026-02-18T22:55:28.467661Z","steps":["trace[837514609] 'agreement among raft nodes before linearized reading' (duration: 1.165439713s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.467688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.302147Z","time spent":"1.165536275s","remote":"127.0.0.1:52576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:28.467849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142567844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:28.467866Z","caller":"traceutil/trace.go:171","msg":"trace[1276668573] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:747; }","duration":"1.142611995s","start":"2026-02-18T22:55:27.325249Z","end":"2026-02-18T22:55:28.467861Z","steps":["trace[1276668573] 'agreement among raft nodes before linearized reading' (duration: 1.142534864s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.467881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.325241Z","time spent":"1.142637146s","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-02-18T22:55:28.46814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080859805s","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-18T22:55:28.468163Z","caller":"traceutil/trace.go:171","msg":"trace[812893253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.080890846s","start":"2026-02-18T22:55:27.387266Z","end":"2026-02-18T22:55:28.468157Z","steps":["trace[812893253] 'agreement among raft nodes before linearized reading' (duration: 1.080831274s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.46818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.387262Z","time spent":"1.080913476s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:28.468278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082092851s","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-18T22:55:28.4683Z","caller":"traceutil/trace.go:171","msg":"trace[1015318864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.082120541s","start":"2026-02-18T22:55:27.386172Z","end":"2026-02-18T22:55:28.468292Z","steps":["trace[1015318864] 'agreement among raft nodes before linearized reading' (duration: 1.08206883s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.468318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.386167Z","time spent":"1.082146242s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:28.468417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110632472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:28.468433Z","caller":"traceutil/trace.go:171","msg":"trace[1601224349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"1.110667392s","start":"2026-02-18T22:55:27.35776Z","end":"2026-02-18T22:55:28.468428Z","steps":["trace[1601224349] 'agreement among raft nodes before linearized reading' (duration: 1.110619041s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.468447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.357755Z","time spent":"1.110688533s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:28.6325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.215303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:28.632593Z","caller":"traceutil/trace.go:171","msg":"trace[1397171617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"158.334816ms","start":"2026-02-18T22:55:28.474235Z","end":"2026-02-18T22:55:28.63257Z","steps":["trace[1397171617] 'range keys from in-memory index tree' (duration: 158.155971ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:28.728055Z","caller":"traceutil/trace.go:171","msg":"trace[291394148] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"245.913239ms","start":"2026-02-18T22:55:28.48212Z","end":"2026-02-18T22:55:28.728033Z","steps":["trace[291394148] 'process raft request' (duration: 245.879788ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:28.728401Z","caller":"traceutil/trace.go:171","msg":"trace[1178531410] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"251.429527ms","start":"2026-02-18T22:55:28.476964Z","end":"2026-02-18T22:55:28.728393Z","steps":["trace[1178531410] 'process raft request' (duration: 250.819404ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:28.728488Z","caller":"traceutil/trace.go:171","msg":"trace[623951858] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"247.78587ms","start":"2026-02-18T22:55:28.480697Z","end":"2026-02-18T22:55:28.728482Z","steps":["trace[623951858] 'process raft request' (duration: 247.279319ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:28.878582Z","caller":"traceutil/trace.go:171","msg":"trace[850977395] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:773; }","duration":"226.473822ms","start":"2026-02-18T22:55:28.652082Z","end":"2026-02-18T22:55:28.878556Z","steps":["trace[850977395] 'read index received' (duration: 75.712299ms)","trace[850977395] 'applied index is now lower than readState.Index' (duration: 150.760753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:28.878792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.682407ms","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-18T22:55:28.878777Z","caller":"traceutil/trace.go:171","msg":"trace[233113437] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"243.956697ms","start":"2026-02-18T22:55:28.634792Z","end":"2026-02-18T22:55:28.878749Z","steps":["trace[233113437] 'process raft request' (duration: 159.694116ms)","trace[233113437] 'compare' (duration: 83.971635ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:28.878819Z","caller":"traceutil/trace.go:171","msg":"trace[713540983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"226.76161ms","start":"2026-02-18T22:55:28.652049Z","end":"2026-02-18T22:55:28.878811Z","steps":["trace[713540983] 'agreement among raft nodes before linearized reading' (duration: 226.603066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:29.296272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.796672ms","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-18T22:55:29.296417Z","caller":"traceutil/trace.go:171","msg":"trace[694135849] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:751; }","duration":"213.966907ms","start":"2026-02-18T22:55:29.082428Z","end":"2026-02-18T22:55:29.296395Z","steps":["trace[694135849] 'range keys from in-memory index tree' (duration: 213.770392ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:29.29655Z","caller":"traceutil/trace.go:171","msg":"trace[573833417] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"415.667139ms","start":"2026-02-18T22:55:28.880855Z","end":"2026-02-18T22:55:29.296522Z","steps":["trace[573833417] 'read index received' (duration: 363.311239ms)","trace[573833417] 'applied index is now lower than readState.Index' (duration: 52.35484ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:29.296694Z","caller":"traceutil/trace.go:171","msg":"trace[369421652] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"502.030477ms","start":"2026-02-18T22:55:28.794647Z","end":"2026-02-18T22:55:29.296678Z","steps":["trace[369421652] 'process raft request' (duration: 449.510213ms)","trace[369421652] 'compare' (duration: 52.183806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:29.296727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.851703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-18T22:55:29.296761Z","caller":"traceutil/trace.go:171","msg":"trace[636890966] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:752; }","duration":"415.928785ms","start":"2026-02-18T22:55:28.880821Z","end":"2026-02-18T22:55:29.29675Z","steps":["trace[636890966] 'agreement among raft nodes before linearized reading' (duration: 415.806353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:29.296788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:28.794617Z","time spent":"502.114729ms","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:55:29.296794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:28.880808Z","time spent":"415.977536ms","remote":"127.0.0.1:52358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-18T22:55:29.491845Z","caller":"traceutil/trace.go:171","msg":"trace[1676631950] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"192.799613ms","start":"2026-02-18T22:55:29.299024Z","end":"2026-02-18T22:55:29.491824Z","steps":["trace[1676631950] 'read index received' (duration: 105.197831ms)","trace[1676631950] 'applied index is now lower than readState.Index' (duration: 87.601212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:29.492006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.957936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:29.492023Z","caller":"traceutil/trace.go:171","msg":"trace[1773299372] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:753; }","duration":"193.007387ms","start":"2026-02-18T22:55:29.29901Z","end":"2026-02-18T22:55:29.492017Z","steps":["trace[1773299372] 'agreement among raft nodes before linearized reading' (duration: 192.886275ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:29.492182Z","caller":"traceutil/trace.go:171","msg":"trace[588169164] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"609.950043ms","start":"2026-02-18T22:55:28.882226Z","end":"2026-02-18T22:55:29.492176Z","steps":["trace[588169164] 'process raft request' (duration: 522.004613ms)","trace[588169164] 'compare' (duration: 87.441609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:29.492233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:28.882207Z","time spent":"609.988584ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:42.782113Z","caller":"traceutil/trace.go:171","msg":"trace[1251406726] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"275.814788ms","start":"2026-02-18T22:55:42.506281Z","end":"2026-02-18T22:55:42.782096Z","steps":["trace[1251406726] 'process raft request' (duration: 275.730507ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:43.809178Z","caller":"traceutil/trace.go:171","msg":"trace[232130874] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"245.198153ms","start":"2026-02-18T22:55:43.563971Z","end":"2026-02-18T22:55:43.809169Z","steps":["trace[232130874] 'process raft request' (duration: 244.933917ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:43.809011Z","caller":"traceutil/trace.go:171","msg":"trace[1567574292] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"213.350972ms","start":"2026-02-18T22:55:43.595643Z","end":"2026-02-18T22:55:43.808994Z","steps":["trace[1567574292] 'read index received' (duration: 213.22614ms)","trace[1567574292] 'applied index is now lower than readState.Index' (duration: 124.312µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:43.809682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.027136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:43.810436Z","caller":"traceutil/trace.go:171","msg":"trace[782717105] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:797; }","duration":"214.821174ms","start":"2026-02-18T22:55:43.595605Z","end":"2026-02-18T22:55:43.810426Z","steps":["trace[782717105] 'agreement among raft nodes before linearized reading' (duration: 214.031677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:43.856141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.52232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:43.856395Z","caller":"traceutil/trace.go:171","msg":"trace[874115344] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:797; }","duration":"129.869947ms","start":"2026-02-18T22:55:43.726498Z","end":"2026-02-18T22:55:43.856368Z","steps":["trace[874115344] 'agreement among raft nodes before linearized reading' (duration: 129.57099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:45.364781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142348,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:55:45.563622Z","caller":"traceutil/trace.go:171","msg":"trace[1181917473] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"699.18206ms","start":"2026-02-18T22:55:44.864418Z","end":"2026-02-18T22:55:45.5636Z","steps":["trace[1181917473] 'read index received' (duration: 698.071017ms)","trace[1181917473] 'applied index is now lower than readState.Index' (duration: 1.110293ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:45.563785Z","caller":"traceutil/trace.go:171","msg":"trace[1638902253] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"722.370716ms","start":"2026-02-18T22:55:44.841407Z","end":"2026-02-18T22:55:45.563777Z","steps":["trace[1638902253] 'process raft request' (duration: 721.14464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:45.563864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:44.841394Z","time spent":"722.413546ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:45.56403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.61163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:45.564051Z","caller":"traceutil/trace.go:171","msg":"trace[764013356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"699.662951ms","start":"2026-02-18T22:55:44.864382Z","end":"2026-02-18T22:55:45.564045Z","steps":["trace[764013356] 'agreement among raft nodes before linearized reading' (duration: 699.58589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:45.56407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:44.864369Z","time spent":"699.696132ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:45.567942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.040079ms","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-18T22:55:45.568007Z","caller":"traceutil/trace.go:171","msg":"trace[649294845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"688.865061ms","start":"2026-02-18T22:55:44.879133Z","end":"2026-02-18T22:55:45.567998Z","steps":["trace[649294845] 'agreement among raft nodes before linearized reading' (duration: 685.033699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:45.568048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:44.879119Z","time spent":"688.920101ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T22:55:46.201726Z","caller":"traceutil/trace.go:171","msg":"trace[76049100] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"389.499071ms","start":"2026-02-18T22:55:45.812199Z","end":"2026-02-18T22:55:46.201698Z","steps":["trace[76049100] 'read index received' (duration: 328.886215ms)","trace[76049100] 'applied index is now lower than readState.Index' (duration: 60.611846ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:46.201919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.701505ms","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-18T22:55:46.201958Z","caller":"traceutil/trace.go:171","msg":"trace[1732039510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"389.751186ms","start":"2026-02-18T22:55:45.812194Z","end":"2026-02-18T22:55:46.201945Z","steps":["trace[1732039510] 'agreement among raft nodes before linearized reading' (duration: 389.594073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:46.201992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:45.812176Z","time spent":"389.808458ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:55:46.202347Z","caller":"traceutil/trace.go:171","msg":"trace[2128906334] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"619.560841ms","start":"2026-02-18T22:55:45.582772Z","end":"2026-02-18T22:55:46.202333Z","steps":["trace[2128906334] 'process raft request' (duration: 558.367603ms)","trace[2128906334] 'compare' (duration: 60.438412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:46.202433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:45.582762Z","time spent":"619.619572ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:47.226524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.849027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:47.226606Z","caller":"traceutil/trace.go:171","msg":"trace[218307209] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"747.449392ms","start":"2026-02-18T22:55:46.479142Z","end":"2026-02-18T22:55:47.226591Z","steps":["trace[218307209] 'read index received' (duration: 118.298689ms)","trace[218307209] 'applied index is now lower than readState.Index' (duration: 629.149463ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:47.226805Z","caller":"traceutil/trace.go:171","msg":"trace[21138076] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.009512685s","start":"2026-02-18T22:55:46.217279Z","end":"2026-02-18T22:55:47.226792Z","steps":["trace[21138076] 'process raft request' (duration: 380.331901ms)","trace[21138076] 'compare' (duration: 628.645613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:47.22686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:46.217259Z","time spent":"1.009572777s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:47.227023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.878081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:47.22705Z","caller":"traceutil/trace.go:171","msg":"trace[593262807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"747.925012ms","start":"2026-02-18T22:55:46.479116Z","end":"2026-02-18T22:55:47.227041Z","steps":["trace[593262807] 'agreement among raft nodes before linearized reading' (duration: 747.883301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:47.227076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:46.479103Z","time spent":"747.966953ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:55:47.227255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.782632ms","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-18T22:55:47.227283Z","caller":"traceutil/trace.go:171","msg":"trace[1235630002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"416.860683ms","start":"2026-02-18T22:55:46.810414Z","end":"2026-02-18T22:55:47.227275Z","steps":["trace[1235630002] 'agreement among raft nodes before linearized reading' (duration: 416.767881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:47.227307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:46.810395Z","time spent":"416.906055ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:55:47.675073Z","caller":"traceutil/trace.go:171","msg":"trace[23464248] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"348.3855ms","start":"2026-02-18T22:55:47.326667Z","end":"2026-02-18T22:55:47.675053Z","steps":["trace[23464248] 'read index received' (duration: 348.215327ms)","trace[23464248] 'applied index is now lower than readState.Index' (duration: 169.403µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:47.675649Z","caller":"traceutil/trace.go:171","msg":"trace[642532425] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"427.050292ms","start":"2026-02-18T22:55:47.248585Z","end":"2026-02-18T22:55:47.675635Z","steps":["trace[642532425] 'process raft request' (duration: 426.341578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:47.676418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:47.248569Z","time spent":"427.784889ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:47.676889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.21721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:47.677184Z","caller":"traceutil/trace.go:171","msg":"trace[14903882] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:803; }","duration":"350.532226ms","start":"2026-02-18T22:55:47.326634Z","end":"2026-02-18T22:55:47.677166Z","steps":["trace[14903882] 'agreement among raft nodes before linearized reading' (duration: 350.167548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:47.677414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:47.326622Z","time spent":"350.777411ms","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-02-18T22:55:47.677908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.394054ms","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-18T22:55:47.677964Z","caller":"traceutil/trace.go:171","msg":"trace[1029421935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"105.598109ms","start":"2026-02-18T22:55:47.572354Z","end":"2026-02-18T22:55:47.677952Z","steps":["trace[1029421935] 'agreement among raft nodes before linearized reading' (duration: 105.313433ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:47.863762Z","caller":"traceutil/trace.go:171","msg":"trace[1629330744] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"180.896658ms","start":"2026-02-18T22:55:47.682844Z","end":"2026-02-18T22:55:47.863741Z","steps":["trace[1629330744] 'process raft request' (duration: 180.827327ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:47.867572Z","caller":"traceutil/trace.go:171","msg":"trace[87616991] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"185.672681ms","start":"2026-02-18T22:55:47.681878Z","end":"2026-02-18T22:55:47.867551Z","steps":["trace[87616991] 'process raft request' (duration: 180.4781ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:47.87182Z","caller":"traceutil/trace.go:171","msg":"trace[936981773] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"135.839505ms","start":"2026-02-18T22:55:47.735957Z","end":"2026-02-18T22:55:47.871797Z","steps":["trace[936981773] 'process raft request' (duration: 135.694312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.017542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.419413ms","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-18T22:55:52.017627Z","caller":"traceutil/trace.go:171","msg":"trace[1069049968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"126.609507ms","start":"2026-02-18T22:55:51.890997Z","end":"2026-02-18T22:55:52.017607Z","steps":["trace[1069049968] 'range keys from in-memory index tree' (duration: 125.952993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.017883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.762795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:52.0183Z","caller":"traceutil/trace.go:171","msg":"trace[931399289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"124.219735ms","start":"2026-02-18T22:55:51.894058Z","end":"2026-02-18T22:55:52.018278Z","steps":["trace[931399289] 'range keys from in-memory index tree' (duration: 123.291366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:03.466143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:03.966378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:04.453789Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.660965055s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:56:04.55381Z","caller":"traceutil/trace.go:171","msg":"trace[110413029] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"1.587687671s","start":"2026-02-18T22:56:02.966091Z","end":"2026-02-18T22:56:04.553779Z","steps":["trace[110413029] 'read index received' (duration: 1.487840253s)","trace[110413029] 'applied index is now lower than readState.Index' (duration: 99.845658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:04.554028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587901485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:04.554052Z","caller":"traceutil/trace.go:171","msg":"trace[1183333218] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:900; }","duration":"1.588001268s","start":"2026-02-18T22:56:02.966042Z","end":"2026-02-18T22:56:04.554043Z","steps":["trace[1183333218] 'agreement among raft nodes before linearized reading' (duration: 1.587907446s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.554083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:02.966019Z","time spent":"1.588053879s","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:04.554098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178710132s","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-18T22:56:04.554168Z","caller":"traceutil/trace.go:171","msg":"trace[2064203584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"1.178821214s","start":"2026-02-18T22:56:03.375327Z","end":"2026-02-18T22:56:04.554148Z","steps":["trace[2064203584] 'agreement among raft nodes before linearized reading' (duration: 1.17863545s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.554198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:03.375309Z","time spent":"1.178884196s","remote":"127.0.0.1:52448","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-18T22:56:04.554388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.446501ms","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-18T22:56:04.554404Z","caller":"traceutil/trace.go:171","msg":"trace[1400702628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"227.492042ms","start":"2026-02-18T22:56:04.326907Z","end":"2026-02-18T22:56:04.554399Z","steps":["trace[1400702628] 'agreement among raft nodes before linearized reading' (duration: 227.436231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.554443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.046766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:56:04.554464Z","caller":"traceutil/trace.go:171","msg":"trace[1325009797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"469.100687ms","start":"2026-02-18T22:56:04.085357Z","end":"2026-02-18T22:56:04.554458Z","steps":["trace[1325009797] 'agreement among raft nodes before linearized reading' (duration: 468.995654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.554481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.085339Z","time spent":"469.138738ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:04.554515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.21092ms","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-18T22:56:04.554536Z","caller":"traceutil/trace.go:171","msg":"trace[1181285099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"715.260521ms","start":"2026-02-18T22:56:03.83927Z","end":"2026-02-18T22:56:04.554531Z","steps":["trace[1181285099] 'agreement among raft nodes before linearized reading' (duration: 715.20304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.554552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:03.839256Z","time spent":"715.290901ms","remote":"127.0.0.1:52448","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-18T22:56:04.554622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.290517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:04.554641Z","caller":"traceutil/trace.go:171","msg":"trace[719966344] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:900; }","duration":"254.373028ms","start":"2026-02-18T22:56:04.300263Z","end":"2026-02-18T22:56:04.554636Z","steps":["trace[719966344] 'agreement among raft nodes before linearized reading' (duration: 254.298857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.55466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088536743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:04.554681Z","caller":"traceutil/trace.go:171","msg":"trace[34671359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"1.088582134s","start":"2026-02-18T22:56:03.466092Z","end":"2026-02-18T22:56:04.554675Z","steps":["trace[34671359] 'agreement among raft nodes before linearized reading' (duration: 1.088546433s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.554698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:03.466079Z","time spent":"1.088615004s","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:04.554771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.185118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:56:04.55479Z","caller":"traceutil/trace.go:171","msg":"trace[570564541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"327.232428ms","start":"2026-02-18T22:56:04.227553Z","end":"2026-02-18T22:56:04.554785Z","steps":["trace[570564541] 'agreement among raft nodes before linearized reading' (duration: 327.168707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.554803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.227541Z","time spent":"327.259559ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:56:04.666677Z","caller":"traceutil/trace.go:171","msg":"trace[2111405212] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"106.00142ms","start":"2026-02-18T22:56:04.56066Z","end":"2026-02-18T22:56:04.666662Z","steps":["trace[2111405212] 'process raft request' (duration: 105.906288ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:04.666955Z","caller":"traceutil/trace.go:171","msg":"trace[1203549697] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:935; }","duration":"105.427388ms","start":"2026-02-18T22:56:04.561522Z","end":"2026-02-18T22:56:04.66695Z","steps":["trace[1203549697] 'read index received' (duration: 105.425238ms)","trace[1203549697] 'applied index is now lower than readState.Index' (duration: 1.7µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:04.666998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.463199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:04.667012Z","caller":"traceutil/trace.go:171","msg":"trace[1352029114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"105.50703ms","start":"2026-02-18T22:56:04.561501Z","end":"2026-02-18T22:56:04.667008Z","steps":["trace[1352029114] 'agreement among raft nodes before linearized reading' (duration: 105.469099ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:05.039496Z","caller":"traceutil/trace.go:171","msg":"trace[831506240] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:935; }","duration":"366.147302ms","start":"2026-02-18T22:56:04.673331Z","end":"2026-02-18T22:56:05.039478Z","steps":["trace[831506240] 'read index received' (duration: 365.911087ms)","trace[831506240] 'applied index is now lower than readState.Index' (duration: 235.595µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:05.03962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.272214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:05.039643Z","caller":"traceutil/trace.go:171","msg":"trace[518751910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"366.323726ms","start":"2026-02-18T22:56:04.67331Z","end":"2026-02-18T22:56:05.039634Z","steps":["trace[518751910] 'agreement among raft nodes before linearized reading' (duration: 366.234354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.039664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.673301Z","time spent":"366.358396ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:56:05.03992Z","caller":"traceutil/trace.go:171","msg":"trace[551985718] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"478.346615ms","start":"2026-02-18T22:56:04.561566Z","end":"2026-02-18T22:56:05.039912Z","steps":["trace[551985718] 'process raft request' (duration: 477.738712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.039981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.561559Z","time spent":"478.379165ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:05.040088Z","caller":"traceutil/trace.go:171","msg":"trace[674833305] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"478.414926ms","start":"2026-02-18T22:56:04.561665Z","end":"2026-02-18T22:56:05.04008Z","steps":["trace[674833305] 'process raft request' (duration: 477.749262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.040121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.561661Z","time spent":"478.440917ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:05.040197Z","caller":"traceutil/trace.go:171","msg":"trace[742443925] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"474.83929ms","start":"2026-02-18T22:56:04.565352Z","end":"2026-02-18T22:56:05.040191Z","steps":["trace[742443925] 'process raft request' (duration: 474.088374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.040228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.565345Z","time spent":"474.86583ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:05.040309Z","caller":"traceutil/trace.go:171","msg":"trace[1414442371] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"473.422269ms","start":"2026-02-18T22:56:04.56688Z","end":"2026-02-18T22:56:05.040302Z","steps":["trace[1414442371] 'process raft request' (duration: 472.579811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:05.040339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.566873Z","time spent":"473.449749ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:08.85956Z","caller":"traceutil/trace.go:171","msg":"trace[1983233655] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"178.402411ms","start":"2026-02-18T22:56:08.681127Z","end":"2026-02-18T22:56:08.85953Z","steps":["trace[1983233655] 'process raft request' (duration: 178.232817ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:09.348016Z","caller":"traceutil/trace.go:171","msg":"trace[397775170] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"241.921402ms","start":"2026-02-18T22:56:09.106077Z","end":"2026-02-18T22:56:09.347998Z","steps":["trace[397775170] 'process raft request' (duration: 241.800219ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:09.487243Z","caller":"traceutil/trace.go:171","msg":"trace[1080179624] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"376.375541ms","start":"2026-02-18T22:56:09.110854Z","end":"2026-02-18T22:56:09.487229Z","steps":["trace[1080179624] 'process raft request' (duration: 376.35754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.487328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:09.110841Z","time spent":"376.441532ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:09.487484Z","caller":"traceutil/trace.go:171","msg":"trace[1352873223] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"381.055411ms","start":"2026-02-18T22:56:09.106423Z","end":"2026-02-18T22:56:09.487478Z","steps":["trace[1352873223] 'process raft request' (duration: 352.180222ms)","trace[1352873223] 'compare' (duration: 28.360538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:09.487511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:09.106416Z","time spent":"381.079612ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:09.487572Z","caller":"traceutil/trace.go:171","msg":"trace[457506486] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"379.777704ms","start":"2026-02-18T22:56:09.10779Z","end":"2026-02-18T22:56:09.487568Z","steps":["trace[457506486] 'process raft request' (duration: 379.397136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.487594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:09.107783Z","time spent":"379.798874ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:09.981172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.505808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:09.981226Z","caller":"traceutil/trace.go:171","msg":"trace[1353285760] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:948; }","duration":"529.837817ms","start":"2026-02-18T22:56:09.451379Z","end":"2026-02-18T22:56:09.981216Z","steps":["trace[1353285760] 'read index received' (duration: 7.230414ms)","trace[1353285760] 'applied index is now lower than readState.Index' (duration: 522.606813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:09.98128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.906489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:09.981291Z","caller":"traceutil/trace.go:171","msg":"trace[1357033298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"529.94107ms","start":"2026-02-18T22:56:09.451346Z","end":"2026-02-18T22:56:09.981287Z","steps":["trace[1357033298] 'agreement among raft nodes before linearized reading' (duration: 529.903619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:09.981305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:09.451322Z","time spent":"529.98033ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:56:09.981447Z","caller":"traceutil/trace.go:171","msg":"trace[1117755716] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"621.988211ms","start":"2026-02-18T22:56:09.359453Z","end":"2026-02-18T22:56:09.981441Z","steps":["trace[1117755716] 'process raft request' (duration: 510.166876ms)","trace[1117755716] 'compare' (duration: 111.439127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:09.981498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:09.359421Z","time spent":"622.052713ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:11.116119Z","caller":"traceutil/trace.go:171","msg":"trace[241259784] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"251.933425ms","start":"2026-02-18T22:56:10.864159Z","end":"2026-02-18T22:56:11.116093Z","steps":["trace[241259784] 'process raft request' (duration: 251.834023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:11.95407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:12.458157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389694882935142628,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:12.655493Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004091205s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:56:12.655764Z","caller":"traceutil/trace.go:171","msg":"trace[501078495] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"1.215749229s","start":"2026-02-18T22:56:11.44Z","end":"2026-02-18T22:56:12.655749Z","steps":["trace[501078495] 'process raft request' (duration: 1.215633896s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.655847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.439988Z","time spent":"1.21580723s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:13.454046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000577577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T22:56:13.45413Z","caller":"traceutil/trace.go:171","msg":"trace[1289088363] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000681299s","start":"2026-02-18T22:56:11.453421Z","end":"2026-02-18T22:56:13.454102Z","steps":["trace[1289088363] 'agreement among raft nodes before linearized reading' (duration: 2.000590587s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:13.454178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.453413Z","time spent":"2.000755811s","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 22:56:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T22:56:14.066135Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.05712459s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:56:14.095194Z","caller":"traceutil/trace.go:171","msg":"trace[797557994] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"2.641732898s","start":"2026-02-18T22:56:11.453446Z","end":"2026-02-18T22:56:14.095179Z","steps":["trace[797557994] 'read index received' (duration: 1.202332601s)","trace[797557994] 'applied index is now lower than readState.Index' (duration: 1.439399617s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:14.095316Z","caller":"traceutil/trace.go:171","msg":"trace[1913528041] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"1.849044051s","start":"2026-02-18T22:56:12.246264Z","end":"2026-02-18T22:56:14.095308Z","steps":["trace[1913528041] 'process raft request' (duration: 1.819971119s)","trace[1913528041] 'compare' (duration: 28.50103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:14.095375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.246248Z","time spent":"1.849082981s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:14.264642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000242498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T22:56:14.264707Z","caller":"traceutil/trace.go:171","msg":"trace[1475510591] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000343591s","start":"2026-02-18T22:56:12.264348Z","end":"2026-02-18T22:56:14.264691Z","steps":["trace[1475510591] 'agreement among raft nodes before linearized reading' (duration: 2.000262079s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.264741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.264338Z","time spent":"2.000397512s","remote":"127.0.0.1:52164","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-18T22:56:14.344253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.864749ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T22:56:14.344327Z","caller":"traceutil/trace.go:171","msg":"trace[1867481837] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"249.095925ms","start":"2026-02-18T22:56:14.095217Z","end":"2026-02-18T22:56:14.344313Z","steps":["trace[1867481837] 'read index received' (duration: 85.310207ms)","trace[1867481837] 'applied index is now lower than readState.Index' (duration: 163.784698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:14.344439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.849741643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:56:14.344457Z","caller":"traceutil/trace.go:171","msg":"trace[1881022985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"2.849789234s","start":"2026-02-18T22:56:11.494662Z","end":"2026-02-18T22:56:14.344451Z","steps":["trace[1881022985] 'agreement among raft nodes before linearized reading' (duration: 2.849685212s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.344483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.49465Z","time spent":"2.849825545s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:14.344782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.488477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.344805Z","caller":"traceutil/trace.go:171","msg":"trace[916863468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"881.528298ms","start":"2026-02-18T22:56:13.463268Z","end":"2026-02-18T22:56:14.344797Z","steps":["trace[916863468] 'agreement among raft nodes before linearized reading' (duration: 881.495078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.344835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.463258Z","time spent":"881.573109ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:14.344984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.235475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18957939c6547da3\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T22:56:14.345008Z","caller":"traceutil/trace.go:171","msg":"trace[502003279] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18957939c6547da3; range_end:; response_count:1; response_revision:920; }","duration":"883.281326ms","start":"2026-02-18T22:56:13.461719Z","end":"2026-02-18T22:56:14.345Z","steps":["trace[502003279] 'agreement among raft nodes before linearized reading' (duration: 883.201554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.345026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.461707Z","time spent":"883.314437ms","remote":"127.0.0.1:52274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18957939c6547da3\" "} {"level":"warn","ts":"2026-02-18T22:56:14.345143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.392513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:14.345177Z","caller":"traceutil/trace.go:171","msg":"trace[1908742008] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:920; }","duration":"936.467245ms","start":"2026-02-18T22:56:13.408704Z","end":"2026-02-18T22:56:14.345171Z","steps":["trace[1908742008] 'agreement among raft nodes before linearized reading' (duration: 936.416784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.345196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.408682Z","time spent":"936.508286ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:14.345313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099428216s","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-18T22:56:14.345331Z","caller":"traceutil/trace.go:171","msg":"trace[1127202349] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:920; }","duration":"1.099481637s","start":"2026-02-18T22:56:13.245844Z","end":"2026-02-18T22:56:14.345326Z","steps":["trace[1127202349] 'agreement among raft nodes before linearized reading' (duration: 1.099440377s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.34535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.245832Z","time spent":"1.099511778s","remote":"127.0.0.1:52490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:14.345475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225866324s","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-18T22:56:14.345515Z","caller":"traceutil/trace.go:171","msg":"trace[497465245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"1.225907424s","start":"2026-02-18T22:56:13.119603Z","end":"2026-02-18T22:56:14.34551Z","steps":["trace[497465245] 'agreement among raft nodes before linearized reading' (duration: 1.225832083s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.345533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:13.119564Z","time spent":"1.225964746s","remote":"127.0.0.1:52448","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-18T22:56:14.345654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.843803376s","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-18T22:56:14.345671Z","caller":"traceutil/trace.go:171","msg":"trace[1274334585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"2.843838377s","start":"2026-02-18T22:56:11.501827Z","end":"2026-02-18T22:56:14.345666Z","steps":["trace[1274334585] 'agreement among raft nodes before linearized reading' (duration: 2.843788786s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.345689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.501818Z","time spent":"2.843866877s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:56:14.345803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.850759675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:56:14.345821Z","caller":"traceutil/trace.go:171","msg":"trace[1687421354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"2.850794146s","start":"2026-02-18T22:56:11.495022Z","end":"2026-02-18T22:56:14.345816Z","steps":["trace[1687421354] 'agreement among raft nodes before linearized reading' (duration: 2.850741695s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:14.345837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:11.495015Z","time spent":"2.850818926s","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:56:16.510666Z","caller":"traceutil/trace.go:171","msg":"trace[1425703014] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:963; }","duration":"126.630003ms","start":"2026-02-18T22:56:16.384019Z","end":"2026-02-18T22:56:16.510649Z","steps":["trace[1425703014] 'read index received' (duration: 37.769219ms)","trace[1425703014] 'applied index is now lower than readState.Index' (duration: 88.860043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:16.511198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.668434ms","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-18T22:56:16.511261Z","caller":"traceutil/trace.go:171","msg":"trace[686931087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"126.746665ms","start":"2026-02-18T22:56:16.384493Z","end":"2026-02-18T22:56:16.51124Z","steps":["trace[686931087] 'agreement among raft nodes before linearized reading' (duration: 126.523601ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:16.511477Z","caller":"traceutil/trace.go:171","msg":"trace[1924534688] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"127.471511ms","start":"2026-02-18T22:56:16.383987Z","end":"2026-02-18T22:56:16.511458Z","steps":["trace[1924534688] 'process raft request' (duration: 63.421689ms)","trace[1924534688] 'compare' (duration: 63.176543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:16.511663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.640374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:56:16.511682Z","caller":"traceutil/trace.go:171","msg":"trace[1953933895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"127.663764ms","start":"2026-02-18T22:56:16.38401Z","end":"2026-02-18T22:56:16.511673Z","steps":["trace[1953933895] 'agreement among raft nodes before linearized reading' (duration: 127.519991ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:18.755284Z","caller":"traceutil/trace.go:171","msg":"trace[430889824] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"303.667484ms","start":"2026-02-18T22:56:18.451598Z","end":"2026-02-18T22:56:18.755266Z","steps":["trace[430889824] 'read index received' (duration: 303.548142ms)","trace[430889824] 'applied index is now lower than readState.Index' (duration: 118.802µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:18.755467Z","caller":"traceutil/trace.go:171","msg":"trace[2070625287] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"328.511005ms","start":"2026-02-18T22:56:18.42695Z","end":"2026-02-18T22:56:18.755461Z","steps":["trace[2070625287] 'process raft request' (duration: 328.234989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.755527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:18.426942Z","time spent":"328.540197ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:18.755634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.039702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:18.755651Z","caller":"traceutil/trace.go:171","msg":"trace[499629765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"304.066693ms","start":"2026-02-18T22:56:18.451578Z","end":"2026-02-18T22:56:18.755645Z","steps":["trace[499629765] 'agreement among raft nodes before linearized reading' (duration: 304.031202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.755663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:18.451572Z","time spent":"304.088113ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:56:18.755782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.203026ms","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-18T22:56:18.755794Z","caller":"traceutil/trace.go:171","msg":"trace[1918678790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"241.235607ms","start":"2026-02-18T22:56:18.514555Z","end":"2026-02-18T22:56:18.75579Z","steps":["trace[1918678790] 'agreement among raft nodes before linearized reading' (duration: 241.179646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.75596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.688599ms","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-18T22:56:18.755973Z","caller":"traceutil/trace.go:171","msg":"trace[1210818155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"165.728119ms","start":"2026-02-18T22:56:18.590241Z","end":"2026-02-18T22:56:18.75597Z","steps":["trace[1210818155] 'agreement among raft nodes before linearized reading' (duration: 165.692628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:18.75605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.644001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:56:18.756061Z","caller":"traceutil/trace.go:171","msg":"trace[784383685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"168.681223ms","start":"2026-02-18T22:56:18.587376Z","end":"2026-02-18T22:56:18.756057Z","steps":["trace[784383685] 'agreement among raft nodes before linearized reading' (duration: 168.649852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:20.813979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.667328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:20.814034Z","caller":"traceutil/trace.go:171","msg":"trace[941096010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"308.752459ms","start":"2026-02-18T22:56:20.505269Z","end":"2026-02-18T22:56:20.814022Z","steps":["trace[941096010] 'range keys from in-memory index tree' (duration: 308.622717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:20.814059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:20.505255Z","time spent":"308.79802ms","remote":"127.0.0.1:52164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:56:21.075363Z","caller":"traceutil/trace.go:171","msg":"trace[81352793] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"254.01149ms","start":"2026-02-18T22:56:20.821331Z","end":"2026-02-18T22:56:21.075343Z","steps":["trace[81352793] 'read index received' (duration: 253.865047ms)","trace[81352793] 'applied index is now lower than readState.Index' (duration: 145.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:21.075582Z","caller":"traceutil/trace.go:171","msg":"trace[733782630] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"256.79426ms","start":"2026-02-18T22:56:20.818775Z","end":"2026-02-18T22:56:21.07557Z","steps":["trace[733782630] 'process raft request' (duration: 256.469303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:21.075812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.46343ms","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-18T22:56:21.075841Z","caller":"traceutil/trace.go:171","msg":"trace[1673330227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"254.515391ms","start":"2026-02-18T22:56:20.821317Z","end":"2026-02-18T22:56:21.075833Z","steps":["trace[1673330227] 'agreement among raft nodes before linearized reading' (duration: 254.403689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:21.07605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.310601ms","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-18T22:56:21.07608Z","caller":"traceutil/trace.go:171","msg":"trace[2009619776] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"222.362613ms","start":"2026-02-18T22:56:20.853709Z","end":"2026-02-18T22:56:21.076071Z","steps":["trace[2009619776] 'agreement among raft nodes before linearized reading' (duration: 222.25329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:21.076301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.224948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:56:21.076329Z","caller":"traceutil/trace.go:171","msg":"trace[1098711153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"221.267929ms","start":"2026-02-18T22:56:20.855053Z","end":"2026-02-18T22:56:21.076321Z","steps":["trace[1098711153] 'agreement among raft nodes before linearized reading' (duration: 221.192877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:21.076472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.429913ms","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-18T22:56:21.076495Z","caller":"traceutil/trace.go:171","msg":"trace[198433201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"221.472893ms","start":"2026-02-18T22:56:20.855015Z","end":"2026-02-18T22:56:21.076488Z","steps":["trace[198433201] 'agreement among raft nodes before linearized reading' (duration: 221.406792ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:21.599357Z","caller":"traceutil/trace.go:171","msg":"trace[1047138986] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"515.585723ms","start":"2026-02-18T22:56:21.083751Z","end":"2026-02-18T22:56:21.599337Z","steps":["trace[1047138986] 'process raft request' (duration: 479.107701ms)","trace[1047138986] 'compare' (duration: 36.363789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:21.599464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:21.083734Z","time spent":"515.673504ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:21.676001Z","caller":"traceutil/trace.go:171","msg":"trace[276107338] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:977; }","duration":"221.163827ms","start":"2026-02-18T22:56:21.45482Z","end":"2026-02-18T22:56:21.675984Z","steps":["trace[276107338] 'read index received' (duration: 108.046895ms)","trace[276107338] 'applied index is now lower than readState.Index' (duration: 113.116412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:21.676107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.267159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:21.676122Z","caller":"traceutil/trace.go:171","msg":"trace[1886216359] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:944; }","duration":"221.30201ms","start":"2026-02-18T22:56:21.454816Z","end":"2026-02-18T22:56:21.676118Z","steps":["trace[1886216359] 'agreement among raft nodes before linearized reading' (duration: 221.234678ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:21.676377Z","caller":"traceutil/trace.go:171","msg":"trace[735585679] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"591.967008ms","start":"2026-02-18T22:56:21.084388Z","end":"2026-02-18T22:56:21.676355Z","steps":["trace[735585679] 'process raft request' (duration: 591.419446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:21.676425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:21.084373Z","time spent":"592.01846ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:21.676501Z","caller":"traceutil/trace.go:171","msg":"trace[1956668517] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"592.054961ms","start":"2026-02-18T22:56:21.084442Z","end":"2026-02-18T22:56:21.676497Z","steps":["trace[1956668517] 'process raft request' (duration: 591.446157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:21.676531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:21.084436Z","time spent":"592.082801ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:21.676597Z","caller":"traceutil/trace.go:171","msg":"trace[2111487503] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"591.873898ms","start":"2026-02-18T22:56:21.084718Z","end":"2026-02-18T22:56:21.676592Z","steps":["trace[2111487503] 'process raft request' (duration: 591.188252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:21.676638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:21.084711Z","time spent":"591.895898ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:22.607959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.161363ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T22:56:22.608061Z","caller":"traceutil/trace.go:171","msg":"trace[278343187] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"154.820896ms","start":"2026-02-18T22:56:22.453222Z","end":"2026-02-18T22:56:22.608042Z","steps":["trace[278343187] 'read index received' (duration: 24.181µs)","trace[278343187] 'applied index is now lower than readState.Index' (duration: 154.795085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:22.608469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.231114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:22.608511Z","caller":"traceutil/trace.go:171","msg":"trace[366921742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"155.307846ms","start":"2026-02-18T22:56:22.453192Z","end":"2026-02-18T22:56:22.6085Z","steps":["trace[366921742] 'agreement among raft nodes before linearized reading' (duration: 155.219734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:27.495271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.687281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:56:27.495361Z","caller":"traceutil/trace.go:171","msg":"trace[1256329363] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:964; }","duration":"166.832104ms","start":"2026-02-18T22:56:27.328508Z","end":"2026-02-18T22:56:27.495341Z","steps":["trace[1256329363] 'range keys from in-memory index tree' (duration: 166.476716ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:27.723886Z","caller":"traceutil/trace.go:171","msg":"trace[2145413685] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"227.244967ms","start":"2026-02-18T22:56:27.496619Z","end":"2026-02-18T22:56:27.723864Z","steps":["trace[2145413685] 'read index received' (duration: 227.178985ms)","trace[2145413685] 'applied index is now lower than readState.Index' (duration: 64.952µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:27.723991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.357939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:27.724008Z","caller":"traceutil/trace.go:171","msg":"trace[1353414950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"227.399611ms","start":"2026-02-18T22:56:27.496603Z","end":"2026-02-18T22:56:27.724003Z","steps":["trace[1353414950] 'agreement among raft nodes before linearized reading' (duration: 227.35404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:27.972371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.475546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:27.972689Z","caller":"traceutil/trace.go:171","msg":"trace[459652758] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1004; }","duration":"185.192147ms","start":"2026-02-18T22:56:27.787481Z","end":"2026-02-18T22:56:27.972673Z","steps":["trace[459652758] 'read index received' (duration: 53.358963ms)","trace[459652758] 'applied index is now lower than readState.Index' (duration: 131.832234ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:27.972776Z","caller":"traceutil/trace.go:171","msg":"trace[809324218] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"247.125583ms","start":"2026-02-18T22:56:27.725639Z","end":"2026-02-18T22:56:27.972765Z","steps":["trace[809324218] 'process raft request' (duration: 115.187817ms)","trace[809324218] 'compare' (duration: 131.372794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:27.97319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.705587ms","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-18T22:56:27.973232Z","caller":"traceutil/trace.go:171","msg":"trace[1357060108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"185.748988ms","start":"2026-02-18T22:56:27.787472Z","end":"2026-02-18T22:56:27.973221Z","steps":["trace[1357060108] 'agreement among raft nodes before linearized reading' (duration: 185.603355ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:27.999787Z","caller":"traceutil/trace.go:171","msg":"trace[149176822] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"267.537851ms","start":"2026-02-18T22:56:27.732241Z","end":"2026-02-18T22:56:27.999779Z","steps":["trace[149176822] 'process raft request' (duration: 240.337378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:27.999844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.261038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:56:27.99986Z","caller":"traceutil/trace.go:171","msg":"trace[1079920257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"212.301928ms","start":"2026-02-18T22:56:27.787553Z","end":"2026-02-18T22:56:27.999855Z","steps":["trace[1079920257] 'agreement among raft nodes before linearized reading' (duration: 185.891381ms)","trace[1079920257] 'range keys from bolt db' (duration: 26.284225ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:27.999932Z","caller":"traceutil/trace.go:171","msg":"trace[2132079915] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"266.755324ms","start":"2026-02-18T22:56:27.733172Z","end":"2026-02-18T22:56:27.999927Z","steps":["trace[2132079915] 'process raft request' (duration: 239.468598ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:28.2045Z","caller":"traceutil/trace.go:171","msg":"trace[583590624] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"197.04363ms","start":"2026-02-18T22:56:28.007438Z","end":"2026-02-18T22:56:28.204482Z","steps":["trace[583590624] 'read index received' (duration: 113.835918ms)","trace[583590624] 'applied index is now lower than readState.Index' (duration: 83.207152ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:28.204579Z","caller":"traceutil/trace.go:171","msg":"trace[1413547086] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"197.305535ms","start":"2026-02-18T22:56:28.007267Z","end":"2026-02-18T22:56:28.204572Z","steps":["trace[1413547086] 'process raft request' (duration: 114.059332ms)","trace[1413547086] 'compare' (duration: 83.037439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:28.204728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.275125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-18T22:56:28.204745Z","caller":"traceutil/trace.go:171","msg":"trace[1854525627] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:969; }","duration":"197.314146ms","start":"2026-02-18T22:56:28.007426Z","end":"2026-02-18T22:56:28.20474Z","steps":["trace[1854525627] 'agreement among raft nodes before linearized reading' (duration: 197.126402ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:30.1611Z","caller":"traceutil/trace.go:171","msg":"trace[668594350] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1018; }","duration":"115.729468ms","start":"2026-02-18T22:56:30.045339Z","end":"2026-02-18T22:56:30.161069Z","steps":["trace[668594350] 'read index received' (duration: 16.382311ms)","trace[668594350] 'applied index is now lower than readState.Index' (duration: 99.346277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:30.161282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.925362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-2vbgt\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-18T22:56:30.161314Z","caller":"traceutil/trace.go:171","msg":"trace[1032150824] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-2vbgt; range_end:; response_count:1; response_revision:980; }","duration":"115.994354ms","start":"2026-02-18T22:56:30.04531Z","end":"2026-02-18T22:56:30.161304Z","steps":["trace[1032150824] 'agreement among raft nodes before linearized reading' (duration: 115.845211ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:30.16159Z","caller":"traceutil/trace.go:171","msg":"trace[2007332965] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"152.892234ms","start":"2026-02-18T22:56:30.008684Z","end":"2026-02-18T22:56:30.161576Z","steps":["trace[2007332965] 'process raft request' (duration: 113.775596ms)","trace[2007332965] 'compare' (duration: 38.312801ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:30.161825Z","caller":"traceutil/trace.go:171","msg":"trace[157759505] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"151.325751ms","start":"2026-02-18T22:56:30.010491Z","end":"2026-02-18T22:56:30.161816Z","steps":["trace[157759505] 'process raft request' (duration: 150.533583ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:31.342874Z","caller":"traceutil/trace.go:171","msg":"trace[1079929474] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"136.643877ms","start":"2026-02-18T22:56:31.206205Z","end":"2026-02-18T22:56:31.342849Z","steps":["trace[1079929474] 'process raft request' (duration: 136.521854ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:34.370772Z","caller":"traceutil/trace.go:171","msg":"trace[1346979699] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"182.682602ms","start":"2026-02-18T22:56:34.188066Z","end":"2026-02-18T22:56:34.370748Z","steps":["trace[1346979699] 'process raft request' (duration: 182.546299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:34.874949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.441274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:34.875102Z","caller":"traceutil/trace.go:171","msg":"trace[1813791887] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"350.406304ms","start":"2026-02-18T22:56:34.52468Z","end":"2026-02-18T22:56:34.875086Z","steps":["trace[1813791887] 'process raft request' (duration: 350.355443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:34.87516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:34.52466Z","time spent":"350.472126ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:34.875507Z","caller":"traceutil/trace.go:171","msg":"trace[2108078561] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"687.083225ms","start":"2026-02-18T22:56:34.18841Z","end":"2026-02-18T22:56:34.875494Z","steps":["trace[2108078561] 'process raft request' (duration: 478.023178ms)","trace[2108078561] 'compare' (duration: 208.130577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:34.875563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:34.188377Z","time spent":"687.157097ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:34.876464Z","caller":"traceutil/trace.go:171","msg":"trace[194794928] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"611.202061ms","start":"2026-02-18T22:56:34.265236Z","end":"2026-02-18T22:56:34.876438Z","steps":["trace[194794928] 'read index received' (duration: 106.042101ms)","trace[194794928] 'applied index is now lower than readState.Index' (duration: 505.1579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:34.877013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.768933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:56:34.87712Z","caller":"traceutil/trace.go:171","msg":"trace[1780544753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"611.898765ms","start":"2026-02-18T22:56:34.265207Z","end":"2026-02-18T22:56:34.877106Z","steps":["trace[1780544753] 'agreement among raft nodes before linearized reading' (duration: 611.705951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:34.879009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:34.265194Z","time spent":"613.802536ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:34.951812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.505787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:56:34.951859Z","caller":"traceutil/trace.go:171","msg":"trace[625090556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"597.590568ms","start":"2026-02-18T22:56:34.354257Z","end":"2026-02-18T22:56:34.951848Z","steps":["trace[625090556] 'agreement among raft nodes before linearized reading' (duration: 597.439645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:34.951883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:34.354242Z","time spent":"597.63642ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:34.952103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.273271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:34.952124Z","caller":"traceutil/trace.go:171","msg":"trace[488926808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"215.320812ms","start":"2026-02-18T22:56:34.736798Z","end":"2026-02-18T22:56:34.952119Z","steps":["trace[488926808] 'agreement among raft nodes before linearized reading' (duration: 215.25783ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:39.350309Z","caller":"traceutil/trace.go:171","msg":"trace[1914891488] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"229.003684ms","start":"2026-02-18T22:56:39.121284Z","end":"2026-02-18T22:56:39.350288Z","steps":["trace[1914891488] 'read index received' (duration: 228.814259ms)","trace[1914891488] 'applied index is now lower than readState.Index' (duration: 188.335µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:39.350455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.149647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:39.350478Z","caller":"traceutil/trace.go:171","msg":"trace[1489099653] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1042; }","duration":"229.210989ms","start":"2026-02-18T22:56:39.12126Z","end":"2026-02-18T22:56:39.350471Z","steps":["trace[1489099653] 'agreement among raft nodes before linearized reading' (duration: 229.135238ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:39.350785Z","caller":"traceutil/trace.go:171","msg":"trace[1578899022] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"232.660153ms","start":"2026-02-18T22:56:39.118118Z","end":"2026-02-18T22:56:39.350778Z","steps":["trace[1578899022] 'process raft request' (duration: 232.044699ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:42.408398Z","caller":"traceutil/trace.go:171","msg":"trace[411631086] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"160.037717ms","start":"2026-02-18T22:56:42.248338Z","end":"2026-02-18T22:56:42.408376Z","steps":["trace[411631086] 'read index received' (duration: 124.976997ms)","trace[411631086] 'applied index is now lower than readState.Index' (duration: 35.05979ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:42.408809Z","caller":"traceutil/trace.go:171","msg":"trace[1848852319] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"384.219279ms","start":"2026-02-18T22:56:42.024569Z","end":"2026-02-18T22:56:42.408788Z","steps":["trace[1848852319] 'process raft request' (duration: 348.785021ms)","trace[1848852319] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; req_size:516; } (duration: 34.878227ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:42.409057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:42.024553Z","time spent":"384.413373ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:42.44224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.273949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-18T22:56:42.442818Z","caller":"traceutil/trace.go:171","msg":"trace[1161276195] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1059; }","duration":"194.492235ms","start":"2026-02-18T22:56:42.248308Z","end":"2026-02-18T22:56:42.4428Z","steps":["trace[1161276195] 'agreement among raft nodes before linearized reading' (duration: 161.333066ms)","trace[1161276195] 'range keys from in-memory index tree' (duration: 31.812501ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:21.668703Z","caller":"traceutil/trace.go:171","msg":"trace[1795679703] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1268; }","duration":"261.667184ms","start":"2026-02-18T22:57:21.407019Z","end":"2026-02-18T22:57:21.668686Z","steps":["trace[1795679703] 'read index received' (duration: 259.979608ms)","trace[1795679703] 'applied index is now lower than readState.Index' (duration: 1.686486ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:21.669899Z","caller":"traceutil/trace.go:171","msg":"trace[1677126827] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"314.834718ms","start":"2026-02-18T22:57:21.355048Z","end":"2026-02-18T22:57:21.669882Z","steps":["trace[1677126827] 'process raft request' (duration: 313.558521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.670035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:21.355038Z","time spent":"314.915579ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:21.670246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.220517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:57:21.670285Z","caller":"traceutil/trace.go:171","msg":"trace[1714625048] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1220; }","duration":"263.290209ms","start":"2026-02-18T22:57:21.406982Z","end":"2026-02-18T22:57:21.670272Z","steps":["trace[1714625048] 'agreement among raft nodes before linearized reading' (duration: 263.210177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.670474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.052124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:57:21.670502Z","caller":"traceutil/trace.go:171","msg":"trace[1830784574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1220; }","duration":"218.104614ms","start":"2026-02-18T22:57:21.452389Z","end":"2026-02-18T22:57:21.670493Z","steps":["trace[1830784574] 'agreement among raft nodes before linearized reading' (duration: 218.061214ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:21.667146Z","caller":"traceutil/trace.go:171","msg":"trace[1747277532] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"313.324246ms","start":"2026-02-18T22:57:21.353799Z","end":"2026-02-18T22:57:21.667124Z","steps":["trace[1747277532] 'process raft request' (duration: 313.123672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:21.675683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:21.353779Z","time spent":"321.86979ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:57:23.899206Z","caller":"traceutil/trace.go:171","msg":"trace[31418973] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"213.752961ms","start":"2026-02-18T22:57:23.685436Z","end":"2026-02-18T22:57:23.899189Z","steps":["trace[31418973] 'process raft request' (duration: 213.540237ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:24.099111Z","caller":"traceutil/trace.go:171","msg":"trace[1211850047] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"380.881541ms","start":"2026-02-18T22:57:23.718213Z","end":"2026-02-18T22:57:24.099095Z","steps":["trace[1211850047] 'read index received' (duration: 181.040529ms)","trace[1211850047] 'applied index is now lower than readState.Index' (duration: 199.840362ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:24.099285Z","caller":"traceutil/trace.go:171","msg":"trace[701305696] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"411.850227ms","start":"2026-02-18T22:57:23.687426Z","end":"2026-02-18T22:57:24.099276Z","steps":["trace[701305696] 'process raft request' (duration: 409.278332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:24.099366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:23.68742Z","time spent":"411.883608ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:24.09955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.343932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-18T22:57:24.099571Z","caller":"traceutil/trace.go:171","msg":"trace[62333369] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1229; }","duration":"381.420404ms","start":"2026-02-18T22:57:23.718144Z","end":"2026-02-18T22:57:24.099565Z","steps":["trace[62333369] 'agreement among raft nodes before linearized reading' (duration: 381.332232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:24.099601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:23.718115Z","time spent":"381.479644ms","remote":"127.0.0.1:52448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T22:57:24.099741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.582259ms","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-18T22:57:24.099759Z","caller":"traceutil/trace.go:171","msg":"trace[2132888150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"376.61754ms","start":"2026-02-18T22:57:23.723135Z","end":"2026-02-18T22:57:24.099753Z","steps":["trace[2132888150] 'agreement among raft nodes before linearized reading' (duration: 376.541948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:24.099775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:23.723126Z","time spent":"376.64509ms","remote":"127.0.0.1:52448","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-18T22:57:24.099907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.853827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:57:24.099925Z","caller":"traceutil/trace.go:171","msg":"trace[1959135086] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1229; }","duration":"293.897347ms","start":"2026-02-18T22:57:23.806023Z","end":"2026-02-18T22:57:24.09992Z","steps":["trace[1959135086] 'agreement among raft nodes before linearized reading' (duration: 293.865197ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:48.801406Z","caller":"traceutil/trace.go:171","msg":"trace[745476668] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"108.846839ms","start":"2026-02-18T22:59:48.69254Z","end":"2026-02-18T22:59:48.801387Z","steps":["trace[745476668] 'process raft request' (duration: 108.726166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:49.087212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.280575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-02-18T22:59:49.087419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.431069ms","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-18T22:59:49.087479Z","caller":"traceutil/trace.go:171","msg":"trace[269232725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"196.564372ms","start":"2026-02-18T22:59:48.890895Z","end":"2026-02-18T22:59:49.087459Z","steps":["trace[269232725] 'range keys from in-memory index tree' (duration: 196.340507ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:49.08744Z","caller":"traceutil/trace.go:171","msg":"trace[1562511585] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1845; }","duration":"127.577781ms","start":"2026-02-18T22:59:48.959849Z","end":"2026-02-18T22:59:49.087427Z","steps":["trace[1562511585] 'range keys from in-memory index tree' (duration: 127.111291ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:49.087905Z","caller":"traceutil/trace.go:171","msg":"trace[842832224] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"122.737297ms","start":"2026-02-18T22:59:48.965159Z","end":"2026-02-18T22:59:49.087896Z","steps":["trace[842832224] 'process raft request' (duration: 113.742514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:49.087245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.018719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-18T22:59:49.088168Z","caller":"traceutil/trace.go:171","msg":"trace[352277797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1845; }","duration":"201.970268ms","start":"2026-02-18T22:59:48.886161Z","end":"2026-02-18T22:59:49.088132Z","steps":["trace[352277797] 'range keys from in-memory index tree' (duration: 200.435185ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:54.860703Z","caller":"traceutil/trace.go:171","msg":"trace[387426794] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"105.638409ms","start":"2026-02-18T22:59:54.755043Z","end":"2026-02-18T22:59:54.860682Z","steps":["trace[387426794] 'process raft request' (duration: 105.472265ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:54.869431Z","caller":"traceutil/trace.go:171","msg":"trace[1723253767] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"107.345497ms","start":"2026-02-18T22:59:54.762066Z","end":"2026-02-18T22:59:54.869411Z","steps":["trace[1723253767] 'process raft request' (duration: 107.206104ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:55.246475Z","caller":"traceutil/trace.go:171","msg":"trace[1313900899] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"118.911745ms","start":"2026-02-18T22:59:55.127542Z","end":"2026-02-18T22:59:55.246454Z","steps":["trace[1313900899] 'read index received' (duration: 118.724741ms)","trace[1313900899] 'applied index is now lower than readState.Index' (duration: 186.014µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:59:55.247239Z","caller":"traceutil/trace.go:171","msg":"trace[1372219451] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"120.642433ms","start":"2026-02-18T22:59:55.126581Z","end":"2026-02-18T22:59:55.247223Z","steps":["trace[1372219451] 'process raft request' (duration: 119.750063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:55.247512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.952688ms","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-18T22:59:55.247711Z","caller":"traceutil/trace.go:171","msg":"trace[683422283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1877; }","duration":"120.177532ms","start":"2026-02-18T22:59:55.127521Z","end":"2026-02-18T22:59:55.247698Z","steps":["trace[683422283] 'agreement among raft nodes before linearized reading' (duration: 119.875216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:55.247904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.235884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-02-18T22:59:55.248015Z","caller":"traceutil/trace.go:171","msg":"trace[1997012807] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1877; }","duration":"114.344176ms","start":"2026-02-18T22:59:55.133641Z","end":"2026-02-18T22:59:55.247985Z","steps":["trace[1997012807] 'agreement among raft nodes before linearized reading' (duration: 114.203253ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:55.377874Z","caller":"traceutil/trace.go:171","msg":"trace[1447376390] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"123.733038ms","start":"2026-02-18T22:59:55.254107Z","end":"2026-02-18T22:59:55.37784Z","steps":["trace[1447376390] 'process raft request' (duration: 101.248795ms)","trace[1447376390] 'compare' (duration: 22.376451ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:59:55.441331Z","caller":"traceutil/trace.go:171","msg":"trace[1025772375] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"186.18316ms","start":"2026-02-18T22:59:55.255125Z","end":"2026-02-18T22:59:55.441308Z","steps":["trace[1025772375] 'process raft request' (duration: 186.082258ms)"],"step_count":1}