{"level":"warn","ts":"2026-02-18T22:49:33.748608Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:49:33.749253Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-18T22:49:33.749343Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:49:33.749372Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-18T22:49:33.749417Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T22:49:33.750118Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-02-18T22:49:33.751788Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-18T22:49:33.758255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.171532ms"} {"level":"info","ts":"2026-02-18T22:49:33.770198Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-02-18T22:49:33.770255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:49:33.770282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:49:33.770299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:49:33.770353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:49:33.770395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-02-18T22:49:33.781063Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:49:33.796372Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:49:33.800529Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-18T22:49:33.805703Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:49:33.806339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:49:33.80672Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T22:49:33.806752Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T22:49:33.806812Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T22:49:33.807235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-02-18T22:49:33.808935Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-18T22:49:33.812783Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T22:49:33.81606Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:49:33.816088Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:49:33.815644Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-18T22:49:33.817798Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-18T22:49:33.87136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:49:33.871405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:49:33.871649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-02-18T22:49:33.871671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:49:33.871745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-18T22:49:33.871867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:49:33.871883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-18T22:49:33.872716Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:49:33.873384Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:49:33.874114Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:49:33.874434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:49:33.875437Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:49:33.875475Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:49:33.877544Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:49:33.87766Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:49:33.877709Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:49:33.880647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-02-18T22:49:33.88107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:49:36.079888Z","caller":"traceutil/trace.go:171","msg":"trace[444537100] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:15; }","duration":"222.041722ms","start":"2026-02-18T22:49:35.857835Z","end":"2026-02-18T22:49:36.079876Z","steps":["trace[444537100] 'read index received' (duration: 157.468584ms)","trace[444537100] 'applied index is now lower than readState.Index' (duration: 64.572438ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:36.080107Z","caller":"traceutil/trace.go:171","msg":"trace[2692205] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"225.587319ms","start":"2026-02-18T22:49:35.854498Z","end":"2026-02-18T22:49:36.080085Z","steps":["trace[2692205] 'process raft request' (duration: 160.735713ms)","trace[2692205] 'compare' (duration: 64.055813ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:36.080993Z","caller":"traceutil/trace.go:171","msg":"trace[1472232925] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"225.475655ms","start":"2026-02-18T22:49:35.855509Z","end":"2026-02-18T22:49:36.080985Z","steps":["trace[1472232925] 'process raft request' (duration: 223.876312ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.081146Z","caller":"traceutil/trace.go:171","msg":"trace[1587170970] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"224.344925ms","start":"2026-02-18T22:49:35.856777Z","end":"2026-02-18T22:49:36.081122Z","steps":["trace[1587170970] 'process raft request' (duration: 222.942727ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.081243Z","caller":"traceutil/trace.go:171","msg":"trace[456667415] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"223.695607ms","start":"2026-02-18T22:49:35.857543Z","end":"2026-02-18T22:49:36.081238Z","steps":["trace[456667415] 'process raft request' (duration: 222.193397ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.08135Z","caller":"traceutil/trace.go:171","msg":"trace[1178771293] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"223.766009ms","start":"2026-02-18T22:49:35.85758Z","end":"2026-02-18T22:49:36.081346Z","steps":["trace[1178771293] 'process raft request' (duration: 222.166746ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.081474Z","caller":"traceutil/trace.go:171","msg":"trace[993311662] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"223.859212ms","start":"2026-02-18T22:49:35.85761Z","end":"2026-02-18T22:49:36.081469Z","steps":["trace[993311662] 'process raft request' (duration: 222.151015ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.081698Z","caller":"traceutil/trace.go:171","msg":"trace[578377799] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"195.371417ms","start":"2026-02-18T22:49:35.886319Z","end":"2026-02-18T22:49:36.08169Z","steps":["trace[578377799] 'process raft request' (duration: 193.519406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:36.082119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.81154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T22:49:36.082159Z","caller":"traceutil/trace.go:171","msg":"trace[338303494] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:24; }","duration":"219.864151ms","start":"2026-02-18T22:49:35.862286Z","end":"2026-02-18T22:49:36.08215Z","steps":["trace[338303494] 'agreement among raft nodes before linearized reading' (duration: 219.81589ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.08179Z","caller":"traceutil/trace.go:171","msg":"trace[1037306641] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"181.546996ms","start":"2026-02-18T22:49:35.900237Z","end":"2026-02-18T22:49:36.081784Z","steps":["trace[1037306641] 'process raft request' (duration: 179.613264ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.081805Z","caller":"traceutil/trace.go:171","msg":"trace[1450429357] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"171.927463ms","start":"2026-02-18T22:49:35.909874Z","end":"2026-02-18T22:49:36.081801Z","steps":["trace[1450429357] 'process raft request' (duration: 169.98939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:36.081953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.564933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-18T22:49:36.083162Z","caller":"traceutil/trace.go:171","msg":"trace[1665082082] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24; }","duration":"228.788687ms","start":"2026-02-18T22:49:35.854366Z","end":"2026-02-18T22:49:36.083154Z","steps":["trace[1665082082] 'agreement among raft nodes before linearized reading' (duration: 227.44902ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.083091Z","caller":"traceutil/trace.go:171","msg":"trace[689266434] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"225.402225ms","start":"2026-02-18T22:49:35.857663Z","end":"2026-02-18T22:49:36.083066Z","steps":["trace[689266434] 'process raft request' (duration: 222.113405ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.083687Z","caller":"traceutil/trace.go:171","msg":"trace[1592020918] transaction","detail":"{read_only:false; number_of_response:0; response_revision:19; }","duration":"225.920309ms","start":"2026-02-18T22:49:35.857732Z","end":"2026-02-18T22:49:36.083652Z","steps":["trace[1592020918] 'process raft request' (duration: 222.061043ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.084047Z","caller":"traceutil/trace.go:171","msg":"trace[778453717] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"221.497166ms","start":"2026-02-18T22:49:35.862537Z","end":"2026-02-18T22:49:36.084034Z","steps":["trace[778453717] 'process raft request' (duration: 217.26996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:36.081974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.670896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T22:49:36.084925Z","caller":"traceutil/trace.go:171","msg":"trace[266462888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24; }","duration":"230.623297ms","start":"2026-02-18T22:49:35.854295Z","end":"2026-02-18T22:49:36.084918Z","steps":["trace[266462888] 'agreement among raft nodes before linearized reading' (duration: 227.669586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:36.081989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.700947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T22:49:36.086126Z","caller":"traceutil/trace.go:171","msg":"trace[72970390] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:0; response_revision:24; }","duration":"231.842061ms","start":"2026-02-18T22:49:35.854277Z","end":"2026-02-18T22:49:36.086119Z","steps":["trace[72970390] 'agreement among raft nodes before linearized reading' (duration: 227.704377ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:36.085926Z","caller":"traceutil/trace.go:171","msg":"trace[173232501] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"219.867672ms","start":"2026-02-18T22:49:35.866033Z","end":"2026-02-18T22:49:36.085901Z","steps":["trace[173232501] 'process raft request' (duration: 213.790965ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:38.134473Z","caller":"traceutil/trace.go:171","msg":"trace[1874767949] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"201.285775ms","start":"2026-02-18T22:49:37.933161Z","end":"2026-02-18T22:49:38.134447Z","steps":["trace[1874767949] 'process raft request' (duration: 201.12023ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:38.138677Z","caller":"traceutil/trace.go:171","msg":"trace[214383265] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"130.362931ms","start":"2026-02-18T22:49:38.008297Z","end":"2026-02-18T22:49:38.13866Z","steps":["trace[214383265] 'process raft request' (duration: 130.096394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:43.832222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.568534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:49:43.832361Z","caller":"traceutil/trace.go:171","msg":"trace[1116610279] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:335; }","duration":"475.315058ms","start":"2026-02-18T22:49:43.357036Z","end":"2026-02-18T22:49:43.832351Z","steps":["trace[1116610279] 'read index received' (duration: 11.346223ms)","trace[1116610279] 'applied index is now lower than readState.Index' (duration: 463.968215ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:43.832407Z","caller":"traceutil/trace.go:171","msg":"trace[1901404733] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"512.524914ms","start":"2026-02-18T22:49:43.319876Z","end":"2026-02-18T22:49:43.832401Z","steps":["trace[1901404733] 'process raft request' (duration: 48.500157ms)","trace[1901404733] 'compare' (duration: 463.431431ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:49:43.832434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:43.319861Z","time spent":"512.559755ms","remote":"127.0.0.1:60272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:49:43.832677Z","caller":"traceutil/trace.go:171","msg":"trace[1845783121] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"512.565745ms","start":"2026-02-18T22:49:43.320106Z","end":"2026-02-18T22:49:43.832671Z","steps":["trace[1845783121] 'process raft request' (duration: 512.178475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:43.832772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:43.320101Z","time spent":"512.587806ms","remote":"127.0.0.1:60398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4711,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:49:43.832892Z","caller":"traceutil/trace.go:171","msg":"trace[2015114087] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"508.817431ms","start":"2026-02-18T22:49:43.324069Z","end":"2026-02-18T22:49:43.832886Z","steps":["trace[2015114087] 'process raft request' (duration: 508.259196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:43.832917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:43.32406Z","time spent":"508.843852ms","remote":"127.0.0.1:60388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4054,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:49:43.833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.963216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-18T22:49:43.833018Z","caller":"traceutil/trace.go:171","msg":"trace[1924818378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:328; }","duration":"475.980856ms","start":"2026-02-18T22:49:43.357031Z","end":"2026-02-18T22:49:43.833012Z","steps":["trace[1924818378] 'agreement among raft nodes before linearized reading' (duration: 475.948125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:43.833031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:43.356977Z","time spent":"476.050859ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T22:49:43.833109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.865569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-18T22:49:43.833123Z","caller":"traceutil/trace.go:171","msg":"trace[1803310451] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:328; }","duration":"427.906ms","start":"2026-02-18T22:49:43.405213Z","end":"2026-02-18T22:49:43.833119Z","steps":["trace[1803310451] 'agreement among raft nodes before linearized reading' (duration: 427.87903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:43.833137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:43.405199Z","time spent":"427.933301ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-18T22:49:43.833292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.176321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T22:49:43.833306Z","caller":"traceutil/trace.go:171","msg":"trace[94997998] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:328; }","duration":"377.235953ms","start":"2026-02-18T22:49:43.456066Z","end":"2026-02-18T22:49:43.833302Z","steps":["trace[94997998] 'agreement among raft nodes before linearized reading' (duration: 377.209623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:43.833318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:43.456045Z","time spent":"377.270534ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-18T22:49:54.682156Z","caller":"traceutil/trace.go:171","msg":"trace[387636013] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"440.465769ms","start":"2026-02-18T22:49:54.241656Z","end":"2026-02-18T22:49:54.682122Z","steps":["trace[387636013] 'read index received' (duration: 440.180571ms)","trace[387636013] 'applied index is now lower than readState.Index' (duration: 283.738µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:54.682552Z","caller":"traceutil/trace.go:171","msg":"trace[616948244] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"561.183733ms","start":"2026-02-18T22:49:54.121354Z","end":"2026-02-18T22:49:54.682538Z","steps":["trace[616948244] 'process raft request' (duration: 560.572867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:54.682696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:54.121336Z","time spent":"561.255566ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:49:54.682977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.324332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T22:49:54.683018Z","caller":"traceutil/trace.go:171","msg":"trace[1457944111] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"441.412194ms","start":"2026-02-18T22:49:54.241594Z","end":"2026-02-18T22:49:54.683006Z","steps":["trace[1457944111] 'agreement among raft nodes before linearized reading' (duration: 441.333772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:54.683049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:54.241567Z","time spent":"441.476096ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:49:54.683218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.670665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:49:54.683249Z","caller":"traceutil/trace.go:171","msg":"trace[194259089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"423.728856ms","start":"2026-02-18T22:49:54.259509Z","end":"2026-02-18T22:49:54.683238Z","steps":["trace[194259089] 'agreement among raft nodes before linearized reading' (duration: 423.674575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:54.683276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:54.25949Z","time spent":"423.779937ms","remote":"127.0.0.1:60468","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-18T22:49:54.683471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.75839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:49:54.683518Z","caller":"traceutil/trace.go:171","msg":"trace[1006597621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"342.814771ms","start":"2026-02-18T22:49:54.340681Z","end":"2026-02-18T22:49:54.683496Z","steps":["trace[1006597621] 'agreement among raft nodes before linearized reading' (duration: 342.75852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:54.683544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:54.340668Z","time spent":"342.869794ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:49:54.683714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.798342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:49:54.683742Z","caller":"traceutil/trace.go:171","msg":"trace[861396770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"413.835003ms","start":"2026-02-18T22:49:54.269898Z","end":"2026-02-18T22:49:54.683733Z","steps":["trace[861396770] 'agreement among raft nodes before linearized reading' (duration: 413.781571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:54.68377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:54.269893Z","time spent":"413.871055ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:50:01.809456Z","caller":"traceutil/trace.go:171","msg":"trace[1018078189] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"114.740361ms","start":"2026-02-18T22:50:01.694663Z","end":"2026-02-18T22:50:01.809403Z","steps":["trace[1018078189] 'process raft request' (duration: 85.258001ms)","trace[1018078189] 'compare' (duration: 28.83326ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:05.185022Z","caller":"traceutil/trace.go:171","msg":"trace[219480154] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"388.736444ms","start":"2026-02-18T22:50:04.796265Z","end":"2026-02-18T22:50:05.185001Z","steps":["trace[219480154] 'process raft request' (duration: 388.345708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:05.185218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:04.796254Z","time spent":"388.874796ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:50:05.68127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.084376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:50:05.681353Z","caller":"traceutil/trace.go:171","msg":"trace[378002225] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:488; }","duration":"882.490518ms","start":"2026-02-18T22:50:04.798848Z","end":"2026-02-18T22:50:05.681338Z","steps":["trace[378002225] 'read index received' (duration: 385.889438ms)","trace[378002225] 'applied index is now lower than readState.Index' (duration: 496.59989ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:05.681528Z","caller":"traceutil/trace.go:171","msg":"trace[70109346] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"883.582286ms","start":"2026-02-18T22:50:04.797933Z","end":"2026-02-18T22:50:05.681515Z","steps":["trace[70109346] 'process raft request' (duration: 773.184564ms)","trace[70109346] 'compare' (duration: 109.507887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:50:05.681612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:04.797926Z","time spent":"883.653868ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:50:05.681848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.993238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:50:05.681881Z","caller":"traceutil/trace.go:171","msg":"trace[1531948914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"883.035068ms","start":"2026-02-18T22:50:04.798835Z","end":"2026-02-18T22:50:05.68187Z","steps":["trace[1531948914] 'agreement among raft nodes before linearized reading' (duration: 882.927686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:05.681908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:04.79883Z","time spent":"883.072689ms","remote":"127.0.0.1:60468","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-18T22:50:05.682073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.19127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:50:05.6821Z","caller":"traceutil/trace.go:171","msg":"trace[963976578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"883.222091ms","start":"2026-02-18T22:50:04.798868Z","end":"2026-02-18T22:50:05.68209Z","steps":["trace[963976578] 'agreement among raft nodes before linearized reading' (duration: 883.148409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:05.682124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:04.798865Z","time spent":"883.253021ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:50:05.963849Z","caller":"traceutil/trace.go:171","msg":"trace[127061067] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"275.728716ms","start":"2026-02-18T22:50:05.688105Z","end":"2026-02-18T22:50:05.963834Z","steps":["trace[127061067] 'process raft request' (duration: 275.656285ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:07.246738Z","caller":"traceutil/trace.go:171","msg":"trace[1885867178] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"117.861371ms","start":"2026-02-18T22:50:07.128853Z","end":"2026-02-18T22:50:07.246714Z","steps":["trace[1885867178] 'process raft request' (duration: 116.799644ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:07.24677Z","caller":"traceutil/trace.go:171","msg":"trace[1937016674] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"111.910541ms","start":"2026-02-18T22:50:07.134845Z","end":"2026-02-18T22:50:07.246755Z","steps":["trace[1937016674] 'process raft request' (duration: 111.657847ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:11.904493Z","caller":"traceutil/trace.go:171","msg":"trace[2077258259] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"123.429377ms","start":"2026-02-18T22:50:11.781037Z","end":"2026-02-18T22:50:11.904466Z","steps":["trace[2077258259] 'process raft request' (duration: 123.002779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:13.086205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.663216ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-18T22:50:15.815863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093990980472430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:50:16.316925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093990980472430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:50:16.354919Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067300334s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:50:16.355198Z","caller":"traceutil/trace.go:171","msg":"trace[777141158] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"1.04033484s","start":"2026-02-18T22:50:15.314836Z","end":"2026-02-18T22:50:16.355171Z","steps":["trace[777141158] 'read index received' (duration: 1.040165877s)","trace[777141158] 'applied index is now lower than readState.Index' (duration: 168.153µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:16.355385Z","caller":"traceutil/trace.go:171","msg":"trace[241140743] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.067858154s","start":"2026-02-18T22:50:15.287518Z","end":"2026-02-18T22:50:16.355376Z","steps":["trace[241140743] 'process raft request' (duration: 1.067530588s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:16.355454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:15.2875Z","time spent":"1.067904385s","remote":"127.0.0.1:60398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:50:16.355634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040795848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T22:50:16.355657Z","caller":"traceutil/trace.go:171","msg":"trace[526890769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"1.040852048s","start":"2026-02-18T22:50:15.314797Z","end":"2026-02-18T22:50:16.355649Z","steps":["trace[526890769] 'agreement among raft nodes before linearized reading' (duration: 1.040760066s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:16.355676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:15.314779Z","time spent":"1.040892069s","remote":"127.0.0.1:60468","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-18T22:50:16.355776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031194643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:16.355793Z","caller":"traceutil/trace.go:171","msg":"trace[1711573217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"1.031238414s","start":"2026-02-18T22:50:15.324549Z","end":"2026-02-18T22:50:16.355788Z","steps":["trace[1711573217] 'agreement among raft nodes before linearized reading' (duration: 1.031210714s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:16.355809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:15.324535Z","time spent":"1.031270365s","remote":"127.0.0.1:60202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:50:16.356079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.651147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:50:16.356102Z","caller":"traceutil/trace.go:171","msg":"trace[1725700373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"283.696118ms","start":"2026-02-18T22:50:16.072399Z","end":"2026-02-18T22:50:16.356095Z","steps":["trace[1725700373] 'agreement among raft nodes before linearized reading' (duration: 283.635637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:16.356206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.295754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:50:16.356223Z","caller":"traceutil/trace.go:171","msg":"trace[585927249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"351.333985ms","start":"2026-02-18T22:50:16.004884Z","end":"2026-02-18T22:50:16.356218Z","steps":["trace[585927249] 'agreement among raft nodes before linearized reading' (duration: 351.285664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:16.356239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:16.004874Z","time spent":"351.361545ms","remote":"127.0.0.1:60468","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-18T22:50:16.356332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.407415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T22:50:16.356347Z","caller":"traceutil/trace.go:171","msg":"trace[1637405754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"372.461175ms","start":"2026-02-18T22:50:15.983881Z","end":"2026-02-18T22:50:16.356342Z","steps":["trace[1637405754] 'agreement among raft nodes before linearized reading' (duration: 372.417835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:16.356363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:15.983864Z","time spent":"372.495397ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:50:16.867911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093990980472433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:50:17.368903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093990980472433,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:50:17.424451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.057771439s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:50:17.424998Z","caller":"traceutil/trace.go:171","msg":"trace[1829647701] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.05836913s","start":"2026-02-18T22:50:16.366612Z","end":"2026-02-18T22:50:17.424981Z","steps":["trace[1829647701] 'process raft request' (duration: 1.058273939s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:17.425117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:16.366595Z","time spent":"1.058452461s","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:50:18.367989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000209858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T22:50:18.368055Z","caller":"traceutil/trace.go:171","msg":"trace[1297415845] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000306949s","start":"2026-02-18T22:50:16.367727Z","end":"2026-02-18T22:50:18.368034Z","steps":["trace[1297415845] 'agreement among raft nodes before linearized reading' (duration: 2.000220898s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:18.368086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:16.367722Z","time spent":"2.00035871s","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:50:18.580623Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156014168s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:50:19.758663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:19.389544Z","time spent":"369.113068ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:50:19.759426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178619981s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:50:19.759592Z","caller":"traceutil/trace.go:171","msg":"trace[1160998857] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:540; }","duration":"3.391834908s","start":"2026-02-18T22:50:16.367746Z","end":"2026-02-18T22:50:19.759581Z","steps":["trace[1160998857] 'read index received' (duration: 1.057661218s)","trace[1160998857] 'applied index is now lower than readState.Index' (duration: 2.33417305s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:19.759779Z","caller":"traceutil/trace.go:171","msg":"trace[1108042276] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"3.392986239s","start":"2026-02-18T22:50:16.366784Z","end":"2026-02-18T22:50:19.75977Z","steps":["trace[1108042276] 'process raft request' (duration: 2.21395198s)","trace[1108042276] 'compare' (duration: 1.17854721s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:50:19.759821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:16.366778Z","time spent":"3.393020189s","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:50:19.759907Z","caller":"traceutil/trace.go:171","msg":"trace[863359483] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"3.3930887s","start":"2026-02-18T22:50:16.366811Z","end":"2026-02-18T22:50:19.7599Z","steps":["trace[863359483] 'process raft request' (duration: 3.392657952s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:19.759941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:16.366809Z","time spent":"3.393111981s","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:50:19.760056Z","caller":"traceutil/trace.go:171","msg":"trace[373213229] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"3.392146924s","start":"2026-02-18T22:50:16.367902Z","end":"2026-02-18T22:50:19.760049Z","steps":["trace[373213229] 'process raft request' (duration: 3.391629285s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:19.760089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:16.367897Z","time spent":"3.392173604s","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:50:19.760235Z","caller":"traceutil/trace.go:171","msg":"trace[1670017610] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"2.890609173s","start":"2026-02-18T22:50:16.869616Z","end":"2026-02-18T22:50:19.760225Z","steps":["trace[1670017610] 'process raft request' (duration: 2.889940701s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:19.760285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:16.8696Z","time spent":"2.890657834s","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:50:19.76044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.369893344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T22:50:19.760459Z","caller":"traceutil/trace.go:171","msg":"trace[1040521245] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:527; }","duration":"2.369941684s","start":"2026-02-18T22:50:17.390511Z","end":"2026-02-18T22:50:19.760453Z","steps":["trace[1040521245] 'agreement among raft nodes before linearized reading' (duration: 2.369861033s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:19.760478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:17.390494Z","time spent":"2.369978866s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-02-18T22:50:19.760672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.350184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:19.761087Z","caller":"traceutil/trace.go:171","msg":"trace[251547618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"157.795423ms","start":"2026-02-18T22:50:19.603282Z","end":"2026-02-18T22:50:19.761077Z","steps":["trace[251547618] 'agreement among raft nodes before linearized reading' (duration: 157.373065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:19.761342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.778214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T22:50:19.762409Z","caller":"traceutil/trace.go:171","msg":"trace[537838538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"333.872955ms","start":"2026-02-18T22:50:19.428527Z","end":"2026-02-18T22:50:19.7624Z","steps":["trace[537838538] 'agreement among raft nodes before linearized reading' (duration: 332.767095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:19.761789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393115389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:19.763908Z","caller":"traceutil/trace.go:171","msg":"trace[2086991156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"1.395259808s","start":"2026-02-18T22:50:18.368638Z","end":"2026-02-18T22:50:19.763898Z","steps":["trace[2086991156] 'agreement among raft nodes before linearized reading' (duration: 1.393101569s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:19.764084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:18.368629Z","time spent":"1.395445462s","remote":"127.0.0.1:60202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:50:19.762733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:19.428511Z","time spent":"334.212351ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:50:19.952293Z","caller":"traceutil/trace.go:171","msg":"trace[1580410665] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"183.437729ms","start":"2026-02-18T22:50:19.768838Z","end":"2026-02-18T22:50:19.952276Z","steps":["trace[1580410665] 'process raft request' (duration: 133.401411ms)","trace[1580410665] 'compare' (duration: 49.901035ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:19.952422Z","caller":"traceutil/trace.go:171","msg":"trace[41323185] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"182.133344ms","start":"2026-02-18T22:50:19.770282Z","end":"2026-02-18T22:50:19.952415Z","steps":["trace[41323185] 'read index received' (duration: 79.001183ms)","trace[41323185] 'applied index is now lower than readState.Index' (duration: 103.131581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:50:19.95248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.187385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:19.952784Z","caller":"traceutil/trace.go:171","msg":"trace[1153610686] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"182.374448ms","start":"2026-02-18T22:50:19.770386Z","end":"2026-02-18T22:50:19.952761Z","steps":["trace[1153610686] 'process raft request' (duration: 181.858789ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:19.952495Z","caller":"traceutil/trace.go:171","msg":"trace[1568729885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"182.239635ms","start":"2026-02-18T22:50:19.770251Z","end":"2026-02-18T22:50:19.95249Z","steps":["trace[1568729885] 'agreement among raft nodes before linearized reading' (duration: 182.182804ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:19.958167Z","caller":"traceutil/trace.go:171","msg":"trace[266812103] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"108.392858ms","start":"2026-02-18T22:50:19.849759Z","end":"2026-02-18T22:50:19.958152Z","steps":["trace[266812103] 'process raft request' (duration: 108.299507ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:20.435905Z","caller":"traceutil/trace.go:171","msg":"trace[1378004232] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"146.441361ms","start":"2026-02-18T22:50:20.289444Z","end":"2026-02-18T22:50:20.435886Z","steps":["trace[1378004232] 'process raft request' (duration: 146.305938ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:20.479627Z","caller":"traceutil/trace.go:171","msg":"trace[1067496289] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"175.408861ms","start":"2026-02-18T22:50:20.304202Z","end":"2026-02-18T22:50:20.479611Z","steps":["trace[1067496289] 'process raft request' (duration: 175.318569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:23.068236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.600985ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-18T22:50:26.045532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.201071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-18T22:50:26.045619Z","caller":"traceutil/trace.go:171","msg":"trace[1424444832] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"335.335213ms","start":"2026-02-18T22:50:25.710266Z","end":"2026-02-18T22:50:26.045601Z","steps":["trace[1424444832] 'range keys from in-memory index tree' (duration: 335.009138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:26.045654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:25.710247Z","time spent":"335.398414ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:50:26.045863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.263822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T22:50:26.045893Z","caller":"traceutil/trace.go:171","msg":"trace[1835219722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"257.359664ms","start":"2026-02-18T22:50:25.788523Z","end":"2026-02-18T22:50:26.045883Z","steps":["trace[1835219722] 'range keys from in-memory index tree' (duration: 257.11666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:26.04604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.615352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:50:26.046064Z","caller":"traceutil/trace.go:171","msg":"trace[947161563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"253.664803ms","start":"2026-02-18T22:50:25.792392Z","end":"2026-02-18T22:50:26.046057Z","steps":["trace[947161563] 'range keys from in-memory index tree' (duration: 253.438378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:26.046218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.973047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:50:26.046252Z","caller":"traceutil/trace.go:171","msg":"trace[104310508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"243.028149ms","start":"2026-02-18T22:50:25.803216Z","end":"2026-02-18T22:50:26.046244Z","steps":["trace[104310508] 'range keys from in-memory index tree' (duration: 242.881745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:26.046675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.265295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-18T22:50:26.046724Z","caller":"traceutil/trace.go:171","msg":"trace[194449624] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"137.342866ms","start":"2026-02-18T22:50:25.90937Z","end":"2026-02-18T22:50:26.046713Z","steps":["trace[194449624] 'range keys from in-memory index tree' (duration: 137.136552ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:27.661653Z","caller":"traceutil/trace.go:171","msg":"trace[838634239] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"113.307515ms","start":"2026-02-18T22:50:27.548325Z","end":"2026-02-18T22:50:27.661632Z","steps":["trace[838634239] 'read index received' (duration: 97.239392ms)","trace[838634239] 'applied index is now lower than readState.Index' (duration: 16.067323ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:27.662423Z","caller":"traceutil/trace.go:171","msg":"trace[202547107] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"117.317083ms","start":"2026-02-18T22:50:27.54509Z","end":"2026-02-18T22:50:27.662407Z","steps":["trace[202547107] 'process raft request' (duration: 100.436424ms)","trace[202547107] 'compare' (duration: 15.857889ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:50:27.663377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.139148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-18T22:50:27.663495Z","caller":"traceutil/trace.go:171","msg":"trace[66392283] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:613; }","duration":"118.26377ms","start":"2026-02-18T22:50:27.545216Z","end":"2026-02-18T22:50:27.66348Z","steps":["trace[66392283] 'agreement among raft nodes before linearized reading' (duration: 117.859942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:27.6685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.77683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T22:50:27.668533Z","caller":"traceutil/trace.go:171","msg":"trace[1085353573] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:614; }","duration":"117.836732ms","start":"2026-02-18T22:50:27.550687Z","end":"2026-02-18T22:50:27.668524Z","steps":["trace[1085353573] 'agreement among raft nodes before linearized reading' (duration: 117.686029ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:27.668771Z","caller":"traceutil/trace.go:171","msg":"trace[115595814] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"113.254693ms","start":"2026-02-18T22:50:27.555508Z","end":"2026-02-18T22:50:27.668762Z","steps":["trace[115595814] 'process raft request' (duration: 112.753753ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:51:16.100368Z","caller":"traceutil/trace.go:171","msg":"trace[1695803485] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"138.284261ms","start":"2026-02-18T22:51:15.962067Z","end":"2026-02-18T22:51:16.100352Z","steps":["trace[1695803485] 'process raft request' (duration: 138.153528ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:51:17.775723Z","caller":"traceutil/trace.go:171","msg":"trace[1082815267] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"180.591822ms","start":"2026-02-18T22:51:17.595112Z","end":"2026-02-18T22:51:17.775703Z","steps":["trace[1082815267] 'process raft request' (duration: 150.35178ms)","trace[1082815267] 'compare' (duration: 30.132399ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:18.753278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.294169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:51:18.753346Z","caller":"traceutil/trace.go:171","msg":"trace[332793452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"427.407731ms","start":"2026-02-18T22:51:18.325922Z","end":"2026-02-18T22:51:18.753329Z","steps":["trace[332793452] 'range keys from in-memory index tree' (duration: 427.201406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.753376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:18.325898Z","time spent":"427.471902ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:51:18.753654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.972084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T22:51:18.753675Z","caller":"traceutil/trace.go:171","msg":"trace[125389095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:849; }","duration":"342.020385ms","start":"2026-02-18T22:51:18.411648Z","end":"2026-02-18T22:51:18.753668Z","steps":["trace[125389095] 'range keys from in-memory index tree' (duration: 341.82274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.753692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:18.411599Z","time spent":"342.088027ms","remote":"127.0.0.1:60468","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-18T22:51:18.754067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.863266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:51:18.754098Z","caller":"traceutil/trace.go:171","msg":"trace[554718953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:849; }","duration":"275.919837ms","start":"2026-02-18T22:51:18.478168Z","end":"2026-02-18T22:51:18.754087Z","steps":["trace[554718953] 'range keys from in-memory index tree' (duration: 275.783474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.754312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.191429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T22:51:18.754346Z","caller":"traceutil/trace.go:171","msg":"trace[550624037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:849; }","duration":"296.295081ms","start":"2026-02-18T22:51:18.458036Z","end":"2026-02-18T22:51:18.754332Z","steps":["trace[550624037] 'range keys from in-memory index tree' (duration: 296.041665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.75467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.903501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:51:18.754699Z","caller":"traceutil/trace.go:171","msg":"trace[616700260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:849; }","duration":"258.953353ms","start":"2026-02-18T22:51:18.495737Z","end":"2026-02-18T22:51:18.75469Z","steps":["trace[616700260] 'range keys from in-memory index tree' (duration: 258.807919ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:51:44.425639Z","caller":"traceutil/trace.go:171","msg":"trace[1201992546] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"103.725961ms","start":"2026-02-18T22:51:44.321893Z","end":"2026-02-18T22:51:44.425619Z","steps":["trace[1201992546] 'read index received' (duration: 103.588248ms)","trace[1201992546] 'applied index is now lower than readState.Index' (duration: 136.953µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:44.42576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.846764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:51:44.425783Z","caller":"traceutil/trace.go:171","msg":"trace[1410608195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"103.886975ms","start":"2026-02-18T22:51:44.32189Z","end":"2026-02-18T22:51:44.425777Z","steps":["trace[1410608195] 'agreement among raft nodes before linearized reading' (duration: 103.830863ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:51:44.425858Z","caller":"traceutil/trace.go:171","msg":"trace[1853241359] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"111.716785ms","start":"2026-02-18T22:51:44.314124Z","end":"2026-02-18T22:51:44.425841Z","steps":["trace[1853241359] 'process raft request' (duration: 111.384367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:45.486244Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093990980473471,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:51:45.552143Z","caller":"traceutil/trace.go:171","msg":"trace[662745362] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"566.135951ms","start":"2026-02-18T22:51:44.985982Z","end":"2026-02-18T22:51:45.552118Z","steps":["trace[662745362] 'read index received' (duration: 565.818304ms)","trace[662745362] 'applied index is now lower than readState.Index' (duration: 316.787µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:51:45.560018Z","caller":"traceutil/trace.go:171","msg":"trace[191336165] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"574.041094ms","start":"2026-02-18T22:51:44.985951Z","end":"2026-02-18T22:51:45.559992Z","steps":["trace[191336165] 'process raft request' (duration: 565.931077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:45.560173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:44.985927Z","time spent":"574.167568ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:51:45.706169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.164279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-18T22:51:45.706294Z","caller":"traceutil/trace.go:171","msg":"trace[1250709265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"720.297963ms","start":"2026-02-18T22:51:44.985969Z","end":"2026-02-18T22:51:45.706267Z","steps":["trace[1250709265] 'agreement among raft nodes before linearized reading' (duration: 574.344922ms)","trace[1250709265] 'range keys from in-memory index tree' (duration: 145.750056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:45.70634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:44.985962Z","time spent":"720.370754ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:51:45.70636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.740789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-18T22:51:45.706439Z","caller":"traceutil/trace.go:171","msg":"trace[1384968475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"682.831811ms","start":"2026-02-18T22:51:45.023588Z","end":"2026-02-18T22:51:45.70642Z","steps":["trace[1384968475] 'agreement among raft nodes before linearized reading' (duration: 536.807409ms)","trace[1384968475] 'range keys from in-memory index tree' (duration: 145.861108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:45.706494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:45.023586Z","time spent":"682.899443ms","remote":"127.0.0.1:60468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:51:45.706721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.136688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T22:51:45.706759Z","caller":"traceutil/trace.go:171","msg":"trace[1483562539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"683.18872ms","start":"2026-02-18T22:51:45.023559Z","end":"2026-02-18T22:51:45.706748Z","steps":["trace[1483562539] 'agreement among raft nodes before linearized reading' (duration: 536.769148ms)","trace[1483562539] 'range keys from in-memory index tree' (duration: 146.264619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:45.706792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:45.023551Z","time spent":"683.233731ms","remote":"127.0.0.1:60468","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-18T22:51:45.70687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.968992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:51:45.7069Z","caller":"traceutil/trace.go:171","msg":"trace[1848163605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"381.994483ms","start":"2026-02-18T22:51:45.324896Z","end":"2026-02-18T22:51:45.70689Z","steps":["trace[1848163605] 'agreement among raft nodes before linearized reading' (duration: 235.493569ms)","trace[1848163605] 'range keys from in-memory index tree' (duration: 146.468393ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:45.70693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:45.324822Z","time spent":"382.102386ms","remote":"127.0.0.1:60192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}