{"level":"warn","ts":"2026-02-19T02:51:04.170964Z","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-19T02:51:04.171078Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.224: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.19.213.224:2380","--initial-cluster=instance=https://199.19.213.224:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.224:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.224: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-19T02:51:04.171227Z","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-19T02:51:04.171253Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-02-19T02:51:04.171327Z","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-19T02:51:04.172577Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"]} {"level":"info","ts":"2026-02-19T02:51:04.172753Z","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.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.224: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-19T02:51:04.177308Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.247002ms"} {"level":"info","ts":"2026-02-19T02:51:04.186263Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9870e1635c99d2ec","cluster-id":"185398c074a8a76b"} {"level":"info","ts":"2026-02-19T02:51:04.186428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T02:51:04.186544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 0"} {"level":"info","ts":"2026-02-19T02:51:04.186568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9870e1635c99d2ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T02:51:04.186726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became follower at term 1"} {"level":"info","ts":"2026-02-19T02:51:04.186832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"warn","ts":"2026-02-19T02:51:04.194883Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T02:51:04.196424Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T02:51:04.197704Z","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-19T02:51:04.200285Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9870e1635c99d2ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T02:51:04.201179Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9870e1635c99d2ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T02:51:04.201298Z","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-19T02:51:04.20174Z","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-19T02:51:04.20175Z","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-19T02:51:04.203138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec switched to configuration voters=(10984527308028236524)"} {"level":"info","ts":"2026-02-19T02:51:04.20569Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","added-peer-id":"9870e1635c99d2ec","added-peer-peer-urls":["https://199.19.213.224:2380"]} {"level":"info","ts":"2026-02-19T02:51:04.207756Z","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-19T02:51:04.207968Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-02-19T02:51:04.208132Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.224:2380"} {"level":"info","ts":"2026-02-19T02:51:04.208133Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9870e1635c99d2ec","initial-advertise-peer-urls":["https://199.19.213.224:2380"],"listen-peer-urls":["https://199.19.213.224:2380"],"advertise-client-urls":["https://199.19.213.224:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.224:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T02:51:04.208242Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T02:51:05.188061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T02:51:05.188141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T02:51:05.188165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgPreVoteResp from 9870e1635c99d2ec at term 1"} {"level":"info","ts":"2026-02-19T02:51:05.188178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became candidate at term 2"} {"level":"info","ts":"2026-02-19T02:51:05.188186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec received MsgVoteResp from 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-02-19T02:51:05.188197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9870e1635c99d2ec became leader at term 2"} {"level":"info","ts":"2026-02-19T02:51:05.188206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9870e1635c99d2ec elected leader 9870e1635c99d2ec at term 2"} {"level":"info","ts":"2026-02-19T02:51:05.189051Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9870e1635c99d2ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.224:2379]}","request-path":"/0/members/9870e1635c99d2ec/attributes","cluster-id":"185398c074a8a76b","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T02:51:05.189055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:51:05.189074Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:51:05.189127Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:51:05.189324Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T02:51:05.189396Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T02:51:05.189988Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"185398c074a8a76b","local-member-id":"9870e1635c99d2ec","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:51:05.190168Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:51:05.190277Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:51:05.192089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T02:51:05.192417Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.224:2379"} {"level":"info","ts":"2026-02-19T02:51:07.617128Z","caller":"traceutil/trace.go:171","msg":"trace[391394397] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"143.739419ms","start":"2026-02-19T02:51:07.473348Z","end":"2026-02-19T02:51:07.617087Z","steps":["trace[391394397] 'read index received' (duration: 61.266965ms)","trace[391394397] 'applied index is now lower than readState.Index' (duration: 82.471174ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:07.617272Z","caller":"traceutil/trace.go:171","msg":"trace[618371801] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"145.153326ms","start":"2026-02-19T02:51:07.472076Z","end":"2026-02-19T02:51:07.617229Z","steps":["trace[618371801] 'process raft request' (duration: 62.548368ms)","trace[618371801] 'compare' (duration: 82.253938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:07.617345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.975575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T02:51:07.617435Z","caller":"traceutil/trace.go:171","msg":"trace[1638639956] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:72; }","duration":"144.089758ms","start":"2026-02-19T02:51:07.473327Z","end":"2026-02-19T02:51:07.617417Z","steps":["trace[1638639956] 'agreement among raft nodes before linearized reading' (duration: 143.926363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:07.617489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.83075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T02:51:07.617557Z","caller":"traceutil/trace.go:171","msg":"trace[2009729896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"102.952814ms","start":"2026-02-19T02:51:07.514588Z","end":"2026-02-19T02:51:07.617541Z","steps":["trace[2009729896] 'agreement among raft nodes before linearized reading' (duration: 102.79094ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:07.80216Z","caller":"traceutil/trace.go:171","msg":"trace[2098139071] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"118.456532ms","start":"2026-02-19T02:51:07.683664Z","end":"2026-02-19T02:51:07.80212Z","steps":["trace[2098139071] 'process raft request' (duration: 115.640528ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:08.17231Z","caller":"traceutil/trace.go:171","msg":"trace[388132132] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"298.93948ms","start":"2026-02-19T02:51:07.873343Z","end":"2026-02-19T02:51:08.172282Z","steps":["trace[388132132] 'process raft request' (duration: 264.950104ms)","trace[388132132] 'compare' (duration: 33.854913ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:08.502752Z","caller":"traceutil/trace.go:171","msg":"trace[1965191328] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"244.571146ms","start":"2026-02-19T02:51:08.258155Z","end":"2026-02-19T02:51:08.502727Z","steps":["trace[1965191328] 'process raft request' (duration: 224.23032ms)","trace[1965191328] 'compare' (duration: 20.217563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:08.859607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.470835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T02:51:08.859817Z","caller":"traceutil/trace.go:171","msg":"trace[1714950607] linearizableReadLoop","detail":"{readStateIndex:88; appliedIndex:87; }","duration":"145.196107ms","start":"2026-02-19T02:51:08.7146Z","end":"2026-02-19T02:51:08.859796Z","steps":["trace[1714950607] 'read index received' (duration: 51.102µs)","trace[1714950607] 'applied index is now lower than readState.Index' (duration: 145.143475ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:08.859863Z","caller":"traceutil/trace.go:171","msg":"trace[973074838] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"322.128421ms","start":"2026-02-19T02:51:08.537709Z","end":"2026-02-19T02:51:08.859837Z","steps":["trace[973074838] 'process raft request' (duration: 68.835084ms)","trace[973074838] 'compare' (duration: 252.321192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:08.859964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.361671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-19T02:51:08.860094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:08.537687Z","time spent":"322.362738ms","remote":"127.0.0.1:56476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:08.860155Z","caller":"traceutil/trace.go:171","msg":"trace[1894432953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"145.594877ms","start":"2026-02-19T02:51:08.714542Z","end":"2026-02-19T02:51:08.860136Z","steps":["trace[1894432953] 'agreement among raft nodes before linearized reading' (duration: 145.32286ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:09.061716Z","caller":"traceutil/trace.go:171","msg":"trace[29515997] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"134.515075ms","start":"2026-02-19T02:51:08.927162Z","end":"2026-02-19T02:51:09.061677Z","steps":["trace[29515997] 'process raft request' (duration: 38.841584ms)","trace[29515997] 'compare' (duration: 95.494687ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:09.224179Z","caller":"traceutil/trace.go:171","msg":"trace[1145606089] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:92; }","duration":"135.716967ms","start":"2026-02-19T02:51:09.088439Z","end":"2026-02-19T02:51:09.224156Z","steps":["trace[1145606089] 'read index received' (duration: 74.35651ms)","trace[1145606089] 'applied index is now lower than readState.Index' (duration: 61.359437ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:09.224198Z","caller":"traceutil/trace.go:171","msg":"trace[475933487] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"159.057493ms","start":"2026-02-19T02:51:09.065112Z","end":"2026-02-19T02:51:09.224169Z","steps":["trace[475933487] 'process raft request' (duration: 97.728266ms)","trace[475933487] 'compare' (duration: 61.144832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:09.224323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.870461ms","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-19T02:51:09.224412Z","caller":"traceutil/trace.go:171","msg":"trace[1158735594] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"157.869551ms","start":"2026-02-19T02:51:09.066429Z","end":"2026-02-19T02:51:09.224299Z","steps":["trace[1158735594] 'process raft request' (duration: 157.675826ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:09.224829Z","caller":"traceutil/trace.go:171","msg":"trace[234580761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:90; }","duration":"136.038536ms","start":"2026-02-19T02:51:09.088382Z","end":"2026-02-19T02:51:09.22442Z","steps":["trace[234580761] 'agreement among raft nodes before linearized reading' (duration: 135.861522ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:09.538278Z","caller":"traceutil/trace.go:171","msg":"trace[100001934] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"111.434747ms","start":"2026-02-19T02:51:09.426828Z","end":"2026-02-19T02:51:09.538262Z","steps":["trace[100001934] 'process raft request' (duration: 106.896878ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:09.648703Z","caller":"traceutil/trace.go:171","msg":"trace[780990241] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"107.840692ms","start":"2026-02-19T02:51:09.540845Z","end":"2026-02-19T02:51:09.648685Z","steps":["trace[780990241] 'read index received' (duration: 107.195925ms)","trace[780990241] 'applied index is now lower than readState.Index' (duration: 643.847µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:09.648746Z","caller":"traceutil/trace.go:171","msg":"trace[1733242818] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"108.189041ms","start":"2026-02-19T02:51:09.540529Z","end":"2026-02-19T02:51:09.648718Z","steps":["trace[1733242818] 'process raft request' (duration: 107.604855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:09.648957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.092908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-19T02:51:09.649075Z","caller":"traceutil/trace.go:171","msg":"trace[1082045970] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:0; response_revision:100; }","duration":"108.251563ms","start":"2026-02-19T02:51:09.54081Z","end":"2026-02-19T02:51:09.649061Z","steps":["trace[1082045970] 'agreement among raft nodes before linearized reading' (duration: 107.984976ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:11.59934Z","caller":"traceutil/trace.go:171","msg":"trace[870714220] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:212; }","duration":"325.462799ms","start":"2026-02-19T02:51:11.273846Z","end":"2026-02-19T02:51:11.599309Z","steps":["trace[870714220] 'read index received' (duration: 325.239173ms)","trace[870714220] 'applied index is now lower than readState.Index' (duration: 222.686µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:11.599358Z","caller":"traceutil/trace.go:171","msg":"trace[562618610] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"674.613273ms","start":"2026-02-19T02:51:10.924725Z","end":"2026-02-19T02:51:11.599339Z","steps":["trace[562618610] 'process raft request' (duration: 674.451069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:11.599595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.736056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-19T02:51:11.599636Z","caller":"traceutil/trace.go:171","msg":"trace[1761175967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:208; }","duration":"325.821779ms","start":"2026-02-19T02:51:11.273803Z","end":"2026-02-19T02:51:11.599625Z","steps":["trace[1761175967] 'agreement among raft nodes before linearized reading' (duration: 325.632944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:11.59974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:11.273783Z","time spent":"325.945482ms","remote":"127.0.0.1:56414","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-19T02:51:11.599763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.836709ms","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-19T02:51:11.599864Z","caller":"traceutil/trace.go:171","msg":"trace[204476917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:208; }","duration":"325.989833ms","start":"2026-02-19T02:51:11.273848Z","end":"2026-02-19T02:51:11.599838Z","steps":["trace[204476917] 'agreement among raft nodes before linearized reading' (duration: 325.792518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:11.599913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:11.273827Z","time spent":"326.076896ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:51:11.600475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:10.924711Z","time spent":"674.816819ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3385,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:15.744157Z","caller":"traceutil/trace.go:171","msg":"trace[1062007274] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"122.399987ms","start":"2026-02-19T02:51:15.621727Z","end":"2026-02-19T02:51:15.744127Z","steps":["trace[1062007274] 'read index received' (duration: 122.215852ms)","trace[1062007274] 'applied index is now lower than readState.Index' (duration: 182.915µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:15.744292Z","caller":"traceutil/trace.go:171","msg":"trace[2108977978] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"162.237046ms","start":"2026-02-19T02:51:15.582033Z","end":"2026-02-19T02:51:15.74427Z","steps":["trace[2108977978] 'process raft request' (duration: 161.956519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:15.744501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.695994ms","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-19T02:51:15.744638Z","caller":"traceutil/trace.go:171","msg":"trace[1350894937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:287; }","duration":"122.89942ms","start":"2026-02-19T02:51:15.621684Z","end":"2026-02-19T02:51:15.744584Z","steps":["trace[1350894937] 'agreement among raft nodes before linearized reading' (duration: 122.600932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:15.744759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.183143ms","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-19T02:51:15.744919Z","caller":"traceutil/trace.go:171","msg":"trace[1217005110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:287; }","duration":"119.396558ms","start":"2026-02-19T02:51:15.625502Z","end":"2026-02-19T02:51:15.744899Z","steps":["trace[1217005110] 'agreement among raft nodes before linearized reading' (duration: 119.07648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:17.770258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187288142s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:51:17.770427Z","caller":"traceutil/trace.go:171","msg":"trace[993581462] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"1.189380417s","start":"2026-02-19T02:51:16.581017Z","end":"2026-02-19T02:51:17.770398Z","steps":["trace[993581462] 'read index received' (duration: 1.780746ms)","trace[993581462] 'applied index is now lower than readState.Index' (duration: 1.187597871s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:17.770588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189565762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:17.770581Z","caller":"traceutil/trace.go:171","msg":"trace[1756808164] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"1.212205049s","start":"2026-02-19T02:51:16.558312Z","end":"2026-02-19T02:51:17.770517Z","steps":["trace[1756808164] 'process raft request' (duration: 24.550137ms)","trace[1756808164] 'compare' (duration: 1.187135348s)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:17.770644Z","caller":"traceutil/trace.go:171","msg":"trace[1309899965] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:297; }","duration":"1.189674485s","start":"2026-02-19T02:51:16.580951Z","end":"2026-02-19T02:51:17.770626Z","steps":["trace[1309899965] 'agreement among raft nodes before linearized reading' (duration: 1.189601693s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:17.77067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.222013ms","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-19T02:51:17.770693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:16.580921Z","time spent":"1.189762138s","remote":"127.0.0.1:56348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"info","ts":"2026-02-19T02:51:17.770712Z","caller":"traceutil/trace.go:171","msg":"trace[2112935019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:297; }","duration":"774.313575ms","start":"2026-02-19T02:51:16.996387Z","end":"2026-02-19T02:51:17.770701Z","steps":["trace[2112935019] 'agreement among raft nodes before linearized reading' (duration: 774.238783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:17.770743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:16.996358Z","time spent":"774.377417ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:51:17.77059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13976083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-19T02:51:17.770954Z","caller":"traceutil/trace.go:171","msg":"trace[1625028787] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:297; }","duration":"1.1401724s","start":"2026-02-19T02:51:16.630766Z","end":"2026-02-19T02:51:17.770938Z","steps":["trace[1625028787] 'agreement among raft nodes before linearized reading' (duration: 1.13977312s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:17.771042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:16.630738Z","time spent":"1.140292024s","remote":"127.0.0.1:56348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-02-19T02:51:17.771328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:16.558274Z","time spent":"1.212413915s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:17.905301Z","caller":"traceutil/trace.go:171","msg":"trace[1962501325] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"130.550552ms","start":"2026-02-19T02:51:17.774724Z","end":"2026-02-19T02:51:17.905274Z","steps":["trace[1962501325] 'process raft request' (duration: 128.467567ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:17.944805Z","caller":"traceutil/trace.go:171","msg":"trace[1283795003] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:309; }","duration":"169.315114ms","start":"2026-02-19T02:51:17.775463Z","end":"2026-02-19T02:51:17.944778Z","steps":["trace[1283795003] 'read index received' (duration: 127.736928ms)","trace[1283795003] 'applied index is now lower than readState.Index' (duration: 41.577276ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:17.944932Z","caller":"traceutil/trace.go:171","msg":"trace[127167319] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"167.309271ms","start":"2026-02-19T02:51:17.777582Z","end":"2026-02-19T02:51:17.944891Z","steps":["trace[127167319] 'process raft request' (duration: 167.159497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:17.945017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.53361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-19T02:51:17.944818Z","caller":"traceutil/trace.go:171","msg":"trace[1855994530] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"169.569471ms","start":"2026-02-19T02:51:17.775215Z","end":"2026-02-19T02:51:17.944784Z","steps":["trace[1855994530] 'process raft request' (duration: 169.343595ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:17.945057Z","caller":"traceutil/trace.go:171","msg":"trace[1821185151] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:301; }","duration":"169.611152ms","start":"2026-02-19T02:51:17.775433Z","end":"2026-02-19T02:51:17.945044Z","steps":["trace[1821185151] 'agreement among raft nodes before linearized reading' (duration: 169.473808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:17.945215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.435228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-19T02:51:17.945267Z","caller":"traceutil/trace.go:171","msg":"trace[693522963] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:301; }","duration":"166.506929ms","start":"2026-02-19T02:51:17.77875Z","end":"2026-02-19T02:51:17.945257Z","steps":["trace[693522963] 'agreement among raft nodes before linearized reading' (duration: 166.416587ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:17.945336Z","caller":"traceutil/trace.go:171","msg":"trace[826082528] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"169.801167ms","start":"2026-02-19T02:51:17.775517Z","end":"2026-02-19T02:51:17.945318Z","steps":["trace[826082528] 'process raft request' (duration: 169.179721ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:22.554045Z","caller":"traceutil/trace.go:171","msg":"trace[1508895686] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"146.668887ms","start":"2026-02-19T02:51:22.40736Z","end":"2026-02-19T02:51:22.554029Z","steps":["trace[1508895686] 'process raft request' (duration: 146.574045ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:24.222957Z","caller":"traceutil/trace.go:171","msg":"trace[27059238] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"246.801088ms","start":"2026-02-19T02:51:23.976131Z","end":"2026-02-19T02:51:24.222932Z","steps":["trace[27059238] 'read index received' (duration: 246.52287ms)","trace[27059238] 'applied index is now lower than readState.Index' (duration: 277.098µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:24.223031Z","caller":"traceutil/trace.go:171","msg":"trace[134758930] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"288.548447ms","start":"2026-02-19T02:51:23.93445Z","end":"2026-02-19T02:51:24.222999Z","steps":["trace[134758930] 'process raft request' (duration: 288.289751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:24.223139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.997274ms","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-19T02:51:24.223201Z","caller":"traceutil/trace.go:171","msg":"trace[590382147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"247.105506ms","start":"2026-02-19T02:51:23.976081Z","end":"2026-02-19T02:51:24.223186Z","steps":["trace[590382147] 'agreement among raft nodes before linearized reading' (duration: 246.993823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:24.223278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.436472ms","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-19T02:51:24.223345Z","caller":"traceutil/trace.go:171","msg":"trace[553454664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:374; }","duration":"245.535055ms","start":"2026-02-19T02:51:23.977783Z","end":"2026-02-19T02:51:24.223318Z","steps":["trace[553454664] 'agreement among raft nodes before linearized reading' (duration: 245.412212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:24.223353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.582947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T02:51:24.223548Z","caller":"traceutil/trace.go:171","msg":"trace[1054727189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"217.856895ms","start":"2026-02-19T02:51:24.005676Z","end":"2026-02-19T02:51:24.223533Z","steps":["trace[1054727189] 'agreement among raft nodes before linearized reading' (duration: 217.580907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:25.572815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:51:25.754445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.42203608s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-19T02:51:25.895085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562611436s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:51:25.895374Z","caller":"traceutil/trace.go:171","msg":"trace[650775745] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.667655166s","start":"2026-02-19T02:51:24.227701Z","end":"2026-02-19T02:51:25.895356Z","steps":["trace[650775745] 'process raft request' (duration: 1.667505832s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:25.895459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:24.227674Z","time spent":"1.667743239s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:25.895495Z","caller":"traceutil/trace.go:171","msg":"trace[341634050] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"1.668072348s","start":"2026-02-19T02:51:24.227376Z","end":"2026-02-19T02:51:25.895448Z","steps":["trace[341634050] 'process raft request' (duration: 105.007199ms)","trace[341634050] 'compare' (duration: 1.562486603s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:25.895922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:24.227356Z","time spent":"1.668368475s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:25.896307Z","caller":"traceutil/trace.go:171","msg":"trace[1036578699] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"1.66817752s","start":"2026-02-19T02:51:24.228085Z","end":"2026-02-19T02:51:25.896262Z","steps":["trace[1036578699] 'process raft request' (duration: 1.667183704s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:25.896298Z","caller":"traceutil/trace.go:171","msg":"trace[608375182] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:388; }","duration":"823.914987ms","start":"2026-02-19T02:51:25.072346Z","end":"2026-02-19T02:51:25.896261Z","steps":["trace[608375182] 'read index received' (duration: 682.267781ms)","trace[608375182] 'applied index is now lower than readState.Index' (duration: 141.645676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:25.896401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:24.228058Z","time spent":"1.668287393s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:51:25.896868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.515143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:25.89692Z","caller":"traceutil/trace.go:171","msg":"trace[1661361922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"824.601645ms","start":"2026-02-19T02:51:25.072306Z","end":"2026-02-19T02:51:25.896907Z","steps":["trace[1661361922] 'agreement among raft nodes before linearized reading' (duration: 824.076091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:25.896951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:25.072284Z","time spent":"824.660487ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:51:26.278978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.790174ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T02:51:26.27906Z","caller":"traceutil/trace.go:171","msg":"trace[1454342647] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"380.579936ms","start":"2026-02-19T02:51:25.898467Z","end":"2026-02-19T02:51:26.279047Z","steps":["trace[1454342647] 'read index received' (duration: 49.501µs)","trace[1454342647] 'applied index is now lower than readState.Index' (duration: 380.529305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:26.279154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.677939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:26.27923Z","caller":"traceutil/trace.go:171","msg":"trace[82812545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"380.776051ms","start":"2026-02-19T02:51:25.898423Z","end":"2026-02-19T02:51:26.279199Z","steps":["trace[82812545] 'agreement among raft nodes before linearized reading' (duration: 380.671659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:26.279273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:25.898407Z","time spent":"380.857854ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:51:26.760055Z","caller":"traceutil/trace.go:171","msg":"trace[748276905] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"472.932972ms","start":"2026-02-19T02:51:26.287096Z","end":"2026-02-19T02:51:26.760029Z","steps":["trace[748276905] 'process raft request' (duration: 472.777398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:26.760207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:26.287075Z","time spent":"473.055696ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:51:32.480322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:51:32.980612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:51:33.481323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:51:33.981956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:51:34.070034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000585733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-19T02:51:34.07009Z","caller":"traceutil/trace.go:171","msg":"trace[811927319] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000712236s","start":"2026-02-19T02:51:32.069365Z","end":"2026-02-19T02:51:34.070077Z","steps":["trace[811927319] 'agreement among raft nodes before linearized reading' (duration: 2.000636714s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:34.070127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:32.069335Z","time spent":"2.000786638s","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:51:34.482374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703542,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:51:34.634628Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.677743273s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T02:51:34.63509Z","caller":"traceutil/trace.go:171","msg":"trace[1027373490] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"2.678252446s","start":"2026-02-19T02:51:31.956811Z","end":"2026-02-19T02:51:34.635064Z","steps":["trace[1027373490] 'process raft request' (duration: 2.678065121s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:34.635253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:31.956798Z","time spent":"2.678372719s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:35.053383Z","caller":"traceutil/trace.go:171","msg":"trace[1204074747] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"3.07351394s","start":"2026-02-19T02:51:31.97985Z","end":"2026-02-19T02:51:35.053364Z","steps":["trace[1204074747] 'read index received' (duration: 2.655033453s)","trace[1204074747] 'applied index is now lower than readState.Index' (duration: 418.479717ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:35.053495Z","caller":"traceutil/trace.go:171","msg":"trace[313378156] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"2.003525061s","start":"2026-02-19T02:51:33.049938Z","end":"2026-02-19T02:51:35.053463Z","steps":["trace[313378156] 'process raft request' (duration: 1.999792192s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:35.053587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204373645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:51:35.053608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.235632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:35.053629Z","caller":"traceutil/trace.go:171","msg":"trace[1129292624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"1.204445076s","start":"2026-02-19T02:51:33.849171Z","end":"2026-02-19T02:51:35.053616Z","steps":["trace[1129292624] 'agreement among raft nodes before linearized reading' (duration: 1.204376224s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:35.053638Z","caller":"traceutil/trace.go:171","msg":"trace[1066575462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"983.321844ms","start":"2026-02-19T02:51:34.070307Z","end":"2026-02-19T02:51:35.053629Z","steps":["trace[1066575462] 'agreement among raft nodes before linearized reading' (duration: 983.252802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:35.05363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:33.049912Z","time spent":"2.003647334s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:51:35.053653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:33.849156Z","time spent":"1.204492398s","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:51:35.053647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.266833335s","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-19T02:51:35.053668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:34.070292Z","time spent":"983.369055ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:51:35.05369Z","caller":"traceutil/trace.go:171","msg":"trace[2082247669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"2.266903157s","start":"2026-02-19T02:51:32.786775Z","end":"2026-02-19T02:51:35.053678Z","steps":["trace[2082247669] 'agreement among raft nodes before linearized reading' (duration: 2.266814285s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:35.053728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:32.786761Z","time spent":"2.266961309s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:51:35.05394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.074000843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T02:51:35.053971Z","caller":"traceutil/trace.go:171","msg":"trace[1765645009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"3.074104146s","start":"2026-02-19T02:51:31.979857Z","end":"2026-02-19T02:51:35.053962Z","steps":["trace[1765645009] 'agreement among raft nodes before linearized reading' (duration: 3.074001343s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:35.053994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:31.979843Z","time spent":"3.074146618s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:51:35.054158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.074300671s","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-19T02:51:35.054229Z","caller":"traceutil/trace.go:171","msg":"trace[351919620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"3.074365043s","start":"2026-02-19T02:51:31.979822Z","end":"2026-02-19T02:51:35.054187Z","steps":["trace[351919620] 'agreement among raft nodes before linearized reading' (duration: 3.073644104s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:35.054261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:31.979807Z","time spent":"3.074445746s","remote":"127.0.0.1:56414","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":"info","ts":"2026-02-19T02:51:36.760339Z","caller":"traceutil/trace.go:171","msg":"trace[914194802] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"117.619573ms","start":"2026-02-19T02:51:36.642695Z","end":"2026-02-19T02:51:36.760315Z","steps":["trace[914194802] 'process raft request' (duration: 117.406127ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:39.33115Z","caller":"traceutil/trace.go:171","msg":"trace[599539257] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"260.677406ms","start":"2026-02-19T02:51:39.070449Z","end":"2026-02-19T02:51:39.331126Z","steps":["trace[599539257] 'read index received' (duration: 260.575073ms)","trace[599539257] 'applied index is now lower than readState.Index' (duration: 101.403µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:39.331275Z","caller":"traceutil/trace.go:171","msg":"trace[367227672] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"563.922914ms","start":"2026-02-19T02:51:38.767307Z","end":"2026-02-19T02:51:39.33123Z","steps":["trace[367227672] 'process raft request' (duration: 563.685798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:39.33133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.86481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:39.33138Z","caller":"traceutil/trace.go:171","msg":"trace[415452967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"260.960663ms","start":"2026-02-19T02:51:39.070403Z","end":"2026-02-19T02:51:39.331363Z","steps":["trace[415452967] 'agreement among raft nodes before linearized reading' (duration: 260.871491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:39.331459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.488847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-19T02:51:39.331466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:38.767287Z","time spent":"564.074528ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:39.331537Z","caller":"traceutil/trace.go:171","msg":"trace[620038003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"250.59731ms","start":"2026-02-19T02:51:39.080919Z","end":"2026-02-19T02:51:39.331516Z","steps":["trace[620038003] 'agreement among raft nodes before linearized reading' (duration: 250.456576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:39.331566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.849866ms","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-19T02:51:39.33163Z","caller":"traceutil/trace.go:171","msg":"trace[450938849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"242.936437ms","start":"2026-02-19T02:51:39.088673Z","end":"2026-02-19T02:51:39.33161Z","steps":["trace[450938849] 'agreement among raft nodes before linearized reading' (duration: 242.797494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:39.331642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.006549ms","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-19T02:51:39.331729Z","caller":"traceutil/trace.go:171","msg":"trace[1225381936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"243.126853ms","start":"2026-02-19T02:51:39.088582Z","end":"2026-02-19T02:51:39.331708Z","steps":["trace[1225381936] 'agreement among raft nodes before linearized reading' (duration: 242.931868ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:43.681995Z","caller":"traceutil/trace.go:171","msg":"trace[1741531574] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"229.011455ms","start":"2026-02-19T02:51:43.452964Z","end":"2026-02-19T02:51:43.681975Z","steps":["trace[1741531574] 'process raft request' (duration: 228.950733ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:43.682154Z","caller":"traceutil/trace.go:171","msg":"trace[577276837] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"229.159629ms","start":"2026-02-19T02:51:43.452985Z","end":"2026-02-19T02:51:43.682144Z","steps":["trace[577276837] 'process raft request' (duration: 216.195568ms)","trace[577276837] 'compare' (duration: 12.632193ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:47.039216Z","caller":"traceutil/trace.go:171","msg":"trace[1745004714] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"276.644602ms","start":"2026-02-19T02:51:46.762552Z","end":"2026-02-19T02:51:47.039196Z","steps":["trace[1745004714] 'read index received' (duration: 276.53821ms)","trace[1745004714] 'applied index is now lower than readState.Index' (duration: 105.552µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:47.039327Z","caller":"traceutil/trace.go:171","msg":"trace[1564081949] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"475.603821ms","start":"2026-02-19T02:51:46.563689Z","end":"2026-02-19T02:51:47.039293Z","steps":["trace[1564081949] 'process raft request' (duration: 475.409777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:47.039376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.805425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:51:47.039488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:46.563668Z","time spent":"475.742823ms","remote":"127.0.0.1:56206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:47.039512Z","caller":"traceutil/trace.go:171","msg":"trace[860176435] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:513; }","duration":"276.965408ms","start":"2026-02-19T02:51:46.762525Z","end":"2026-02-19T02:51:47.03949Z","steps":["trace[860176435] 'agreement among raft nodes before linearized reading' (duration: 276.786594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:47.721844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.419093ms","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-19T02:51:47.721943Z","caller":"traceutil/trace.go:171","msg":"trace[578657248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"257.526266ms","start":"2026-02-19T02:51:47.464393Z","end":"2026-02-19T02:51:47.721919Z","steps":["trace[578657248] 'range keys from in-memory index tree' (duration: 257.249299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:47.721935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.06759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-19T02:51:47.722019Z","caller":"traceutil/trace.go:171","msg":"trace[1883957308] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:515; }","duration":"152.222373ms","start":"2026-02-19T02:51:47.569774Z","end":"2026-02-19T02:51:47.721996Z","steps":["trace[1883957308] 'range keys from in-memory index tree' (duration: 151.846575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:47.722019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.301593ms","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-19T02:51:47.722093Z","caller":"traceutil/trace.go:171","msg":"trace[580106087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"171.404474ms","start":"2026-02-19T02:51:47.550666Z","end":"2026-02-19T02:51:47.722071Z","steps":["trace[580106087] 'range keys from in-memory index tree' (duration: 171.122709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:47.722079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.975493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:47.722169Z","caller":"traceutil/trace.go:171","msg":"trace[1256485979] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"556.106026ms","start":"2026-02-19T02:51:47.16604Z","end":"2026-02-19T02:51:47.722146Z","steps":["trace[1256485979] 'range keys from in-memory index tree' (duration: 555.905422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:47.722227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:47.166024Z","time spent":"556.188847ms","remote":"127.0.0.1:56206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-19T02:51:47.722405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.430756ms","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-19T02:51:47.722499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.155234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-19T02:51:47.72257Z","caller":"traceutil/trace.go:171","msg":"trace[966080349] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:515; }","duration":"361.238777ms","start":"2026-02-19T02:51:47.361307Z","end":"2026-02-19T02:51:47.722545Z","steps":["trace[966080349] 'range keys from in-memory index tree' (duration: 361.041662ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:47.722524Z","caller":"traceutil/trace.go:171","msg":"trace[2032631754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:515; }","duration":"152.705872ms","start":"2026-02-19T02:51:47.569797Z","end":"2026-02-19T02:51:47.722503Z","steps":["trace[2032631754] 'range keys from in-memory index tree' (duration: 151.916487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:47.722624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:47.361292Z","time spent":"361.316938ms","remote":"127.0.0.1:56206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-19T02:51:48.559864Z","caller":"traceutil/trace.go:171","msg":"trace[624116650] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:562; }","duration":"232.445341ms","start":"2026-02-19T02:51:48.327401Z","end":"2026-02-19T02:51:48.559846Z","steps":["trace[624116650] 'read index received' (duration: 232.339719ms)","trace[624116650] 'applied index is now lower than readState.Index' (duration: 104.942µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:48.559971Z","caller":"traceutil/trace.go:171","msg":"trace[313605109] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"395.977629ms","start":"2026-02-19T02:51:48.163969Z","end":"2026-02-19T02:51:48.559947Z","steps":["trace[313605109] 'process raft request' (duration: 395.783525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:48.560069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.610733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:51:48.560131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:48.163946Z","time spent":"396.08391ms","remote":"127.0.0.1:56206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T02:51:48.560162Z","caller":"traceutil/trace.go:171","msg":"trace[155930378] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:543; }","duration":"232.714276ms","start":"2026-02-19T02:51:48.327423Z","end":"2026-02-19T02:51:48.560138Z","steps":["trace[155930378] 'agreement among raft nodes before linearized reading' (duration: 232.575674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:48.560099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.269407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:51:48.560194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.106832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-19T02:51:48.560235Z","caller":"traceutil/trace.go:171","msg":"trace[1120757175] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:543; }","duration":"232.41695ms","start":"2026-02-19T02:51:48.327805Z","end":"2026-02-19T02:51:48.560222Z","steps":["trace[1120757175] 'agreement among raft nodes before linearized reading' (duration: 232.265977ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:48.560262Z","caller":"traceutil/trace.go:171","msg":"trace[1887628926] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:543; }","duration":"198.232214ms","start":"2026-02-19T02:51:48.362013Z","end":"2026-02-19T02:51:48.560245Z","steps":["trace[1887628926] 'agreement among raft nodes before linearized reading' (duration: 198.039571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:48.560155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.636034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:48.560321Z","caller":"traceutil/trace.go:171","msg":"trace[523899700] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:543; }","duration":"232.93086ms","start":"2026-02-19T02:51:48.327383Z","end":"2026-02-19T02:51:48.560313Z","steps":["trace[523899700] 'agreement among raft nodes before linearized reading' (duration: 232.578353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.298002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703865,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:51:54.377349Z","caller":"traceutil/trace.go:171","msg":"trace[608210433] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"579.609937ms","start":"2026-02-19T02:51:53.797716Z","end":"2026-02-19T02:51:54.377326Z","steps":["trace[608210433] 'read index received' (duration: 579.434244ms)","trace[608210433] 'applied index is now lower than readState.Index' (duration: 174.563µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:51:54.377593Z","caller":"traceutil/trace.go:171","msg":"trace[1605761771] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"653.666625ms","start":"2026-02-19T02:51:53.723914Z","end":"2026-02-19T02:51:54.377581Z","steps":["trace[1605761771] 'process raft request' (duration: 653.294638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.377685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.723898Z","time spent":"653.721776ms","remote":"127.0.0.1:56330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:51:54.377835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.113667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-19T02:51:54.377865Z","caller":"traceutil/trace.go:171","msg":"trace[2109193533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:571; }","duration":"580.172719ms","start":"2026-02-19T02:51:53.797684Z","end":"2026-02-19T02:51:54.377856Z","steps":["trace[2109193533] 'agreement among raft nodes before linearized reading' (duration: 580.076527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.377937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.442891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:51:54.377962Z","caller":"traceutil/trace.go:171","msg":"trace[1706675222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"307.500163ms","start":"2026-02-19T02:51:54.070452Z","end":"2026-02-19T02:51:54.377952Z","steps":["trace[1706675222] 'agreement among raft nodes before linearized reading' (duration: 307.462962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.377975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:54.070433Z","time spent":"307.539643ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:51:54.377884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.797664Z","time spent":"580.215689ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T02:51:54.378024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.269641ms","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-19T02:51:54.378073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.013036ms","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-19T02:51:54.378121Z","caller":"traceutil/trace.go:171","msg":"trace[489281231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"578.390534ms","start":"2026-02-19T02:51:53.799704Z","end":"2026-02-19T02:51:54.378094Z","steps":["trace[489281231] 'agreement among raft nodes before linearized reading' (duration: 578.078617ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:54.37816Z","caller":"traceutil/trace.go:171","msg":"trace[1211280981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"578.111707ms","start":"2026-02-19T02:51:53.800023Z","end":"2026-02-19T02:51:54.378134Z","steps":["trace[1211280981] 'agreement among raft nodes before linearized reading' (duration: 577.917824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.378176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.799682Z","time spent":"578.483605ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:51:54.378208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.800017Z","time spent":"578.179609ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:51:54.378179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.371632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T02:51:54.378296Z","caller":"traceutil/trace.go:171","msg":"trace[69013989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:571; }","duration":"577.518766ms","start":"2026-02-19T02:51:53.800747Z","end":"2026-02-19T02:51:54.378266Z","steps":["trace[69013989] 'agreement among raft nodes before linearized reading' (duration: 577.151479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.378386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.800742Z","time spent":"577.632948ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:51:54.378441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.24953ms","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-19T02:51:54.378494Z","caller":"traceutil/trace.go:171","msg":"trace[770120388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"577.313341ms","start":"2026-02-19T02:51:53.801165Z","end":"2026-02-19T02:51:54.378479Z","steps":["trace[770120388] 'agreement among raft nodes before linearized reading' (duration: 576.658498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:54.378531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:53.80116Z","time spent":"577.362042ms","remote":"127.0.0.1:56414","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-19T02:51:55.084886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.452876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:51:55.085173Z","caller":"traceutil/trace.go:171","msg":"trace[132758920] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"700.73121ms","start":"2026-02-19T02:51:54.384422Z","end":"2026-02-19T02:51:55.085153Z","steps":["trace[132758920] 'process raft request' (duration: 700.642468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:55.085255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:54.384399Z","time spent":"700.822351ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:55.085317Z","caller":"traceutil/trace.go:171","msg":"trace[122645910] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"700.964104ms","start":"2026-02-19T02:51:54.384311Z","end":"2026-02-19T02:51:55.085275Z","steps":["trace[122645910] 'process raft request' (duration: 700.705749ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:51:55.085288Z","caller":"traceutil/trace.go:171","msg":"trace[312038743] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"701.274051ms","start":"2026-02-19T02:51:54.383963Z","end":"2026-02-19T02:51:55.085237Z","steps":["trace[312038743] 'process raft request' (duration: 432.364295ms)","trace[312038743] 'compare' (duration: 268.319024ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:51:55.085495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:54.384282Z","time spent":"701.113147ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:55.085489Z","caller":"traceutil/trace.go:171","msg":"trace[479115983] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"700.956604ms","start":"2026-02-19T02:51:54.384508Z","end":"2026-02-19T02:51:55.085464Z","steps":["trace[479115983] 'process raft request' (duration: 700.843622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:55.085619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:54.384487Z","time spent":"701.063476ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:55.085588Z","caller":"traceutil/trace.go:171","msg":"trace[1150644044] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"700.968084ms","start":"2026-02-19T02:51:54.384506Z","end":"2026-02-19T02:51:55.085474Z","steps":["trace[1150644044] 'process raft request' (duration: 700.599647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:55.08567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:54.383936Z","time spent":"701.590656ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:51:55.085876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:54.384498Z","time spent":"701.26669ms","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:51:55.524013Z","caller":"traceutil/trace.go:171","msg":"trace[28938224] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"165.664584ms","start":"2026-02-19T02:51:55.358316Z","end":"2026-02-19T02:51:55.523981Z","steps":["trace[28938224] 'process raft request' (duration: 165.45125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:56.313761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.744066ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-19T02:51:58.572188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15198694863674703884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:51:58.919891Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.826664008s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T02:51:58.920356Z","caller":"traceutil/trace.go:171","msg":"trace[631255853] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.827125088s","start":"2026-02-19T02:51:57.093138Z","end":"2026-02-19T02:51:58.920263Z","steps":["trace[631255853] 'process raft request' (duration: 1.826942944s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:51:58.920513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.09312Z","time spent":"1.827303121s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:00.071982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000388376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-19T02:52:00.072059Z","caller":"traceutil/trace.go:171","msg":"trace[1895955406] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000506238s","start":"2026-02-19T02:51:58.071536Z","end":"2026-02-19T02:52:00.072043Z","steps":["trace[1895955406] 'agreement among raft nodes before linearized reading' (duration: 2.000410636s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:00.072113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:58.071517Z","time spent":"2.00058568s","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/19 02:52:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-19T02:52:00.579413Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.659318488s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T02:52:00.579899Z","caller":"traceutil/trace.go:171","msg":"trace[764126995] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"3.486115348s","start":"2026-02-19T02:51:57.093759Z","end":"2026-02-19T02:52:00.579874Z","steps":["trace[764126995] 'process raft request' (duration: 3.485788312s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:00.579929Z","caller":"traceutil/trace.go:171","msg":"trace[1317435118] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"3.48523291s","start":"2026-02-19T02:51:57.094682Z","end":"2026-02-19T02:52:00.579915Z","steps":["trace[1317435118] 'process raft request' (duration: 3.485029576s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:00.579948Z","caller":"traceutil/trace.go:171","msg":"trace[341059306] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:599; }","duration":"2.508361649s","start":"2026-02-19T02:51:58.071567Z","end":"2026-02-19T02:52:00.579929Z","steps":["trace[341059306] 'read index received' (duration: 848.565582ms)","trace[341059306] 'applied index is now lower than readState.Index' (duration: 1.659794967s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:00.580005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.094659Z","time spent":"3.485295992s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:00.580005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.093714Z","time spent":"3.486228491s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:00.580031Z","caller":"traceutil/trace.go:171","msg":"trace[1370017634] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"3.484044686s","start":"2026-02-19T02:51:57.095977Z","end":"2026-02-19T02:52:00.580022Z","steps":["trace[1370017634] 'process raft request' (duration: 3.483781221s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:00.580102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.095966Z","time spent":"3.484091497s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:52:00.580196Z","caller":"traceutil/trace.go:171","msg":"trace[997704691] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.729340595s","start":"2026-02-19T02:51:58.850845Z","end":"2026-02-19T02:52:00.580186Z","steps":["trace[997704691] 'process raft request' (duration: 1.729029958s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:00.580216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.036874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:00.580251Z","caller":"traceutil/trace.go:171","msg":"trace[2119746523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"508.104856ms","start":"2026-02-19T02:52:00.072135Z","end":"2026-02-19T02:52:00.58024Z","steps":["trace[2119746523] 'agreement among raft nodes before linearized reading' (duration: 508.054365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:00.580263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:58.850819Z","time spent":"1.729413576s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:00.580308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:00.072116Z","time spent":"508.156348ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:52:00.580409Z","caller":"traceutil/trace.go:171","msg":"trace[1806061937] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"3.483641768s","start":"2026-02-19T02:51:57.096755Z","end":"2026-02-19T02:52:00.580397Z","steps":["trace[1806061937] 'process raft request' (duration: 3.483073546s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:00.580466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:51:57.096747Z","time spent":"3.48368834s","remote":"127.0.0.1:56414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:00.58058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.306998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.224\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T02:52:00.580622Z","caller":"traceutil/trace.go:171","msg":"trace[1101106650] range","detail":"{range_begin:/registry/masterleases/199.19.213.224; range_end:; response_count:1; response_revision:583; }","duration":"262.385589ms","start":"2026-02-19T02:52:00.318226Z","end":"2026-02-19T02:52:00.580611Z","steps":["trace[1101106650] 'agreement among raft nodes before linearized reading' (duration: 262.217126ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:04.05962Z","caller":"traceutil/trace.go:171","msg":"trace[1361249198] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"208.158458ms","start":"2026-02-19T02:52:03.851438Z","end":"2026-02-19T02:52:04.059597Z","steps":["trace[1361249198] 'read index received' (duration: 181.503092ms)","trace[1361249198] 'applied index is now lower than readState.Index' (duration: 26.654466ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:04.059784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.337942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:04.059745Z","caller":"traceutil/trace.go:171","msg":"trace[698274634] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"294.631114ms","start":"2026-02-19T02:52:03.76505Z","end":"2026-02-19T02:52:04.059681Z","steps":["trace[698274634] 'process raft request' (duration: 268.011538ms)","trace[698274634] 'compare' (duration: 26.415361ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:04.059935Z","caller":"traceutil/trace.go:171","msg":"trace[2051928270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"208.490634ms","start":"2026-02-19T02:52:03.851373Z","end":"2026-02-19T02:52:04.059863Z","steps":["trace[2051928270] 'agreement among raft nodes before linearized reading' (duration: 208.326981ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:05.187724Z","caller":"traceutil/trace.go:171","msg":"trace[1409493231] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"117.819995ms","start":"2026-02-19T02:52:05.069881Z","end":"2026-02-19T02:52:05.187701Z","steps":["trace[1409493231] 'read index received' (duration: 117.689462ms)","trace[1409493231] 'applied index is now lower than readState.Index' (duration: 129.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:05.187775Z","caller":"traceutil/trace.go:171","msg":"trace[549585970] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"207.955909ms","start":"2026-02-19T02:52:04.979786Z","end":"2026-02-19T02:52:05.187742Z","steps":["trace[549585970] 'process raft request' (duration: 207.777685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:05.187844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.951207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:05.187886Z","caller":"traceutil/trace.go:171","msg":"trace[211030164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"118.046699ms","start":"2026-02-19T02:52:05.069827Z","end":"2026-02-19T02:52:05.187873Z","steps":["trace[211030164] 'agreement among raft nodes before linearized reading' (duration: 117.968178ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.705244Z","caller":"traceutil/trace.go:171","msg":"trace[800933954] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:768; }","duration":"179.284667ms","start":"2026-02-19T02:52:20.525935Z","end":"2026-02-19T02:52:20.70522Z","steps":["trace[800933954] 'read index received' (duration: 173.97729ms)","trace[800933954] 'applied index is now lower than readState.Index' (duration: 5.306086ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:20.705295Z","caller":"traceutil/trace.go:171","msg":"trace[1313137752] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"352.747615ms","start":"2026-02-19T02:52:20.352513Z","end":"2026-02-19T02:52:20.70526Z","steps":["trace[1313137752] 'process raft request' (duration: 347.374018ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:20.705364Z","caller":"traceutil/trace.go:171","msg":"trace[1066950407] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"179.666244ms","start":"2026-02-19T02:52:20.52569Z","end":"2026-02-19T02:52:20.705356Z","steps":["trace[1066950407] 'process raft request' (duration: 179.484831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:20.705467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:20.352487Z","time spent":"352.886429ms","remote":"127.0.0.1:56194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:20.705834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.90525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-xtbts\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-19T02:52:20.705883Z","caller":"traceutil/trace.go:171","msg":"trace[1864627515] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-xtbts; range_end:; response_count:1; response_revision:741; }","duration":"179.979972ms","start":"2026-02-19T02:52:20.525894Z","end":"2026-02-19T02:52:20.705874Z","steps":["trace[1864627515] 'agreement among raft nodes before linearized reading' (duration: 179.826128ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:22.950181Z","caller":"traceutil/trace.go:171","msg":"trace[454396130] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"211.526606ms","start":"2026-02-19T02:52:22.738628Z","end":"2026-02-19T02:52:22.950155Z","steps":["trace[454396130] 'read index received' (duration: 211.374633ms)","trace[454396130] 'applied index is now lower than readState.Index' (duration: 151.273µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:22.950274Z","caller":"traceutil/trace.go:171","msg":"trace[348548537] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"229.602244ms","start":"2026-02-19T02:52:22.720646Z","end":"2026-02-19T02:52:22.950248Z","steps":["trace[348548537] 'process raft request' (duration: 229.374119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:22.950478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.140871ms","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-19T02:52:22.950549Z","caller":"traceutil/trace.go:171","msg":"trace[1629844791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"113.254324ms","start":"2026-02-19T02:52:22.837277Z","end":"2026-02-19T02:52:22.950531Z","steps":["trace[1629844791] 'agreement among raft nodes before linearized reading' (duration: 113.08146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:22.950567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.922226ms","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-19T02:52:22.950638Z","caller":"traceutil/trace.go:171","msg":"trace[1711950289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"212.048948ms","start":"2026-02-19T02:52:22.738567Z","end":"2026-02-19T02:52:22.950616Z","steps":["trace[1711950289] 'agreement among raft nodes before linearized reading' (duration: 211.696001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:22.950989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.601052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-19T02:52:22.95107Z","caller":"traceutil/trace.go:171","msg":"trace[259084168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"203.787056ms","start":"2026-02-19T02:52:22.747267Z","end":"2026-02-19T02:52:22.951054Z","steps":["trace[259084168] 'agreement among raft nodes before linearized reading' (duration: 203.093491ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:23.501541Z","caller":"traceutil/trace.go:171","msg":"trace[1265994685] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"206.702142ms","start":"2026-02-19T02:52:23.294796Z","end":"2026-02-19T02:52:23.501498Z","steps":["trace[1265994685] 'process raft request' (duration: 206.451896ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:25.151973Z","caller":"traceutil/trace.go:171","msg":"trace[1409538563] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"184.564971ms","start":"2026-02-19T02:52:24.967376Z","end":"2026-02-19T02:52:25.151941Z","steps":["trace[1409538563] 'read index received' (duration: 184.222174ms)","trace[1409538563] 'applied index is now lower than readState.Index' (duration: 341.407µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:25.15209Z","caller":"traceutil/trace.go:171","msg":"trace[1339362932] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"193.600152ms","start":"2026-02-19T02:52:24.958448Z","end":"2026-02-19T02:52:25.152048Z","steps":["trace[1339362932] 'process raft request' (duration: 193.281845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:25.152347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.920327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-19T02:52:25.152343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.93557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-19T02:52:25.152413Z","caller":"traceutil/trace.go:171","msg":"trace[1807265825] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"185.051602ms","start":"2026-02-19T02:52:24.967343Z","end":"2026-02-19T02:52:25.152395Z","steps":["trace[1807265825] 'agreement among raft nodes before linearized reading' (duration: 184.804777ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:25.152476Z","caller":"traceutil/trace.go:171","msg":"trace[1587586105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"184.03009ms","start":"2026-02-19T02:52:24.968358Z","end":"2026-02-19T02:52:25.152388Z","steps":["trace[1587586105] 'agreement among raft nodes before linearized reading' (duration: 183.850116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:25.152534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.629381ms","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-19T02:52:25.152619Z","caller":"traceutil/trace.go:171","msg":"trace[357987441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"183.747334ms","start":"2026-02-19T02:52:24.968847Z","end":"2026-02-19T02:52:25.152594Z","steps":["trace[357987441] 'agreement among raft nodes before linearized reading' (duration: 183.507729ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:30.513631Z","caller":"traceutil/trace.go:171","msg":"trace[1829827584] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"118.873837ms","start":"2026-02-19T02:52:30.394731Z","end":"2026-02-19T02:52:30.513604Z","steps":["trace[1829827584] 'process raft request' (duration: 115.824729ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:31.456743Z","caller":"traceutil/trace.go:171","msg":"trace[1757740502] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"216.16067ms","start":"2026-02-19T02:52:31.240558Z","end":"2026-02-19T02:52:31.456719Z","steps":["trace[1757740502] 'process raft request' (duration: 214.927162ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:31.458855Z","caller":"traceutil/trace.go:171","msg":"trace[1558019481] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"217.297866ms","start":"2026-02-19T02:52:31.241525Z","end":"2026-02-19T02:52:31.458823Z","steps":["trace[1558019481] 'process raft request' (duration: 216.366605ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:31.459108Z","caller":"traceutil/trace.go:171","msg":"trace[1897718148] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"214.966083ms","start":"2026-02-19T02:52:31.24332Z","end":"2026-02-19T02:52:31.458286Z","steps":["trace[1897718148] 'read index received' (duration: 212.095669ms)","trace[1897718148] 'applied index is now lower than readState.Index' (duration: 2.868674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:31.459401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.043368ms","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-19T02:52:31.459512Z","caller":"traceutil/trace.go:171","msg":"trace[536388277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"216.200421ms","start":"2026-02-19T02:52:31.243293Z","end":"2026-02-19T02:52:31.459494Z","steps":["trace[536388277] 'agreement among raft nodes before linearized reading' (duration: 215.827223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:31.784047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.861368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:52:31.784133Z","caller":"traceutil/trace.go:171","msg":"trace[950614129] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:785; }","duration":"101.024272ms","start":"2026-02-19T02:52:31.68309Z","end":"2026-02-19T02:52:31.784114Z","steps":["trace[950614129] 'count revisions from in-memory index tree' (duration: 100.750566ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:33.620248Z","caller":"traceutil/trace.go:171","msg":"trace[225433487] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"118.286491ms","start":"2026-02-19T02:52:33.501941Z","end":"2026-02-19T02:52:33.620228Z","steps":["trace[225433487] 'process raft request' (duration: 118.157528ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:33.626845Z","caller":"traceutil/trace.go:171","msg":"trace[1142322874] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"124.88878ms","start":"2026-02-19T02:52:33.501941Z","end":"2026-02-19T02:52:33.62683Z","steps":["trace[1142322874] 'process raft request' (duration: 124.794897ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:33.627106Z","caller":"traceutil/trace.go:171","msg":"trace[182903276] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"124.524772ms","start":"2026-02-19T02:52:33.502574Z","end":"2026-02-19T02:52:33.627099Z","steps":["trace[182903276] 'process raft request' (duration: 124.224785ms)"],"step_count":1}