{"level":"warn","ts":"2026-02-25T22:00:51.723988Z","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-25T22:00:51.724085Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-25T22:00:51.724167Z","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-25T22:00:51.724183Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-25T22:00:51.724204Z","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-25T22:00:51.724936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-02-25T22:00:51.725005Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-25T22:00:51.935308Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"210.113011ms"} {"level":"info","ts":"2026-02-25T22:00:52.484412Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-02-25T22:00:52.484525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T22:00:52.484548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-02-25T22:00:52.484567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T22:00:52.48458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-02-25T22:00:52.484621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-02-25T22:00:52.527052Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T22:00:52.558359Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T22:00:52.639128Z","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-25T22:00:52.736008Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T22:00:52.736279Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T22:00:52.736546Z","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-25T22:00:52.737171Z","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-25T22:00:52.737209Z","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-25T22:00:52.740461Z","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-25T22:00:52.740604Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-25T22:00:52.740729Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-25T22:00:52.740793Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T22:00:52.74075Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T22:00:52.9126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-02-25T22:00:52.912846Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-25T22:00:53.385793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T22:00:53.385862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T22:00:53.385902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-02-25T22:00:53.385981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-02-25T22:00:53.386002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-25T22:00:53.386018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-02-25T22:00:53.386031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-25T22:00:53.389483Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:00:53.400661Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T22:00:53.400728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T22:00:53.40065Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T22:00:53.401091Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T22:00:53.401178Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T22:00:53.403766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T22:00:53.40422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-02-25T22:00:53.446261Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:00:53.446354Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:00:53.446372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T22:00:55.412403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.311524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T22:00:55.412595Z","caller":"traceutil/trace.go:171","msg":"trace[791430225] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"279.741432ms","start":"2026-02-25T22:00:55.132838Z","end":"2026-02-25T22:00:55.412579Z","steps":["trace[791430225] 'process raft request' (duration: 279.6578ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:55.412685Z","caller":"traceutil/trace.go:171","msg":"trace[1792531231] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"283.198664ms","start":"2026-02-25T22:00:55.129391Z","end":"2026-02-25T22:00:55.41259Z","steps":["trace[1792531231] 'process raft request' (duration: 61.823091ms)","trace[1792531231] 'compare' (duration: 218.19151ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:00:55.41294Z","caller":"traceutil/trace.go:171","msg":"trace[937070737] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"267.478245ms","start":"2026-02-25T22:00:55.145438Z","end":"2026-02-25T22:00:55.412916Z","steps":["trace[937070737] 'process raft request' (duration: 267.113333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:55.79794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.380776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"warn","ts":"2026-02-25T22:00:55.798237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.535193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T22:00:55.798193Z","caller":"traceutil/trace.go:171","msg":"trace[746310234] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"381.613756ms","start":"2026-02-25T22:00:55.416536Z","end":"2026-02-25T22:00:55.79815Z","steps":["trace[746310234] 'process raft request' (duration: 119.798068ms)","trace[746310234] 'compare' (duration: 261.268633ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:00:55.79829Z","caller":"traceutil/trace.go:171","msg":"trace[877113345] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:52; }","duration":"285.640786ms","start":"2026-02-25T22:00:55.512629Z","end":"2026-02-25T22:00:55.79827Z","steps":["trace[877113345] 'agreement among raft nodes before linearized reading' (duration: 285.525202ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:55.798372Z","caller":"traceutil/trace.go:171","msg":"trace[47715152] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"381.805782ms","start":"2026-02-25T22:00:55.416538Z","end":"2026-02-25T22:00:55.798344Z","steps":["trace[47715152] 'process raft request' (duration: 381.505383ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:55.798404Z","caller":"traceutil/trace.go:171","msg":"trace[1271519162] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"308.833965ms","start":"2026-02-25T22:00:55.489543Z","end":"2026-02-25T22:00:55.798377Z","steps":["trace[1271519162] 'process raft request' (duration: 308.575357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:55.798386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:55.416518Z","time spent":"381.772141ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1059,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:00:55.798501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:55.416519Z","time spent":"381.922096ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:00:55.798493Z","caller":"traceutil/trace.go:171","msg":"trace[1516539834] linearizableReadLoop","detail":"{readStateIndex:57; appliedIndex:54; }","duration":"285.479321ms","start":"2026-02-25T22:00:55.512671Z","end":"2026-02-25T22:00:55.79815Z","steps":["trace[1516539834] 'read index received' (duration: 23.672124ms)","trace[1516539834] 'applied index is now lower than readState.Index' (duration: 261.806337ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:55.798509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:55.489526Z","time spent":"308.938008ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:00:56.083102Z","caller":"traceutil/trace.go:171","msg":"trace[1556410113] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"281.083503ms","start":"2026-02-25T22:00:55.801973Z","end":"2026-02-25T22:00:56.083056Z","steps":["trace[1556410113] 'process raft request' (duration: 240.287448ms)","trace[1556410113] 'compare' (duration: 40.430365ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:00:56.085102Z","caller":"traceutil/trace.go:171","msg":"trace[2041486218] linearizableReadLoop","detail":"{readStateIndex:60; appliedIndex:57; }","duration":"282.00766ms","start":"2026-02-25T22:00:55.803062Z","end":"2026-02-25T22:00:56.085069Z","steps":["trace[2041486218] 'read index received' (duration: 239.116562ms)","trace[2041486218] 'applied index is now lower than readState.Index' (duration: 42.890058ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:00:56.085231Z","caller":"traceutil/trace.go:171","msg":"trace[1817924952] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"282.601307ms","start":"2026-02-25T22:00:55.802615Z","end":"2026-02-25T22:00:56.085217Z","steps":["trace[1817924952] 'process raft request' (duration: 282.364599ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:56.085193Z","caller":"traceutil/trace.go:171","msg":"trace[532338633] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"282.759812ms","start":"2026-02-25T22:00:55.802383Z","end":"2026-02-25T22:00:56.085142Z","steps":["trace[532338633] 'process raft request' (duration: 282.470222ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:56.085474Z","caller":"traceutil/trace.go:171","msg":"trace[1310449503] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"281.769223ms","start":"2026-02-25T22:00:55.803695Z","end":"2026-02-25T22:00:56.085464Z","steps":["trace[1310449503] 'process raft request' (duration: 281.327219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:56.085676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.592837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T22:00:56.085722Z","caller":"traceutil/trace.go:171","msg":"trace[1487121418] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:56; }","duration":"282.668929ms","start":"2026-02-25T22:00:55.803039Z","end":"2026-02-25T22:00:56.085708Z","steps":["trace[1487121418] 'agreement among raft nodes before linearized reading' (duration: 282.573476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:56.085806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.339901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T22:00:56.085866Z","caller":"traceutil/trace.go:171","msg":"trace[1903385511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:56; }","duration":"239.439804ms","start":"2026-02-25T22:00:55.846407Z","end":"2026-02-25T22:00:56.085847Z","steps":["trace[1903385511] 'agreement among raft nodes before linearized reading' (duration: 239.317131ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:56.274502Z","caller":"traceutil/trace.go:171","msg":"trace[2036713526] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"107.116457ms","start":"2026-02-25T22:00:56.167287Z","end":"2026-02-25T22:00:56.274404Z","steps":["trace[2036713526] 'process raft request' (duration: 16.846193ms)","trace[2036713526] 'compare' (duration: 89.972546ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:00:56.482508Z","caller":"traceutil/trace.go:171","msg":"trace[1261324570] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"153.318631ms","start":"2026-02-25T22:00:56.329149Z","end":"2026-02-25T22:00:56.482468Z","steps":["trace[1261324570] 'process raft request' (duration: 64.86864ms)","trace[1261324570] 'compare' (duration: 88.009898ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:00:56.482607Z","caller":"traceutil/trace.go:171","msg":"trace[1130696533] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"151.803027ms","start":"2026-02-25T22:00:56.330777Z","end":"2026-02-25T22:00:56.48258Z","steps":["trace[1130696533] 'process raft request' (duration: 151.471897ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:56.482786Z","caller":"traceutil/trace.go:171","msg":"trace[900053521] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"140.780923ms","start":"2026-02-25T22:00:56.341985Z","end":"2026-02-25T22:00:56.482766Z","steps":["trace[900053521] 'process raft request' (duration: 140.350901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:56.482927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.711442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18979c505144d370\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-25T22:00:56.482994Z","caller":"traceutil/trace.go:171","msg":"trace[325126493] range","detail":"{range_begin:/registry/events/default/instance.18979c505144d370; range_end:; response_count:1; response_revision:73; }","duration":"140.799045ms","start":"2026-02-25T22:00:56.34218Z","end":"2026-02-25T22:00:56.482979Z","steps":["trace[325126493] 'agreement among raft nodes before linearized reading' (duration: 140.674361ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:56.483414Z","caller":"traceutil/trace.go:171","msg":"trace[2132297365] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:75; }","duration":"140.503935ms","start":"2026-02-25T22:00:56.342195Z","end":"2026-02-25T22:00:56.482699Z","steps":["trace[2132297365] 'read index received' (duration: 51.917651ms)","trace[2132297365] 'applied index is now lower than readState.Index' (duration: 88.583754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:56.790641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.807637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T22:00:56.790935Z","caller":"traceutil/trace.go:171","msg":"trace[2122525227] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:79; }","duration":"291.841158ms","start":"2026-02-25T22:00:56.499071Z","end":"2026-02-25T22:00:56.790912Z","steps":["trace[2122525227] 'read index received' (duration: 154.66545ms)","trace[2122525227] 'applied index is now lower than readState.Index' (duration: 137.174568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:56.791093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.011774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18979c505144e86a\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-02-25T22:00:56.791131Z","caller":"traceutil/trace.go:171","msg":"trace[1287884225] range","detail":"{range_begin:/registry/events/default/instance.18979c505144e86a; range_end:; response_count:1; response_revision:77; }","duration":"292.125887ms","start":"2026-02-25T22:00:56.498993Z","end":"2026-02-25T22:00:56.791119Z","steps":["trace[1287884225] 'agreement among raft nodes before linearized reading' (duration: 291.997813ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:56.79118Z","caller":"traceutil/trace.go:171","msg":"trace[2028132723] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"303.015867ms","start":"2026-02-25T22:00:56.488078Z","end":"2026-02-25T22:00:56.791094Z","steps":["trace[2028132723] 'process raft request' (duration: 302.699446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:56.79143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.488067Z","time spent":"303.248594ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:00:56.791452Z","caller":"traceutil/trace.go:171","msg":"trace[1651591447] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"302.81676ms","start":"2026-02-25T22:00:56.488608Z","end":"2026-02-25T22:00:56.791424Z","steps":["trace[1651591447] 'process raft request' (duration: 302.234002ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:56.791471Z","caller":"traceutil/trace.go:171","msg":"trace[923393979] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"304.651695ms","start":"2026-02-25T22:00:56.486793Z","end":"2026-02-25T22:00:56.791445Z","steps":["trace[923393979] 'process raft request' (duration: 166.969522ms)","trace[923393979] 'compare' (duration: 136.57368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:56.791532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.488597Z","time spent":"302.887472ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1088,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:00:56.791572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.486776Z","time spent":"304.752067ms","remote":"127.0.0.1:56340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:00:57.447182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.360107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T22:00:57.447557Z","caller":"traceutil/trace.go:171","msg":"trace[148970551] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"648.57467ms","start":"2026-02-25T22:00:56.798954Z","end":"2026-02-25T22:00:57.447529Z","steps":["trace[148970551] 'process raft request' (duration: 648.337953ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:57.447541Z","caller":"traceutil/trace.go:171","msg":"trace[830977042] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"648.622462ms","start":"2026-02-25T22:00:56.798837Z","end":"2026-02-25T22:00:57.447459Z","steps":["trace[830977042] 'process raft request' (duration: 259.889373ms)","trace[830977042] 'compare' (duration: 388.155092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:57.447661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.798946Z","time spent":"648.662742ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1121,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:00:57.447674Z","caller":"traceutil/trace.go:171","msg":"trace[1952579813] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"634.771865ms","start":"2026-02-25T22:00:56.812881Z","end":"2026-02-25T22:00:57.447653Z","steps":["trace[1952579813] 'process raft request' (duration: 634.507338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:57.44773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.654592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18979c505144a4e9\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-25T22:00:57.447763Z","caller":"traceutil/trace.go:171","msg":"trace[870978613] range","detail":"{range_begin:/registry/events/default/instance.18979c505144a4e9; range_end:; response_count:1; response_revision:81; }","duration":"634.711164ms","start":"2026-02-25T22:00:56.813039Z","end":"2026-02-25T22:00:57.44775Z","steps":["trace[870978613] 'agreement among raft nodes before linearized reading' (duration: 634.595391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:57.447753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.812851Z","time spent":"634.869048ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":665,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:00:57.447787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.813029Z","time spent":"634.753065ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":676,"request content":"key:\"/registry/events/default/instance.18979c505144a4e9\" "} {"level":"warn","ts":"2026-02-25T22:00:57.447767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.798812Z","time spent":"648.849117ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":581,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:00:57.447604Z","caller":"traceutil/trace.go:171","msg":"trace[1133031947] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:83; }","duration":"634.521008ms","start":"2026-02-25T22:00:56.81307Z","end":"2026-02-25T22:00:57.447591Z","steps":["trace[1133031947] 'read index received' (duration: 245.664407ms)","trace[1133031947] 'applied index is now lower than readState.Index' (duration: 388.855331ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:57.447974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.852891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T22:00:57.448Z","caller":"traceutil/trace.go:171","msg":"trace[581506091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:81; }","duration":"600.917453ms","start":"2026-02-25T22:00:56.847074Z","end":"2026-02-25T22:00:57.447991Z","steps":["trace[581506091] 'agreement among raft nodes before linearized reading' (duration: 600.866812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:57.448007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.122179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T22:00:57.448019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.847053Z","time spent":"600.961254ms","remote":"127.0.0.1:55936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T22:00:57.448033Z","caller":"traceutil/trace.go:171","msg":"trace[1051885200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:81; }","duration":"530.178851ms","start":"2026-02-25T22:00:56.917842Z","end":"2026-02-25T22:00:57.44802Z","steps":["trace[1051885200] 'agreement among raft nodes before linearized reading' (duration: 530.13957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:57.448026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.298615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T22:00:57.448053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.917821Z","time spent":"530.227423ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T22:00:57.448079Z","caller":"traceutil/trace.go:171","msg":"trace[1224547380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:81; }","duration":"529.400559ms","start":"2026-02-25T22:00:56.918662Z","end":"2026-02-25T22:00:57.448062Z","steps":["trace[1224547380] 'agreement among raft nodes before linearized reading' (duration: 529.284296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:57.448127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:56.918638Z","time spent":"529.476131ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:00:58.038341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.853301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T22:00:58.038574Z","caller":"traceutil/trace.go:171","msg":"trace[892646808] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"513.312288ms","start":"2026-02-25T22:00:57.525241Z","end":"2026-02-25T22:00:58.038553Z","steps":["trace[892646808] 'process raft request' (duration: 513.224425ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:58.038634Z","caller":"traceutil/trace.go:171","msg":"trace[497508873] linearizableReadLoop","detail":"{readStateIndex:93; appliedIndex:92; }","duration":"514.778001ms","start":"2026-02-25T22:00:57.523816Z","end":"2026-02-25T22:00:58.038594Z","steps":["trace[497508873] 'read index received' (duration: 281.518018ms)","trace[497508873] 'applied index is now lower than readState.Index' (duration: 233.257613ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:58.038683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:57.52518Z","time spent":"513.440801ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1101,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:00:58.038757Z","caller":"traceutil/trace.go:171","msg":"trace[741752017] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"518.51358ms","start":"2026-02-25T22:00:57.520232Z","end":"2026-02-25T22:00:58.038745Z","steps":["trace[741752017] 'process raft request' (duration: 285.174605ms)","trace[741752017] 'compare' (duration: 232.670536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:58.038839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:57.520214Z","time spent":"518.569591ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:00:58.039024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.206383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T22:00:58.039066Z","caller":"traceutil/trace.go:171","msg":"trace[2091838045] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:89; }","duration":"515.316026ms","start":"2026-02-25T22:00:57.523739Z","end":"2026-02-25T22:00:58.039055Z","steps":["trace[2091838045] 'agreement among raft nodes before linearized reading' (duration: 515.244274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.039094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:57.523701Z","time spent":"515.387178ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-02-25T22:00:58.0392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.042708ms","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-25T22:00:58.039272Z","caller":"traceutil/trace.go:171","msg":"trace[12879448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:89; }","duration":"515.125661ms","start":"2026-02-25T22:00:57.52412Z","end":"2026-02-25T22:00:58.039245Z","steps":["trace[12879448] 'agreement among raft nodes before linearized reading' (duration: 515.028328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.039318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:57.524108Z","time spent":"515.197912ms","remote":"127.0.0.1:56196","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-25T22:00:58.039346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.089319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18979c505144d370\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-25T22:00:58.039375Z","caller":"traceutil/trace.go:171","msg":"trace[16669446] range","detail":"{range_begin:/registry/events/default/instance.18979c505144d370; range_end:; response_count:1; response_revision:89; }","duration":"514.167882ms","start":"2026-02-25T22:00:57.525198Z","end":"2026-02-25T22:00:58.039366Z","steps":["trace[16669446] 'agreement among raft nodes before linearized reading' (duration: 514.11067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.039419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:57.525176Z","time spent":"514.235954ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":672,"request content":"key:\"/registry/events/default/instance.18979c505144d370\" "} {"level":"warn","ts":"2026-02-25T22:00:58.039492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.758819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T22:00:58.03949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.157722ms","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-25T22:00:58.039561Z","caller":"traceutil/trace.go:171","msg":"trace[308505120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:89; }","duration":"189.866052ms","start":"2026-02-25T22:00:57.84968Z","end":"2026-02-25T22:00:58.039546Z","steps":["trace[308505120] 'agreement among raft nodes before linearized reading' (duration: 189.714928ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:58.0396Z","caller":"traceutil/trace.go:171","msg":"trace[19468185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:89; }","duration":"515.294535ms","start":"2026-02-25T22:00:57.524281Z","end":"2026-02-25T22:00:58.039575Z","steps":["trace[19468185] 'agreement among raft nodes before linearized reading' (duration: 515.049228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.039675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:57.524275Z","time spent":"515.380489ms","remote":"127.0.0.1:56196","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-25T22:00:58.356285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.289278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-25T22:00:58.356489Z","caller":"traceutil/trace.go:171","msg":"trace[1620071895] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"314.307588ms","start":"2026-02-25T22:00:58.042163Z","end":"2026-02-25T22:00:58.35647Z","steps":["trace[1620071895] 'process raft request' (duration: 114.749322ms)","trace[1620071895] 'compare' (duration: 199.167465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:00:58.356652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:58.042135Z","time spent":"314.453053ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:00:58.368151Z","caller":"traceutil/trace.go:171","msg":"trace[36283267] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"322.972062ms","start":"2026-02-25T22:00:58.045165Z","end":"2026-02-25T22:00:58.368138Z","steps":["trace[36283267] 'process raft request' (duration: 322.863699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.368309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:58.045152Z","time spent":"323.119827ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:00:58.368146Z","caller":"traceutil/trace.go:171","msg":"trace[948465225] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"324.056943ms","start":"2026-02-25T22:00:58.044042Z","end":"2026-02-25T22:00:58.368099Z","steps":["trace[948465225] 'process raft request' (duration: 323.914679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.368475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:58.044032Z","time spent":"324.424265ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:00:58.36837Z","caller":"traceutil/trace.go:171","msg":"trace[1207197196] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"322.848339ms","start":"2026-02-25T22:00:58.045465Z","end":"2026-02-25T22:00:58.368314Z","steps":["trace[1207197196] 'process raft request' (duration: 322.583851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.368578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:58.045456Z","time spent":"323.071275ms","remote":"127.0.0.1:56338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:00:58.368423Z","caller":"traceutil/trace.go:171","msg":"trace[1442999224] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"324.041624ms","start":"2026-02-25T22:00:58.044354Z","end":"2026-02-25T22:00:58.368395Z","steps":["trace[1442999224] 'process raft request' (duration: 323.644021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:00:58.368759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:58.04434Z","time spent":"324.334753ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:00:58.618093Z","caller":"traceutil/trace.go:171","msg":"trace[241423757] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"209.496589ms","start":"2026-02-25T22:00:58.408576Z","end":"2026-02-25T22:00:58.618072Z","steps":["trace[241423757] 'process raft request' (duration: 165.398936ms)","trace[241423757] 'compare' (duration: 43.98783ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:00:58.631159Z","caller":"traceutil/trace.go:171","msg":"trace[2043825011] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"221.15339ms","start":"2026-02-25T22:00:58.409988Z","end":"2026-02-25T22:00:58.631141Z","steps":["trace[2043825011] 'process raft request' (duration: 220.908133ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:58.631144Z","caller":"traceutil/trace.go:171","msg":"trace[539638709] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"117.364619ms","start":"2026-02-25T22:00:58.513699Z","end":"2026-02-25T22:00:58.631063Z","steps":["trace[539638709] 'process raft request' (duration: 117.267616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:59.815088Z","caller":"traceutil/trace.go:171","msg":"trace[1740867879] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"111.434215ms","start":"2026-02-25T22:00:59.703622Z","end":"2026-02-25T22:00:59.815056Z","steps":["trace[1740867879] 'process raft request' (duration: 111.393524ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:00:59.815385Z","caller":"traceutil/trace.go:171","msg":"trace[1245408869] transaction","detail":"{read_only:false; response_revision:142; number_of_response:1; }","duration":"112.143406ms","start":"2026-02-25T22:00:59.70319Z","end":"2026-02-25T22:00:59.815333Z","steps":["trace[1245408869] 'process raft request' (duration: 13.078424ms)","trace[1245408869] 'compare' (duration: 98.613039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:00.210729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.177365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:00.210878Z","caller":"traceutil/trace.go:171","msg":"trace[1252486651] linearizableReadLoop","detail":"{readStateIndex:150; appliedIndex:149; }","duration":"263.146511ms","start":"2026-02-25T22:00:59.94771Z","end":"2026-02-25T22:01:00.210856Z","steps":["trace[1252486651] 'read index received' (duration: 60.697698ms)","trace[1252486651] 'applied index is now lower than readState.Index' (duration: 202.447343ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:00.210929Z","caller":"traceutil/trace.go:171","msg":"trace[2130643780] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"388.203145ms","start":"2026-02-25T22:00:59.822669Z","end":"2026-02-25T22:01:00.210872Z","steps":["trace[2130643780] 'process raft request' (duration: 185.774033ms)","trace[2130643780] 'compare' (duration: 201.979399ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:00.211028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.301886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:00.211089Z","caller":"traceutil/trace.go:171","msg":"trace[1671135566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:145; }","duration":"263.415599ms","start":"2026-02-25T22:00:59.947661Z","end":"2026-02-25T22:01:00.211077Z","steps":["trace[1671135566] 'agreement among raft nodes before linearized reading' (duration: 263.253125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:00.211109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:00:59.822648Z","time spent":"388.37387ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:00.439098Z","caller":"traceutil/trace.go:171","msg":"trace[1180454664] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"220.937695ms","start":"2026-02-25T22:01:00.218136Z","end":"2026-02-25T22:01:00.439074Z","steps":["trace[1180454664] 'process raft request' (duration: 158.99606ms)","trace[1180454664] 'compare' (duration: 61.821871ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:01.21388Z","caller":"traceutil/trace.go:171","msg":"trace[1978412755] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"133.997397ms","start":"2026-02-25T22:01:01.079853Z","end":"2026-02-25T22:01:01.213851Z","steps":["trace[1978412755] 'process raft request' (duration: 81.907611ms)","trace[1978412755] 'compare' (duration: 51.912091ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:01.703202Z","caller":"traceutil/trace.go:171","msg":"trace[742705816] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"139.423017ms","start":"2026-02-25T22:01:01.563748Z","end":"2026-02-25T22:01:01.703171Z","steps":["trace[742705816] 'process raft request' (duration: 59.66227ms)","trace[742705816] 'compare' (duration: 79.609212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:04.147048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000139707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T22:01:04.147139Z","caller":"traceutil/trace.go:171","msg":"trace[1931453582] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000298711s","start":"2026-02-25T22:01:02.146819Z","end":"2026-02-25T22:01:04.147118Z","steps":["trace[1931453582] 'agreement among raft nodes before linearized reading' (duration: 2.000191458s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:04.14722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:02.146789Z","time spent":"2.000420915s","remote":"127.0.0.1:55936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 22:01:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T22:01:04.237152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.414072709s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:04.237334Z","caller":"traceutil/trace.go:171","msg":"trace[846540697] linearizableReadLoop","detail":"{readStateIndex:195; appliedIndex:193; }","duration":"2.090421214s","start":"2026-02-25T22:01:02.146895Z","end":"2026-02-25T22:01:04.237316Z","steps":["trace[846540697] 'read index received' (duration: 54.862µs)","trace[846540697] 'applied index is now lower than readState.Index' (duration: 2.090365572s)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:04.237439Z","caller":"traceutil/trace.go:171","msg":"trace[1168503075] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"2.469247436s","start":"2026-02-25T22:01:01.768179Z","end":"2026-02-25T22:01:04.237426Z","steps":["trace[1168503075] 'process raft request' (duration: 2.469088062s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:04.237691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:01.768149Z","time spent":"2.469513124s","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:04.237669Z","caller":"traceutil/trace.go:171","msg":"trace[1682049861] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"2.471706998s","start":"2026-02-25T22:01:01.76593Z","end":"2026-02-25T22:01:04.237637Z","steps":["trace[1682049861] 'process raft request' (duration: 57.057843ms)","trace[1682049861] 'compare' (duration: 2.413893392s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:04.237834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:01.765919Z","time spent":"2.471820642s","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":730,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:01:04.237819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082742989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:01:04.23791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.739980151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:01:04.237987Z","caller":"traceutil/trace.go:171","msg":"trace[1622133770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:190; }","duration":"1.082961345s","start":"2026-02-25T22:01:03.154986Z","end":"2026-02-25T22:01:04.237947Z","steps":["trace[1622133770] 'agreement among raft nodes before linearized reading' (duration: 1.082674037s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:04.238026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740172717s","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-25T22:01:04.238045Z","caller":"traceutil/trace.go:171","msg":"trace[796501032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:190; }","duration":"1.740145717s","start":"2026-02-25T22:01:02.497851Z","end":"2026-02-25T22:01:04.237997Z","steps":["trace[796501032] 'agreement among raft nodes before linearized reading' (duration: 1.739863969s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:04.238046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:03.154966Z","time spent":"1.083066978s","remote":"127.0.0.1:55936","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-25T22:01:04.23808Z","caller":"traceutil/trace.go:171","msg":"trace[2089422606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:190; }","duration":"1.74026805s","start":"2026-02-25T22:01:02.497798Z","end":"2026-02-25T22:01:04.238066Z","steps":["trace[2089422606] 'agreement among raft nodes before linearized reading' (duration: 1.739834417s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:04.238113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:02.497774Z","time spent":"1.740331942s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:01:04.238128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:02.497823Z","time spent":"1.74029111s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T22:01:04.407223Z","caller":"traceutil/trace.go:171","msg":"trace[245777323] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"122.624885ms","start":"2026-02-25T22:01:04.284563Z","end":"2026-02-25T22:01:04.407188Z","steps":["trace[245777323] 'process raft request' (duration: 95.880482ms)","trace[245777323] 'compare' (duration: 26.486455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:05.462829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.497652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:05.462955Z","caller":"traceutil/trace.go:171","msg":"trace[1533096938] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"669.830407ms","start":"2026-02-25T22:01:04.7931Z","end":"2026-02-25T22:01:05.46293Z","steps":["trace[1533096938] 'process raft request' (duration: 226.15074ms)","trace[1533096938] 'compare' (duration: 443.261375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:05.463348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:04.793073Z","time spent":"670.217588ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:05.498077Z","caller":"traceutil/trace.go:171","msg":"trace[285125012] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"440.132263ms","start":"2026-02-25T22:01:05.057918Z","end":"2026-02-25T22:01:05.49805Z","steps":["trace[285125012] 'read index received' (duration: 440.000859ms)","trace[285125012] 'applied index is now lower than readState.Index' (duration: 130.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:05.498116Z","caller":"traceutil/trace.go:171","msg":"trace[1053812768] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"700.460124ms","start":"2026-02-25T22:01:04.797637Z","end":"2026-02-25T22:01:05.498097Z","steps":["trace[1053812768] 'process raft request' (duration: 700.276339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:05.498269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:04.797612Z","time spent":"700.559828ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:05.498659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.711981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:05.498781Z","caller":"traceutil/trace.go:171","msg":"trace[1487625795] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:206; }","duration":"440.880416ms","start":"2026-02-25T22:01:05.057868Z","end":"2026-02-25T22:01:05.498749Z","steps":["trace[1487625795] 'agreement among raft nodes before linearized reading' (duration: 440.409733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:05.498834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.057847Z","time spent":"440.977459ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-25T22:01:06.02013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.489978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:06.020666Z","caller":"traceutil/trace.go:171","msg":"trace[1958645052] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:211; }","duration":"522.441406ms","start":"2026-02-25T22:01:05.498159Z","end":"2026-02-25T22:01:06.0206Z","steps":["trace[1958645052] 'read index received' (duration: 336.370422ms)","trace[1958645052] 'applied index is now lower than readState.Index' (duration: 186.069564ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:06.020847Z","caller":"traceutil/trace.go:171","msg":"trace[682170301] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"676.453981ms","start":"2026-02-25T22:01:05.344378Z","end":"2026-02-25T22:01:06.020832Z","steps":["trace[682170301] 'process raft request' (duration: 675.847923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.021169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.947572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:06.021239Z","caller":"traceutil/trace.go:171","msg":"trace[903580661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:208; }","duration":"873.078345ms","start":"2026-02-25T22:01:05.148146Z","end":"2026-02-25T22:01:06.021225Z","steps":["trace[903580661] 'agreement among raft nodes before linearized reading' (duration: 872.982972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.021258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.752437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"warn","ts":"2026-02-25T22:01:06.021195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.197207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:01:06.021305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.433968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3473"} {"level":"info","ts":"2026-02-25T22:01:06.02133Z","caller":"traceutil/trace.go:171","msg":"trace[1977996537] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:208; }","duration":"519.474179ms","start":"2026-02-25T22:01:05.501849Z","end":"2026-02-25T22:01:06.021324Z","steps":["trace[1977996537] 'agreement among raft nodes before linearized reading' (duration: 519.434798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.021347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.501841Z","time spent":"519.50246ms","remote":"127.0.0.1:56106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3495,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T22:01:06.020608Z","caller":"traceutil/trace.go:171","msg":"trace[1008234059] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"785.436917ms","start":"2026-02-25T22:01:05.235089Z","end":"2026-02-25T22:01:06.020526Z","steps":["trace[1008234059] 'process raft request' (duration: 599.460205ms)","trace[1008234059] 'compare' (duration: 185.371114ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:06.021295Z","caller":"traceutil/trace.go:171","msg":"trace[699827074] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:208; }","duration":"519.812449ms","start":"2026-02-25T22:01:05.501473Z","end":"2026-02-25T22:01:06.021285Z","steps":["trace[699827074] 'agreement among raft nodes before linearized reading' (duration: 519.589282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.021268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.148124Z","time spent":"873.138637ms","remote":"127.0.0.1:55934","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-25T22:01:06.021454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.23507Z","time spent":"786.334032ms","remote":"127.0.0.1:56106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:06.021452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.339889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:01:06.021394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.344345Z","time spent":"676.823612ms","remote":"127.0.0.1:56196","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-25T22:01:06.021571Z","caller":"traceutil/trace.go:171","msg":"trace[499840433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:208; }","duration":"366.515394ms","start":"2026-02-25T22:01:05.655026Z","end":"2026-02-25T22:01:06.021541Z","steps":["trace[499840433] 'agreement among raft nodes before linearized reading' (duration: 366.243416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.021623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.655011Z","time spent":"366.603428ms","remote":"127.0.0.1:55936","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-25T22:01:06.021517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.50146Z","time spent":"520.026995ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3996,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-02-25T22:01:06.021386Z","caller":"traceutil/trace.go:171","msg":"trace[519011795] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslice-controller; range_end:; response_count:0; response_revision:208; }","duration":"555.362151ms","start":"2026-02-25T22:01:05.465937Z","end":"2026-02-25T22:01:06.021299Z","steps":["trace[519011795] 'agreement among raft nodes before linearized reading' (duration: 554.9591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.021788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:05.465918Z","time spent":"555.859437ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:endpointslice-controller\" "} {"level":"warn","ts":"2026-02-25T22:01:06.374707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.756794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:06.374844Z","caller":"traceutil/trace.go:171","msg":"trace[1696342926] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:214; }","duration":"127.551001ms","start":"2026-02-25T22:01:06.247282Z","end":"2026-02-25T22:01:06.374833Z","steps":["trace[1696342926] 'read index received' (duration: 34.911µs)","trace[1696342926] 'applied index is now lower than readState.Index' (duration: 127.51555ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:06.374896Z","caller":"traceutil/trace.go:171","msg":"trace[1158152967] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"348.722275ms","start":"2026-02-25T22:01:06.026168Z","end":"2026-02-25T22:01:06.374891Z","steps":["trace[1158152967] 'process raft request' (duration: 202.737955ms)","trace[1158152967] 'compare' (duration: 145.617439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:06.375084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.791757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:06.375167Z","caller":"traceutil/trace.go:171","msg":"trace[365917175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:211; }","duration":"127.923331ms","start":"2026-02-25T22:01:06.247225Z","end":"2026-02-25T22:01:06.375148Z","steps":["trace[365917175] 'agreement among raft nodes before linearized reading' (duration: 127.716715ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:06.375219Z","caller":"traceutil/trace.go:171","msg":"trace[1823224282] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"346.497289ms","start":"2026-02-25T22:01:06.028667Z","end":"2026-02-25T22:01:06.375165Z","steps":["trace[1823224282] 'process raft request' (duration: 346.119088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.375482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:06.028657Z","time spent":"346.687575ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:06.375575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:06.026118Z","time spent":"348.798007ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:01:06.847272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.234105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:06.84744Z","caller":"traceutil/trace.go:171","msg":"trace[456672662] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:219; }","duration":"388.319765ms","start":"2026-02-25T22:01:06.459103Z","end":"2026-02-25T22:01:06.847422Z","steps":["trace[456672662] 'read index received' (duration: 267.786221ms)","trace[456672662] 'applied index is now lower than readState.Index' (duration: 120.532274ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:06.847484Z","caller":"traceutil/trace.go:171","msg":"trace[346658791] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"424.549227ms","start":"2026-02-25T22:01:06.422895Z","end":"2026-02-25T22:01:06.847444Z","steps":["trace[346658791] 'process raft request' (duration: 304.071775ms)","trace[346658791] 'compare' (duration: 120.104662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:06.847658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:06.422854Z","time spent":"424.712872ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:01:06.847679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.750881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:01:06.847668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.540261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-02-25T22:01:06.84772Z","caller":"traceutil/trace.go:171","msg":"trace[418681112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:215; }","duration":"191.887495ms","start":"2026-02-25T22:01:06.65582Z","end":"2026-02-25T22:01:06.847707Z","steps":["trace[418681112] 'agreement among raft nodes before linearized reading' (duration: 191.6986ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:06.847755Z","caller":"traceutil/trace.go:171","msg":"trace[660189988] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:215; }","duration":"388.647264ms","start":"2026-02-25T22:01:06.459087Z","end":"2026-02-25T22:01:06.847734Z","steps":["trace[660189988] 'agreement among raft nodes before linearized reading' (duration: 388.411457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:06.847812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:06.459021Z","time spent":"388.782518ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3488,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-02-25T22:01:07.079563Z","caller":"traceutil/trace.go:171","msg":"trace[77408245] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"209.555134ms","start":"2026-02-25T22:01:06.869976Z","end":"2026-02-25T22:01:07.079531Z","steps":["trace[77408245] 'read index received' (duration: 126.249962ms)","trace[77408245] 'applied index is now lower than readState.Index' (duration: 83.303842ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:07.079693Z","caller":"traceutil/trace.go:171","msg":"trace[1844720433] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"223.08222ms","start":"2026-02-25T22:01:06.856583Z","end":"2026-02-25T22:01:07.079665Z","steps":["trace[1844720433] 'process raft request' (duration: 139.732026ms)","trace[1844720433] 'compare' (duration: 82.814809ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:07.07976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.76483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:07.079947Z","caller":"traceutil/trace.go:171","msg":"trace[299556016] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:0; response_revision:217; }","duration":"209.995217ms","start":"2026-02-25T22:01:06.869924Z","end":"2026-02-25T22:01:07.07992Z","steps":["trace[299556016] 'agreement among raft nodes before linearized reading' (duration: 209.743869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:08.421763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.776709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:08.421857Z","caller":"traceutil/trace.go:171","msg":"trace[1384301554] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"174.068604ms","start":"2026-02-25T22:01:08.247772Z","end":"2026-02-25T22:01:08.42184Z","steps":["trace[1384301554] 'read index received' (duration: 62.662µs)","trace[1384301554] 'applied index is now lower than readState.Index' (duration: 174.005022ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:08.421942Z","caller":"traceutil/trace.go:171","msg":"trace[1649521018] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"351.493307ms","start":"2026-02-25T22:01:08.070438Z","end":"2026-02-25T22:01:08.421931Z","steps":["trace[1649521018] 'process raft request' (duration: 48.487632ms)","trace[1649521018] 'compare' (duration: 302.634704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:08.421973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.189738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:01:08.421986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:08.070403Z","time spent":"351.564669ms","remote":"127.0.0.1:56258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":793,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:08.421999Z","caller":"traceutil/trace.go:171","msg":"trace[1599214631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:252; }","duration":"174.279421ms","start":"2026-02-25T22:01:08.247713Z","end":"2026-02-25T22:01:08.421992Z","steps":["trace[1599214631] 'agreement among raft nodes before linearized reading' (duration: 174.180108ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:08.593653Z","caller":"traceutil/trace.go:171","msg":"trace[1938700517] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"165.130402ms","start":"2026-02-25T22:01:08.428487Z","end":"2026-02-25T22:01:08.593617Z","steps":["trace[1938700517] 'process raft request' (duration: 143.068035ms)","trace[1938700517] 'compare' (duration: 21.874392ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:08.593675Z","caller":"traceutil/trace.go:171","msg":"trace[111294725] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:257; }","duration":"134.312179ms","start":"2026-02-25T22:01:08.459332Z","end":"2026-02-25T22:01:08.593644Z","steps":["trace[111294725] 'read index received' (duration: 2.839633ms)","trace[111294725] 'applied index is now lower than readState.Index' (duration: 131.470166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:08.593855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.503444ms","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-25T22:01:08.593899Z","caller":"traceutil/trace.go:171","msg":"trace[1805304443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:255; }","duration":"134.561976ms","start":"2026-02-25T22:01:08.459326Z","end":"2026-02-25T22:01:08.593888Z","steps":["trace[1805304443] 'agreement among raft nodes before linearized reading' (duration: 134.407401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:09.010678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.746185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:09.01084Z","caller":"traceutil/trace.go:171","msg":"trace[21368642] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"263.149868ms","start":"2026-02-25T22:01:08.747668Z","end":"2026-02-25T22:01:09.010818Z","steps":["trace[21368642] 'read index received' (duration: 31.941µs)","trace[21368642] 'applied index is now lower than readState.Index' (duration: 263.116407ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:09.010946Z","caller":"traceutil/trace.go:171","msg":"trace[1864400177] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"349.593002ms","start":"2026-02-25T22:01:08.661279Z","end":"2026-02-25T22:01:09.010872Z","steps":["trace[1864400177] 'process raft request' (duration: 42.572668ms)","trace[1864400177] 'compare' (duration: 306.586291ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:09.011035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.341453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:09.011108Z","caller":"traceutil/trace.go:171","msg":"trace[1413078185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:260; }","duration":"263.470256ms","start":"2026-02-25T22:01:08.747616Z","end":"2026-02-25T22:01:09.011087Z","steps":["trace[1413078185] 'agreement among raft nodes before linearized reading' (duration: 263.26182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:09.0112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:08.661263Z","time spent":"349.792998ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:09.196408Z","caller":"traceutil/trace.go:171","msg":"trace[1647116829] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"149.766173ms","start":"2026-02-25T22:01:09.046606Z","end":"2026-02-25T22:01:09.196372Z","steps":["trace[1647116829] 'process raft request' (duration: 79.221195ms)","trace[1647116829] 'compare' (duration: 70.122816ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:09.196433Z","caller":"traceutil/trace.go:171","msg":"trace[908340963] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"143.117737ms","start":"2026-02-25T22:01:09.053278Z","end":"2026-02-25T22:01:09.196396Z","steps":["trace[908340963] 'process raft request' (duration: 143.000763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:09.473584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.454095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T22:01:09.473683Z","caller":"traceutil/trace.go:171","msg":"trace[821052733] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:267; }","duration":"177.60963ms","start":"2026-02-25T22:01:09.296054Z","end":"2026-02-25T22:01:09.473663Z","steps":["trace[821052733] 'range keys from in-memory index tree' (duration: 177.377873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:09.473959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.84788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:09.474077Z","caller":"traceutil/trace.go:171","msg":"trace[217745296] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"176.575998ms","start":"2026-02-25T22:01:09.297482Z","end":"2026-02-25T22:01:09.474058Z","steps":["trace[217745296] 'process raft request' (duration: 64.561893ms)","trace[217745296] 'compare' (duration: 111.528491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:09.692965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.774329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:09.693159Z","caller":"traceutil/trace.go:171","msg":"trace[1601483428] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"144.544919ms","start":"2026-02-25T22:01:09.548589Z","end":"2026-02-25T22:01:09.693134Z","steps":["trace[1601483428] 'process raft request' (duration: 34.537653ms)","trace[1601483428] 'compare' (duration: 109.688756ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:09.693163Z","caller":"traceutil/trace.go:171","msg":"trace[1903663232] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"142.825438ms","start":"2026-02-25T22:01:09.550321Z","end":"2026-02-25T22:01:09.693147Z","steps":["trace[1903663232] 'process raft request' (duration: 142.746616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:09.948729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.74174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:09.94882Z","caller":"traceutil/trace.go:171","msg":"trace[25112328] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:275; }","duration":"151.902754ms","start":"2026-02-25T22:01:09.796896Z","end":"2026-02-25T22:01:09.948799Z","steps":["trace[25112328] 'range keys from in-memory index tree' (duration: 151.583504ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:13.260675Z","caller":"traceutil/trace.go:171","msg":"trace[1066136889] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"157.350606ms","start":"2026-02-25T22:01:13.103301Z","end":"2026-02-25T22:01:13.260651Z","steps":["trace[1066136889] 'read index received' (duration: 157.103089ms)","trace[1066136889] 'applied index is now lower than readState.Index' (duration: 246.597µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:13.261692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.380466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-02-25T22:01:13.261821Z","caller":"traceutil/trace.go:171","msg":"trace[1561802886] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:350; }","duration":"158.542151ms","start":"2026-02-25T22:01:13.103235Z","end":"2026-02-25T22:01:13.261777Z","steps":["trace[1561802886] 'agreement among raft nodes before linearized reading' (duration: 157.48151ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:13.261153Z","caller":"traceutil/trace.go:171","msg":"trace[402891653] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"265.768716ms","start":"2026-02-25T22:01:12.994948Z","end":"2026-02-25T22:01:13.260717Z","steps":["trace[402891653] 'process raft request' (duration: 265.55695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:13.799446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.341919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:13.799698Z","caller":"traceutil/trace.go:171","msg":"trace[847985481] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"506.966281ms","start":"2026-02-25T22:01:13.292665Z","end":"2026-02-25T22:01:13.799631Z","steps":["trace[847985481] 'process raft request' (duration: 369.364115ms)","trace[847985481] 'compare' (duration: 137.109942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:13.7999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:13.292643Z","time spent":"507.134517ms","remote":"127.0.0.1:56138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:01:14.322674Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734651696,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:01:14.749223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.632069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:14.749347Z","caller":"traceutil/trace.go:171","msg":"trace[1789673878] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"927.133628ms","start":"2026-02-25T22:01:13.822194Z","end":"2026-02-25T22:01:14.749328Z","steps":["trace[1789673878] 'read index received' (duration: 715.186459ms)","trace[1789673878] 'applied index is now lower than readState.Index' (duration: 211.945299ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:14.749578Z","caller":"traceutil/trace.go:171","msg":"trace[595333812] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"943.831088ms","start":"2026-02-25T22:01:13.805605Z","end":"2026-02-25T22:01:14.749436Z","steps":["trace[595333812] 'process raft request' (duration: 731.823088ms)","trace[595333812] 'compare' (duration: 211.059892ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:14.749644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.440916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T22:01:14.749697Z","caller":"traceutil/trace.go:171","msg":"trace[1134055544] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:353; }","duration":"927.54789ms","start":"2026-02-25T22:01:13.822134Z","end":"2026-02-25T22:01:14.749682Z","steps":["trace[1134055544] 'agreement among raft nodes before linearized reading' (duration: 927.452637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:14.749868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.338484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T22:01:14.749767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:13.805584Z","time spent":"944.071964ms","remote":"127.0.0.1:56196","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-25T22:01:14.749943Z","caller":"traceutil/trace.go:171","msg":"trace[1495549553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"265.452568ms","start":"2026-02-25T22:01:14.484471Z","end":"2026-02-25T22:01:14.749924Z","steps":["trace[1495549553] 'agreement among raft nodes before linearized reading' (duration: 265.300614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:14.749964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.206012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T22:01:14.749856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:13.822121Z","time spent":"927.707554ms","remote":"127.0.0.1:56046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T22:01:14.750035Z","caller":"traceutil/trace.go:171","msg":"trace[127114388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:353; }","duration":"128.302155ms","start":"2026-02-25T22:01:14.621713Z","end":"2026-02-25T22:01:14.750015Z","steps":["trace[127114388] 'agreement among raft nodes before linearized reading' (duration: 128.108809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.112962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.479323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:15.113157Z","caller":"traceutil/trace.go:171","msg":"trace[1862487154] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"202.121868ms","start":"2026-02-25T22:01:14.911016Z","end":"2026-02-25T22:01:15.113138Z","steps":["trace[1862487154] 'process raft request' (duration: 49.407088ms)","trace[1862487154] 'compare' (duration: 152.318979ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:15.137078Z","caller":"traceutil/trace.go:171","msg":"trace[1044914080] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"222.595009ms","start":"2026-02-25T22:01:14.914464Z","end":"2026-02-25T22:01:15.137059Z","steps":["trace[1044914080] 'process raft request' (duration: 222.481376ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.137078Z","caller":"traceutil/trace.go:171","msg":"trace[552698645] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:374; }","duration":"188.774908ms","start":"2026-02-25T22:01:14.948261Z","end":"2026-02-25T22:01:15.137036Z","steps":["trace[552698645] 'read index received' (duration: 12.176346ms)","trace[552698645] 'applied index is now lower than readState.Index' (duration: 176.597692ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:15.137337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.062676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T22:01:15.137372Z","caller":"traceutil/trace.go:171","msg":"trace[583032488] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:361; }","duration":"189.136439ms","start":"2026-02-25T22:01:14.948226Z","end":"2026-02-25T22:01:15.137362Z","steps":["trace[583032488] 'agreement among raft nodes before linearized reading' (duration: 189.044325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.137414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.871752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T22:01:15.137379Z","caller":"traceutil/trace.go:171","msg":"trace[1031595269] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"223.663082ms","start":"2026-02-25T22:01:14.913679Z","end":"2026-02-25T22:01:15.137342Z","steps":["trace[1031595269] 'process raft request' (duration: 223.021612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.137489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.651629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-25T22:01:15.137534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.327687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T22:01:15.137574Z","caller":"traceutil/trace.go:171","msg":"trace[1923932430] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:361; }","duration":"167.781683ms","start":"2026-02-25T22:01:14.969774Z","end":"2026-02-25T22:01:15.137556Z","steps":["trace[1923932430] 'agreement among raft nodes before linearized reading' (duration: 167.604418ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.137588Z","caller":"traceutil/trace.go:171","msg":"trace[1506844083] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:361; }","duration":"186.401079ms","start":"2026-02-25T22:01:14.951172Z","end":"2026-02-25T22:01:15.137573Z","steps":["trace[1506844083] 'agreement among raft nodes before linearized reading' (duration: 186.264695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.137621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.649616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-25T22:01:15.13765Z","caller":"traceutil/trace.go:171","msg":"trace[1892375128] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:361; }","duration":"187.704277ms","start":"2026-02-25T22:01:14.949936Z","end":"2026-02-25T22:01:15.13764Z","steps":["trace[1892375128] 'agreement among raft nodes before linearized reading' (duration: 187.610164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.137514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.015825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T22:01:15.137665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.901741ms","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-25T22:01:15.137718Z","caller":"traceutil/trace.go:171","msg":"trace[274398841] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:361; }","duration":"176.306253ms","start":"2026-02-25T22:01:14.961403Z","end":"2026-02-25T22:01:15.137709Z","steps":["trace[274398841] 'agreement among raft nodes before linearized reading' (duration: 176.030005ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.137541Z","caller":"traceutil/trace.go:171","msg":"trace[33036203] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:361; }","duration":"188.008297ms","start":"2026-02-25T22:01:14.949453Z","end":"2026-02-25T22:01:15.137461Z","steps":["trace[33036203] 'agreement among raft nodes before linearized reading' (duration: 187.847851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.137751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.41059ms","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-25T22:01:15.137818Z","caller":"traceutil/trace.go:171","msg":"trace[1401395058] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:361; }","duration":"155.514854ms","start":"2026-02-25T22:01:14.98229Z","end":"2026-02-25T22:01:15.137805Z","steps":["trace[1401395058] 'agreement among raft nodes before linearized reading' (duration: 155.412441ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.137771Z","caller":"traceutil/trace.go:171","msg":"trace[1152424407] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:361; }","duration":"116.089467ms","start":"2026-02-25T22:01:15.021642Z","end":"2026-02-25T22:01:15.137732Z","steps":["trace[1152424407] 'agreement among raft nodes before linearized reading' (duration: 115.892001ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.863669Z","caller":"traceutil/trace.go:171","msg":"trace[517440926] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"715.305527ms","start":"2026-02-25T22:01:15.148331Z","end":"2026-02-25T22:01:15.863637Z","steps":["trace[517440926] 'process raft request' (duration: 670.210583ms)","trace[517440926] 'compare' (duration: 44.643781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:15.863795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.148313Z","time spent":"715.41993ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:01:15.864085Z","caller":"traceutil/trace.go:171","msg":"trace[235129288] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"714.291106ms","start":"2026-02-25T22:01:15.149782Z","end":"2026-02-25T22:01:15.864073Z","steps":["trace[235129288] 'process raft request' (duration: 714.098431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.864158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.149772Z","time spent":"714.339677ms","remote":"127.0.0.1:56388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:15.864247Z","caller":"traceutil/trace.go:171","msg":"trace[715721355] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"713.991878ms","start":"2026-02-25T22:01:15.15017Z","end":"2026-02-25T22:01:15.864162Z","steps":["trace[715721355] 'process raft request' (duration: 713.768621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.864377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.150158Z","time spent":"714.174173ms","remote":"127.0.0.1:56042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:15.864223Z","caller":"traceutil/trace.go:171","msg":"trace[1765792167] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"714.4076ms","start":"2026-02-25T22:01:15.149727Z","end":"2026-02-25T22:01:15.864134Z","steps":["trace[1765792167] 'process raft request' (duration: 713.792542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.864577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.149712Z","time spent":"714.803541ms","remote":"127.0.0.1:56212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:15.86459Z","caller":"traceutil/trace.go:171","msg":"trace[1031841757] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"713.508004ms","start":"2026-02-25T22:01:15.151058Z","end":"2026-02-25T22:01:15.864566Z","steps":["trace[1031841757] 'process raft request' (duration: 712.999709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.864641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.151041Z","time spent":"713.578966ms","remote":"127.0.0.1:56378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:15.865403Z","caller":"traceutil/trace.go:171","msg":"trace[650425212] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"713.06521ms","start":"2026-02-25T22:01:15.1523Z","end":"2026-02-25T22:01:15.865365Z","steps":["trace[650425212] 'process raft request' (duration: 711.836114ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.865475Z","caller":"traceutil/trace.go:171","msg":"trace[1744748114] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"711.827354ms","start":"2026-02-25T22:01:15.153635Z","end":"2026-02-25T22:01:15.865462Z","steps":["trace[1744748114] 'process raft request' (duration: 711.652418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.86549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.152293Z","time spent":"713.159072ms","remote":"127.0.0.1:56138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:15.865512Z","caller":"traceutil/trace.go:171","msg":"trace[790686437] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"711.027869ms","start":"2026-02-25T22:01:15.154466Z","end":"2026-02-25T22:01:15.865494Z","steps":["trace[790686437] 'process raft request' (duration: 710.889835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.865673Z","caller":"traceutil/trace.go:171","msg":"trace[1718277335] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"710.810184ms","start":"2026-02-25T22:01:15.154842Z","end":"2026-02-25T22:01:15.865652Z","steps":["trace[1718277335] 'process raft request' (duration: 710.556827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:15.865661Z","caller":"traceutil/trace.go:171","msg":"trace[30698358] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"710.651959ms","start":"2026-02-25T22:01:15.155Z","end":"2026-02-25T22:01:15.865652Z","steps":["trace[30698358] 'process raft request' (duration: 710.445383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:15.866496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.154835Z","time spent":"711.618348ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:15.866606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.154992Z","time spent":"711.553196ms","remote":"127.0.0.1:56184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:15.865603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.153629Z","time spent":"711.942658ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:15.867016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:15.154459Z","time spent":"711.621478ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:01:16.001509Z","caller":"traceutil/trace.go:171","msg":"trace[1554537569] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"126.843981ms","start":"2026-02-25T22:01:15.874636Z","end":"2026-02-25T22:01:16.00148Z","steps":["trace[1554537569] 'process raft request' (duration: 126.754359ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:16.001578Z","caller":"traceutil/trace.go:171","msg":"trace[903055037] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"128.46815ms","start":"2026-02-25T22:01:15.873089Z","end":"2026-02-25T22:01:16.001558Z","steps":["trace[903055037] 'process raft request' (duration: 128.228373ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:16.001519Z","caller":"traceutil/trace.go:171","msg":"trace[1558366690] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"128.554171ms","start":"2026-02-25T22:01:15.872924Z","end":"2026-02-25T22:01:16.001478Z","steps":["trace[1558366690] 'process raft request' (duration: 90.182265ms)","trace[1558366690] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/pods/kube-system/kube-proxy-9c762; req_size:3375; } (duration: 37.918393ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:16.001704Z","caller":"traceutil/trace.go:171","msg":"trace[902307079] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:388; }","duration":"116.56412ms","start":"2026-02-25T22:01:15.885118Z","end":"2026-02-25T22:01:16.001682Z","steps":["trace[902307079] 'read index received' (duration: 77.897815ms)","trace[902307079] 'applied index is now lower than readState.Index' (duration: 38.664995ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:16.001795Z","caller":"traceutil/trace.go:171","msg":"trace[2057108529] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"116.663323ms","start":"2026-02-25T22:01:15.885121Z","end":"2026-02-25T22:01:16.001784Z","steps":["trace[2057108529] 'process raft request' (duration: 116.53752ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:16.001802Z","caller":"traceutil/trace.go:171","msg":"trace[874605088] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"125.486542ms","start":"2026-02-25T22:01:15.876297Z","end":"2026-02-25T22:01:16.001783Z","steps":["trace[874605088] 'process raft request' (duration: 125.306097ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:16.001988Z","caller":"traceutil/trace.go:171","msg":"trace[1982531569] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"127.273344ms","start":"2026-02-25T22:01:15.874698Z","end":"2026-02-25T22:01:16.001971Z","steps":["trace[1982531569] 'process raft request' (duration: 126.735268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:16.002132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.993683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T22:01:16.00218Z","caller":"traceutil/trace.go:171","msg":"trace[1861700861] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:381; }","duration":"117.072285ms","start":"2026-02-25T22:01:15.885096Z","end":"2026-02-25T22:01:16.002168Z","steps":["trace[1861700861] 'agreement among raft nodes before linearized reading' (duration: 116.665214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:16.401988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.548346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:16.402108Z","caller":"traceutil/trace.go:171","msg":"trace[98827625] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"193.048455ms","start":"2026-02-25T22:01:16.209039Z","end":"2026-02-25T22:01:16.402088Z","steps":["trace[98827625] 'process raft request' (duration: 71.359444ms)","trace[98827625] 'compare' (duration: 121.438323ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:16.833601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.568703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:16.833933Z","caller":"traceutil/trace.go:171","msg":"trace[1415910519] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"426.740711ms","start":"2026-02-25T22:01:16.407171Z","end":"2026-02-25T22:01:16.833911Z","steps":["trace[1415910519] 'process raft request' (duration: 161.796217ms)","trace[1415910519] 'compare' (duration: 264.381888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:16.834059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:16.407147Z","time spent":"426.844134ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:01:16.833883Z","caller":"traceutil/trace.go:171","msg":"trace[330045659] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"426.030821ms","start":"2026-02-25T22:01:16.407834Z","end":"2026-02-25T22:01:16.833865Z","steps":["trace[330045659] 'process raft request' (duration: 425.939398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:16.834305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:16.407813Z","time spent":"426.446723ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:01:16.840919Z","caller":"traceutil/trace.go:171","msg":"trace[1427730128] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"420.917252ms","start":"2026-02-25T22:01:16.419991Z","end":"2026-02-25T22:01:16.840908Z","steps":["trace[1427730128] 'process raft request' (duration: 420.760397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:16.840986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:16.419976Z","time spent":"420.975563ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:16.841045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.482762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:16.840908Z","caller":"traceutil/trace.go:171","msg":"trace[1622138675] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:412; }","duration":"415.375089ms","start":"2026-02-25T22:01:16.425518Z","end":"2026-02-25T22:01:16.840893Z","steps":["trace[1622138675] 'read index received' (duration: 143.46377ms)","trace[1622138675] 'applied index is now lower than readState.Index' (duration: 271.910309ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:16.841092Z","caller":"traceutil/trace.go:171","msg":"trace[1009125720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"415.594266ms","start":"2026-02-25T22:01:16.425487Z","end":"2026-02-25T22:01:16.841081Z","steps":["trace[1009125720] 'agreement among raft nodes before linearized reading' (duration: 415.452832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:16.841159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:16.425474Z","time spent":"415.673568ms","remote":"127.0.0.1:55936","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-25T22:01:16.841196Z","caller":"traceutil/trace.go:171","msg":"trace[1067734064] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"419.737357ms","start":"2026-02-25T22:01:16.42145Z","end":"2026-02-25T22:01:16.841188Z","steps":["trace[1067734064] 'process raft request' (duration: 419.400817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:16.841236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:16.421436Z","time spent":"419.778908ms","remote":"127.0.0.1:56196","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-25T22:01:16.871666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.490928ms","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-25T22:01:16.871742Z","caller":"traceutil/trace.go:171","msg":"trace[527250461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"117.629231ms","start":"2026-02-25T22:01:16.754094Z","end":"2026-02-25T22:01:16.871723Z","steps":["trace[527250461] 'agreement among raft nodes before linearized reading' (duration: 117.487297ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:19.090008Z","caller":"traceutil/trace.go:171","msg":"trace[1055782812] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"186.901564ms","start":"2026-02-25T22:01:18.903088Z","end":"2026-02-25T22:01:19.08999Z","steps":["trace[1055782812] 'read index received' (duration: 186.725679ms)","trace[1055782812] 'applied index is now lower than readState.Index' (duration: 175.085µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:19.090329Z","caller":"traceutil/trace.go:171","msg":"trace[11053272] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"241.725675ms","start":"2026-02-25T22:01:18.848585Z","end":"2026-02-25T22:01:19.090311Z","steps":["trace[11053272] 'process raft request' (duration: 241.290801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:19.090679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.448541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T22:01:19.090759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.637367ms","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-25T22:01:19.09102Z","caller":"traceutil/trace.go:171","msg":"trace[908879650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"187.932245ms","start":"2026-02-25T22:01:18.903061Z","end":"2026-02-25T22:01:19.090994Z","steps":["trace[908879650] 'agreement among raft nodes before linearized reading' (duration: 187.522314ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:19.090845Z","caller":"traceutil/trace.go:171","msg":"trace[520712227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"187.669787ms","start":"2026-02-25T22:01:18.903136Z","end":"2026-02-25T22:01:19.090806Z","steps":["trace[520712227] 'agreement among raft nodes before linearized reading' (duration: 187.346048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:19.090827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.032054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:01:19.091298Z","caller":"traceutil/trace.go:171","msg":"trace[2001763437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"180.518788ms","start":"2026-02-25T22:01:18.910757Z","end":"2026-02-25T22:01:19.091276Z","steps":["trace[2001763437] 'agreement among raft nodes before linearized reading' (duration: 179.798417ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:20.296567Z","caller":"traceutil/trace.go:171","msg":"trace[794780627] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"123.450742ms","start":"2026-02-25T22:01:20.173094Z","end":"2026-02-25T22:01:20.296545Z","steps":["trace[794780627] 'process raft request' (duration: 123.296558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:20.500998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.039374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:20.501152Z","caller":"traceutil/trace.go:171","msg":"trace[1089445287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"149.22617ms","start":"2026-02-25T22:01:20.351893Z","end":"2026-02-25T22:01:20.50112Z","steps":["trace[1089445287] 'range keys from in-memory index tree' (duration: 148.89262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:24.687304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.999316ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T22:01:25.807185Z","caller":"traceutil/trace.go:171","msg":"trace[140609883] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"146.971974ms","start":"2026-02-25T22:01:25.660191Z","end":"2026-02-25T22:01:25.807163Z","steps":["trace[140609883] 'process raft request' (duration: 146.811519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:29.366778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:29.015425Z","time spent":"351.346075ms","remote":"127.0.0.1:55968","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T22:01:37.173846Z","caller":"traceutil/trace.go:171","msg":"trace[610230640] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"156.242618ms","start":"2026-02-25T22:01:37.017585Z","end":"2026-02-25T22:01:37.173828Z","steps":["trace[610230640] 'process raft request' (duration: 156.139065ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:41.860631Z","caller":"traceutil/trace.go:171","msg":"trace[1673542509] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"426.790195ms","start":"2026-02-25T22:01:41.433818Z","end":"2026-02-25T22:01:41.860608Z","steps":["trace[1673542509] 'read index received' (duration: 426.583759ms)","trace[1673542509] 'applied index is now lower than readState.Index' (duration: 205.356µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:41.860709Z","caller":"traceutil/trace.go:171","msg":"trace[2140590833] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"472.25928ms","start":"2026-02-25T22:01:41.388419Z","end":"2026-02-25T22:01:41.860678Z","steps":["trace[2140590833] 'process raft request' (duration: 472.047234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:41.860869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.987462ms","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-25T22:01:41.860915Z","caller":"traceutil/trace.go:171","msg":"trace[1549566849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"424.076265ms","start":"2026-02-25T22:01:41.436827Z","end":"2026-02-25T22:01:41.860903Z","steps":["trace[1549566849] 'agreement among raft nodes before linearized reading' (duration: 423.92429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:41.860936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.294554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T22:01:41.86095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:41.436807Z","time spent":"424.132786ms","remote":"127.0.0.1:56196","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-25T22:01:41.860971Z","caller":"traceutil/trace.go:171","msg":"trace[1475150447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"340.327635ms","start":"2026-02-25T22:01:41.520632Z","end":"2026-02-25T22:01:41.86096Z","steps":["trace[1475150447] 'agreement among raft nodes before linearized reading' (duration: 340.212602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:41.860992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:41.520602Z","time spent":"340.384317ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:01:41.861022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.8628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T22:01:41.861036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.174386ms","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-25T22:01:41.861157Z","caller":"traceutil/trace.go:171","msg":"trace[1622065558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"427.353131ms","start":"2026-02-25T22:01:41.433783Z","end":"2026-02-25T22:01:41.861136Z","steps":["trace[1622065558] 'agreement among raft nodes before linearized reading' (duration: 426.96772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:41.861208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:41.433766Z","time spent":"427.432564ms","remote":"127.0.0.1:56196","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":"info","ts":"2026-02-25T22:01:41.861213Z","caller":"traceutil/trace.go:171","msg":"trace[587998478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"311.000994ms","start":"2026-02-25T22:01:41.55009Z","end":"2026-02-25T22:01:41.861091Z","steps":["trace[587998478] 'agreement among raft nodes before linearized reading' (duration: 310.711696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:41.861284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:41.550072Z","time spent":"311.193219ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:01:41.861344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:41.388401Z","time spent":"472.358243ms","remote":"127.0.0.1:56106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:01:48.04457Z","caller":"traceutil/trace.go:171","msg":"trace[515583996] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"151.549381ms","start":"2026-02-25T22:01:47.892998Z","end":"2026-02-25T22:01:48.044547Z","steps":["trace[515583996] 'process raft request' (duration: 151.406177ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:48.044578Z","caller":"traceutil/trace.go:171","msg":"trace[764668015] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"148.322617ms","start":"2026-02-25T22:01:47.896221Z","end":"2026-02-25T22:01:48.044543Z","steps":["trace[764668015] 'read index received' (duration: 148.306476ms)","trace[764668015] 'applied index is now lower than readState.Index' (duration: 12.431µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:48.044846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.361768ms","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-25T22:01:48.044887Z","caller":"traceutil/trace.go:171","msg":"trace[459790579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"132.4417ms","start":"2026-02-25T22:01:47.912433Z","end":"2026-02-25T22:01:48.044875Z","steps":["trace[459790579] 'agreement among raft nodes before linearized reading' (duration: 132.300725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:48.044999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.738799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:01:48.045075Z","caller":"traceutil/trace.go:171","msg":"trace[1580821740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"131.915055ms","start":"2026-02-25T22:01:47.913143Z","end":"2026-02-25T22:01:48.045058Z","steps":["trace[1580821740] 'agreement among raft nodes before linearized reading' (duration: 131.622676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:48.045127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.867683ms","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-25T22:01:48.04522Z","caller":"traceutil/trace.go:171","msg":"trace[185676045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"149.004197ms","start":"2026-02-25T22:01:47.896194Z","end":"2026-02-25T22:01:48.045198Z","steps":["trace[185676045] 'agreement among raft nodes before linearized reading' (duration: 148.40636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:48.715079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734652127,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T22:01:48.842141Z","caller":"traceutil/trace.go:171","msg":"trace[52087966] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"793.427455ms","start":"2026-02-25T22:01:48.048686Z","end":"2026-02-25T22:01:48.842113Z","steps":["trace[52087966] 'process raft request' (duration: 750.34269ms)","trace[52087966] 'compare' (duration: 42.968672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:48.842307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:48.048664Z","time spent":"793.545139ms","remote":"127.0.0.1:56196","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-25T22:01:49.9727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.173563032s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:01:49.973071Z","caller":"traceutil/trace.go:171","msg":"trace[1795223614] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"1.758218696s","start":"2026-02-25T22:01:48.214827Z","end":"2026-02-25T22:01:49.973046Z","steps":["trace[1795223614] 'read index received' (duration: 584.353625ms)","trace[1795223614] 'applied index is now lower than readState.Index' (duration: 1.173864221s)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:49.97314Z","caller":"traceutil/trace.go:171","msg":"trace[50690278] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.924381566s","start":"2026-02-25T22:01:48.048723Z","end":"2026-02-25T22:01:49.973105Z","steps":["trace[50690278] 'process raft request' (duration: 1.92415739s)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:49.973204Z","caller":"traceutil/trace.go:171","msg":"trace[561004791] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.923626514s","start":"2026-02-25T22:01:48.04954Z","end":"2026-02-25T22:01:49.973166Z","steps":["trace[561004791] 'process raft request' (duration: 1.92346983s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:49.973294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758449663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2952n\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-25T22:01:49.973338Z","caller":"traceutil/trace.go:171","msg":"trace[15499834] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2952n; range_end:; response_count:1; response_revision:578; }","duration":"1.758543165s","start":"2026-02-25T22:01:48.214783Z","end":"2026-02-25T22:01:49.973326Z","steps":["trace[15499834] 'agreement among raft nodes before linearized reading' (duration: 1.758357819s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:49.973365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:48.049532Z","time spent":"1.923748408s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:49.973375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:48.214765Z","time spent":"1.758601697s","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-2952n\" "} {"level":"warn","ts":"2026-02-25T22:01:49.973364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622780028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:01:49.973414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:48.048702Z","time spent":"1.924551211s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:01:49.973478Z","caller":"traceutil/trace.go:171","msg":"trace[305417707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"1.622955883s","start":"2026-02-25T22:01:48.350499Z","end":"2026-02-25T22:01:49.973454Z","steps":["trace[305417707] 'agreement among raft nodes before linearized reading' (duration: 1.622736546s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:49.973535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:48.350479Z","time spent":"1.623046836s","remote":"127.0.0.1:55936","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-25T22:01:49.973647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.910428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T22:01:49.973707Z","caller":"traceutil/trace.go:171","msg":"trace[676387813] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:578; }","duration":"958.00645ms","start":"2026-02-25T22:01:49.01568Z","end":"2026-02-25T22:01:49.973687Z","steps":["trace[676387813] 'agreement among raft nodes before linearized reading' (duration: 957.835005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:49.973765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:49.015658Z","time spent":"958.090983ms","remote":"127.0.0.1:55968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-02-25T22:01:50.109284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.631435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:50.109369Z","caller":"traceutil/trace.go:171","msg":"trace[411333725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"133.762778ms","start":"2026-02-25T22:01:49.975593Z","end":"2026-02-25T22:01:50.109356Z","steps":["trace[411333725] 'agreement among raft nodes before linearized reading' (duration: 133.613564ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:50.10973Z","caller":"traceutil/trace.go:171","msg":"trace[826957033] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:603; }","duration":"133.511131ms","start":"2026-02-25T22:01:49.975626Z","end":"2026-02-25T22:01:50.109137Z","steps":["trace[826957033] 'read index received' (duration: 133.48158ms)","trace[826957033] 'applied index is now lower than readState.Index' (duration: 26.721µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:50.610746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734652136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:01:51.111494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734652136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:01:51.55412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.445004957s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:01:51.554723Z","caller":"traceutil/trace.go:171","msg":"trace[1163845998] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.567138914s","start":"2026-02-25T22:01:49.987556Z","end":"2026-02-25T22:01:51.554695Z","steps":["trace[1163845998] 'process raft request' (duration: 1.566901177s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:51.554879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:49.98754Z","time spent":"1.567259297s","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:01:51.565121Z","caller":"traceutil/trace.go:171","msg":"trace[1382493328] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"1.455331791s","start":"2026-02-25T22:01:50.109768Z","end":"2026-02-25T22:01:51.5651Z","steps":["trace[1382493328] 'read index received' (duration: 1.444785211s)","trace[1382493328] 'applied index is now lower than readState.Index' (duration: 10.54573ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:51.565151Z","caller":"traceutil/trace.go:171","msg":"trace[1754916039] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.454767904s","start":"2026-02-25T22:01:50.110357Z","end":"2026-02-25T22:01:51.565125Z","steps":["trace[1754916039] 'process raft request' (duration: 1.454602909s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:51.565255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:50.11033Z","time spent":"1.454869266s","remote":"127.0.0.1:55968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:01:51.565292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447377507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:51.565334Z","caller":"traceutil/trace.go:171","msg":"trace[1185658814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"1.447470429s","start":"2026-02-25T22:01:50.117853Z","end":"2026-02-25T22:01:51.565323Z","steps":["trace[1185658814] 'agreement among raft nodes before linearized reading' (duration: 1.447366326s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:51.56536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516920619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T22:01:51.565371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:50.117839Z","time spent":"1.44752379s","remote":"127.0.0.1:55936","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-25T22:01:51.565404Z","caller":"traceutil/trace.go:171","msg":"trace[1432351210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"1.517026302s","start":"2026-02-25T22:01:50.048368Z","end":"2026-02-25T22:01:51.565394Z","steps":["trace[1432351210] 'agreement among raft nodes before linearized reading' (duration: 1.516844947s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:51.565438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:50.048348Z","time spent":"1.517082703s","remote":"127.0.0.1:56196","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-25T22:01:51.565436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.248858ms","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-25T22:01:51.565505Z","caller":"traceutil/trace.go:171","msg":"trace[1613353828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"719.361661ms","start":"2026-02-25T22:01:50.846128Z","end":"2026-02-25T22:01:51.56549Z","steps":["trace[1613353828] 'agreement among raft nodes before linearized reading' (duration: 719.154785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:51.565537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:50.846114Z","time spent":"719.417473ms","remote":"127.0.0.1:56196","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-25T22:01:51.75815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.364011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:01:51.758421Z","caller":"traceutil/trace.go:171","msg":"trace[414911333] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"187.844048ms","start":"2026-02-25T22:01:51.570556Z","end":"2026-02-25T22:01:51.7584Z","steps":["trace[414911333] 'read index received' (duration: 74.158218ms)","trace[414911333] 'applied index is now lower than readState.Index' (duration: 113.6847ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:51.758543Z","caller":"traceutil/trace.go:171","msg":"trace[14030138] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"189.652791ms","start":"2026-02-25T22:01:51.568875Z","end":"2026-02-25T22:01:51.758528Z","steps":["trace[14030138] 'process raft request' (duration: 75.839907ms)","trace[14030138] 'compare' (duration: 113.130134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:51.758602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.042484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T22:01:51.758624Z","caller":"traceutil/trace.go:171","msg":"trace[1966911606] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:582; }","duration":"188.121746ms","start":"2026-02-25T22:01:51.570496Z","end":"2026-02-25T22:01:51.758617Z","steps":["trace[1966911606] 'agreement among raft nodes before linearized reading' (duration: 188.073064ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:51.758725Z","caller":"traceutil/trace.go:171","msg":"trace[1664087835] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"189.806336ms","start":"2026-02-25T22:01:51.568893Z","end":"2026-02-25T22:01:51.758699Z","steps":["trace[1664087835] 'process raft request' (duration: 189.432715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:51.758796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.120748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:51.758839Z","caller":"traceutil/trace.go:171","msg":"trace[2045675653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"185.17702ms","start":"2026-02-25T22:01:51.573649Z","end":"2026-02-25T22:01:51.758826Z","steps":["trace[2045675653] 'agreement among raft nodes before linearized reading' (duration: 185.111448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:53.849433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734652148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:01:54.349999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734652148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:01:54.850609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734652148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:01:55.349061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000614092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T22:01:55.34917Z","caller":"traceutil/trace.go:171","msg":"trace[877442841] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000740155s","start":"2026-02-25T22:01:53.348411Z","end":"2026-02-25T22:01:55.349151Z","steps":["trace[877442841] 'agreement among raft nodes before linearized reading' (duration: 2.000608272s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.349237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:53.348354Z","time spent":"2.000867599s","remote":"127.0.0.1:55936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 22:01:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T22:01:55.351219Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734652148,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:01:55.750884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.772883154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:01:55.750962Z","caller":"traceutil/trace.go:171","msg":"trace[199313319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"3.773012928s","start":"2026-02-25T22:01:51.977931Z","end":"2026-02-25T22:01:55.750944Z","steps":["trace[199313319] 'range keys from in-memory index tree' (duration: 3.772684188s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.751003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:51.977905Z","time spent":"3.77308946s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:01:55.751237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.772598825s","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-25T22:01:55.751269Z","caller":"traceutil/trace.go:171","msg":"trace[1513620867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"3.772706768s","start":"2026-02-25T22:01:51.978552Z","end":"2026-02-25T22:01:55.751259Z","steps":["trace[1513620867] 'range keys from in-memory index tree' (duration: 3.772439681s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.751297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:51.97849Z","time spent":"3.772799711s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:01:55.751429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.810443841s","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-25T22:01:55.751514Z","caller":"traceutil/trace.go:171","msg":"trace[552724575] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:582; }","duration":"2.810532014s","start":"2026-02-25T22:01:52.940969Z","end":"2026-02-25T22:01:55.751501Z","steps":["trace[552724575] 'range keys from in-memory index tree' (duration: 2.810433101s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.7925Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.515167923s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:01:55.792974Z","caller":"traceutil/trace.go:171","msg":"trace[1143910424] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"3.515746991s","start":"2026-02-25T22:01:52.277204Z","end":"2026-02-25T22:01:55.792951Z","steps":["trace[1143910424] 'process raft request' (duration: 3.515602667s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.793262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:52.277177Z","time spent":"3.515911996s","remote":"127.0.0.1:56196","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-25T22:01:55.841309Z","caller":"traceutil/trace.go:171","msg":"trace[1894353198] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"2.492865482s","start":"2026-02-25T22:01:53.348417Z","end":"2026-02-25T22:01:55.841282Z","steps":["trace[1894353198] 'read index received' (duration: 2.444363198s)","trace[1894353198] 'applied index is now lower than readState.Index' (duration: 48.499554ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:01:55.84156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079291883s","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-25T22:01:55.841599Z","caller":"traceutil/trace.go:171","msg":"trace[580145623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"2.079410507s","start":"2026-02-25T22:01:53.762179Z","end":"2026-02-25T22:01:55.841589Z","steps":["trace[580145623] 'agreement among raft nodes before linearized reading' (duration: 2.079258722s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.841641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:53.762156Z","time spent":"2.079474449s","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T22:01:55.841867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079584632s","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-25T22:01:55.8419Z","caller":"traceutil/trace.go:171","msg":"trace[1336807810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"2.079713346s","start":"2026-02-25T22:01:53.762179Z","end":"2026-02-25T22:01:55.841892Z","steps":["trace[1336807810] 'agreement among raft nodes before linearized reading' (duration: 2.079629834s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.841897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.617432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:01:55.841988Z","caller":"traceutil/trace.go:171","msg":"trace[492273825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"492.749406ms","start":"2026-02-25T22:01:55.349215Z","end":"2026-02-25T22:01:55.841964Z","steps":["trace[492273825] 'agreement among raft nodes before linearized reading' (duration: 492.590402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:55.841928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:53.762155Z","time spent":"2.079766168s","remote":"127.0.0.1:56196","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-25T22:01:55.84204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:55.349194Z","time spent":"492.837388ms","remote":"127.0.0.1:55934","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-25T22:01:56.403389Z","caller":"traceutil/trace.go:171","msg":"trace[248652502] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"150.945515ms","start":"2026-02-25T22:01:56.252428Z","end":"2026-02-25T22:01:56.403373Z","steps":["trace[248652502] 'process raft request' (duration: 150.248084ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:01:58.004639Z","caller":"traceutil/trace.go:171","msg":"trace[468837814] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"159.596308ms","start":"2026-02-25T22:01:57.845014Z","end":"2026-02-25T22:01:58.00461Z","steps":["trace[468837814] 'read index received' (duration: 159.333511ms)","trace[468837814] 'applied index is now lower than readState.Index' (duration: 260.797µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:01:58.004791Z","caller":"traceutil/trace.go:171","msg":"trace[1743845781] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"622.24546ms","start":"2026-02-25T22:01:57.382532Z","end":"2026-02-25T22:01:58.004777Z","steps":["trace[1743845781] 'process raft request' (duration: 621.90824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:58.004907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:01:57.382516Z","time spent":"622.314843ms","remote":"127.0.0.1:56196","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-25T22:01:58.005328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.876958ms","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-25T22:01:58.005411Z","caller":"traceutil/trace.go:171","msg":"trace[2020315914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"158.009832ms","start":"2026-02-25T22:01:57.847383Z","end":"2026-02-25T22:01:58.005393Z","steps":["trace[2020315914] 'agreement among raft nodes before linearized reading' (duration: 157.487787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:58.005427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.729785ms","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-25T22:01:58.005474Z","caller":"traceutil/trace.go:171","msg":"trace[1881151561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"106.807648ms","start":"2026-02-25T22:01:57.898655Z","end":"2026-02-25T22:01:58.005462Z","steps":["trace[1881151561] 'agreement among raft nodes before linearized reading' (duration: 106.649333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:58.005631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.579317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:01:58.005734Z","caller":"traceutil/trace.go:171","msg":"trace[203293013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"160.750092ms","start":"2026-02-25T22:01:57.844958Z","end":"2026-02-25T22:01:58.005708Z","steps":["trace[203293013] 'agreement among raft nodes before linearized reading' (duration: 160.311049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:01:58.005712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.990373ms","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-25T22:01:58.005823Z","caller":"traceutil/trace.go:171","msg":"trace[1794453332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"108.144558ms","start":"2026-02-25T22:01:57.897653Z","end":"2026-02-25T22:01:58.005797Z","steps":["trace[1794453332] 'agreement among raft nodes before linearized reading' (duration: 107.742956ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:03:16.7838Z","caller":"traceutil/trace.go:171","msg":"trace[1533626940] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"367.685167ms","start":"2026-02-25T22:03:16.416088Z","end":"2026-02-25T22:03:16.783773Z","steps":["trace[1533626940] 'process raft request' (duration: 367.511852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:16.783971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:16.416071Z","time spent":"367.819221ms","remote":"127.0.0.1:56196","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-25T22:03:17.220601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.057458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:03:17.220804Z","caller":"traceutil/trace.go:171","msg":"trace[699130640] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1039; }","duration":"801.757105ms","start":"2026-02-25T22:03:16.419026Z","end":"2026-02-25T22:03:17.220783Z","steps":["trace[699130640] 'read index received' (duration: 364.602196ms)","trace[699130640] 'applied index is now lower than readState.Index' (duration: 437.153849ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:03:17.220889Z","caller":"traceutil/trace.go:171","msg":"trace[1717414735] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"801.589249ms","start":"2026-02-25T22:03:16.41929Z","end":"2026-02-25T22:03:17.220879Z","steps":["trace[1717414735] 'process raft request' (duration: 801.403074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:17.220951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:16.419265Z","time spent":"801.652202ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:03:17.221054Z","caller":"traceutil/trace.go:171","msg":"trace[123349176] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"803.648561ms","start":"2026-02-25T22:03:16.417385Z","end":"2026-02-25T22:03:17.221033Z","steps":["trace[123349176] 'process raft request' (duration: 485.051407ms)","trace[123349176] 'compare' (duration: 317.898654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:03:17.22115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:16.417376Z","time spent":"803.737033ms","remote":"127.0.0.1:56196","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-25T22:03:17.221259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.714623ms","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-25T22:03:17.221295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.251839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T22:03:17.221335Z","caller":"traceutil/trace.go:171","msg":"trace[1296690613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"801.854627ms","start":"2026-02-25T22:03:16.419461Z","end":"2026-02-25T22:03:17.221316Z","steps":["trace[1296690613] 'agreement among raft nodes before linearized reading' (duration: 801.61227ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:03:17.22135Z","caller":"traceutil/trace.go:171","msg":"trace[2110212868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"802.335992ms","start":"2026-02-25T22:03:16.419002Z","end":"2026-02-25T22:03:17.221338Z","steps":["trace[2110212868] 'agreement among raft nodes before linearized reading' (duration: 802.174597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:17.221383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:16.418992Z","time spent":"802.383763ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:03:17.22138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:16.419451Z","time spent":"801.918269ms","remote":"127.0.0.1:56196","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-25T22:03:25.752043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018685734653193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:03:25.900098Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.083767853s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:03:25.90047Z","caller":"traceutil/trace.go:171","msg":"trace[1174105800] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"649.575371ms","start":"2026-02-25T22:03:25.25087Z","end":"2026-02-25T22:03:25.900445Z","steps":["trace[1174105800] 'read index received' (duration: 649.371955ms)","trace[1174105800] 'applied index is now lower than readState.Index' (duration: 202.436µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:03:25.900595Z","caller":"traceutil/trace.go:171","msg":"trace[412472291] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.08434612s","start":"2026-02-25T22:03:24.816204Z","end":"2026-02-25T22:03:25.90055Z","steps":["trace[412472291] 'process raft request' (duration: 1.084084282s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.900705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.826348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T22:03:25.900746Z","caller":"traceutil/trace.go:171","msg":"trace[1613844698] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"649.927072ms","start":"2026-02-25T22:03:25.250807Z","end":"2026-02-25T22:03:25.900734Z","steps":["trace[1613844698] 'agreement among raft nodes before linearized reading' (duration: 649.760456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.900796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:25.250755Z","time spent":"650.031155ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T22:03:25.900801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:24.816171Z","time spent":"1.084536396s","remote":"127.0.0.1:56196","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-25T22:03:25.900832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.132553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:03:25.900879Z","caller":"traceutil/trace.go:171","msg":"trace[2058178852] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1023; }","duration":"625.242697ms","start":"2026-02-25T22:03:25.275618Z","end":"2026-02-25T22:03:25.900861Z","steps":["trace[2058178852] 'agreement among raft nodes before linearized reading' (duration: 625.111853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.900912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:25.275511Z","time spent":"625.394351ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:03:25.90088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.366942ms","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-25T22:03:25.900983Z","caller":"traceutil/trace.go:171","msg":"trace[2007077950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"639.515297ms","start":"2026-02-25T22:03:25.261447Z","end":"2026-02-25T22:03:25.900962Z","steps":["trace[2007077950] 'agreement among raft nodes before linearized reading' (duration: 639.208027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.900972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.119429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:03:25.901033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:25.261436Z","time spent":"639.588729ms","remote":"127.0.0.1:56196","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-25T22:03:25.901056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.551371ms","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-25T22:03:25.901082Z","caller":"traceutil/trace.go:171","msg":"trace[640820107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"549.296224ms","start":"2026-02-25T22:03:25.351759Z","end":"2026-02-25T22:03:25.901056Z","steps":["trace[640820107] 'agreement among raft nodes before linearized reading' (duration: 549.087798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.901041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.909072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T22:03:25.90115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:25.351743Z","time spent":"549.399237ms","remote":"127.0.0.1:55936","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-25T22:03:25.901182Z","caller":"traceutil/trace.go:171","msg":"trace[1580892221] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1023; }","duration":"355.18434ms","start":"2026-02-25T22:03:25.54597Z","end":"2026-02-25T22:03:25.901154Z","steps":["trace[1580892221] 'agreement among raft nodes before linearized reading' (duration: 354.869361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.901237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:25.545948Z","time spent":"355.277862ms","remote":"127.0.0.1:38684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-02-25T22:03:25.901095Z","caller":"traceutil/trace.go:171","msg":"trace[723495660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"633.610823ms","start":"2026-02-25T22:03:25.267469Z","end":"2026-02-25T22:03:25.90108Z","steps":["trace[723495660] 'agreement among raft nodes before linearized reading' (duration: 633.396927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.901392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:25.267456Z","time spent":"633.926782ms","remote":"127.0.0.1:56196","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-25T22:03:25.901675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.015757ms","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-25T22:03:25.901734Z","caller":"traceutil/trace.go:171","msg":"trace[613688619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"628.09241ms","start":"2026-02-25T22:03:25.273626Z","end":"2026-02-25T22:03:25.901718Z","steps":["trace[613688619] 'agreement among raft nodes before linearized reading' (duration: 627.109851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:03:25.901763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:03:25.273615Z","time spent":"628.140121ms","remote":"127.0.0.1:56196","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-25T22:03:26.039569Z","caller":"traceutil/trace.go:171","msg":"trace[926494595] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"133.454433ms","start":"2026-02-25T22:03:25.906077Z","end":"2026-02-25T22:03:26.039531Z","steps":["trace[926494595] 'process raft request' (duration: 70.494333ms)","trace[926494595] 'compare' (duration: 62.684982ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:03:26.040335Z","caller":"traceutil/trace.go:171","msg":"trace[360574848] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"133.74713ms","start":"2026-02-25T22:03:25.906573Z","end":"2026-02-25T22:03:26.04032Z","steps":["trace[360574848] 'process raft request' (duration: 132.909576ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:03:26.040398Z","caller":"traceutil/trace.go:171","msg":"trace[801410420] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"133.40308ms","start":"2026-02-25T22:03:25.906957Z","end":"2026-02-25T22:03:26.04036Z","steps":["trace[801410420] 'process raft request' (duration: 133.04659ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:03:26.040456Z","caller":"traceutil/trace.go:171","msg":"trace[1933457482] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"133.095892ms","start":"2026-02-25T22:03:25.907347Z","end":"2026-02-25T22:03:26.040443Z","steps":["trace[1933457482] 'process raft request' (duration: 132.790043ms)"],"step_count":1}