{"level":"warn","ts":"2026-02-12T02:08:24.918496Z","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-12T02:08:24.918717Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-12T02:08:24.91893Z","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-12T02:08:24.918944Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-12T02:08:24.919072Z","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-12T02:08:24.919703Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-02-12T02:08:24.91985Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-12T02:08:24.92413Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.018184ms"} {"level":"info","ts":"2026-02-12T02:08:24.952831Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-02-12T02:08:24.952991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T02:08:24.953045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-02-12T02:08:24.953077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T02:08:24.953129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-02-12T02:08:24.953194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-02-12T02:08:24.979058Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T02:08:24.98488Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T02:08:24.986535Z","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-12T02:08:24.988978Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T02:08:24.991051Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T02:08:24.991268Z","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-12T02:08:24.991475Z","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-12T02:08:24.99151Z","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-12T02:08:24.992187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-02-12T02:08:24.99234Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-12T02:08:24.996205Z","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-12T02:08:24.998289Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T02:08:24.99811Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-12T02:08:24.999962Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T02:08:25.001652Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-12T02:08:25.553505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T02:08:25.553544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T02:08:25.553565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-02-12T02:08:25.553575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-02-12T02:08:25.553585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-12T02:08:25.553595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-02-12T02:08:25.553604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-12T02:08:25.579004Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T02:08:25.579035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T02:08:25.579225Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:08:25.579313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T02:08:25.58284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-02-12T02:08:25.588142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T02:08:25.609226Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T02:08:25.609242Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T02:08:25.655321Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:08:25.655551Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:08:25.655619Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T02:08:27.448936Z","caller":"traceutil/trace.go:171","msg":"trace[1918948783] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:8; }","duration":"134.711799ms","start":"2026-02-12T02:08:27.314203Z","end":"2026-02-12T02:08:27.448914Z","steps":["trace[1918948783] 'read index received' (duration: 119.69481ms)","trace[1918948783] 'applied index is now lower than readState.Index' (duration: 15.016069ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:08:27.449095Z","caller":"traceutil/trace.go:171","msg":"trace[1493776328] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"140.109535ms","start":"2026-02-12T02:08:27.308913Z","end":"2026-02-12T02:08:27.449022Z","steps":["trace[1493776328] 'process raft request' (duration: 139.90707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:27.44934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.018463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T02:08:27.450242Z","caller":"traceutil/trace.go:171","msg":"trace[99737226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"140.826934ms","start":"2026-02-12T02:08:27.309277Z","end":"2026-02-12T02:08:27.450104Z","steps":["trace[99737226] 'agreement among raft nodes before linearized reading' (duration: 139.918121ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.451043Z","caller":"traceutil/trace.go:171","msg":"trace[1669946194] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"143.86177ms","start":"2026-02-12T02:08:27.307158Z","end":"2026-02-12T02:08:27.45102Z","steps":["trace[1669946194] 'process raft request' (duration: 126.780339ms)","trace[1669946194] 'compare' (duration: 14.65192ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:08:27.449634Z","caller":"traceutil/trace.go:171","msg":"trace[1120755205] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"140.540816ms","start":"2026-02-12T02:08:27.309079Z","end":"2026-02-12T02:08:27.44962Z","steps":["trace[1120755205] 'process raft request' (duration: 139.782797ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.449722Z","caller":"traceutil/trace.go:171","msg":"trace[133978049] transaction","detail":"{read_only:false; number_of_response:0; response_revision:11; }","duration":"140.525097ms","start":"2026-02-12T02:08:27.309179Z","end":"2026-02-12T02:08:27.449704Z","steps":["trace[133978049] 'process raft request' (duration: 139.705196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:27.449816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.198402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-12T02:08:27.452254Z","caller":"traceutil/trace.go:171","msg":"trace[517318472] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"143.798418ms","start":"2026-02-12T02:08:27.308444Z","end":"2026-02-12T02:08:27.452242Z","steps":["trace[517318472] 'process raft request' (duration: 140.30012ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.452412Z","caller":"traceutil/trace.go:171","msg":"trace[66256705] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"143.516811ms","start":"2026-02-12T02:08:27.30886Z","end":"2026-02-12T02:08:27.452377Z","steps":["trace[66256705] 'process raft request' (duration: 139.91801ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.449453Z","caller":"traceutil/trace.go:171","msg":"trace[1492453602] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"140.420084ms","start":"2026-02-12T02:08:27.309018Z","end":"2026-02-12T02:08:27.449438Z","steps":["trace[1492453602] 'process raft request' (duration: 139.823489ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.453085Z","caller":"traceutil/trace.go:171","msg":"trace[998622476] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:11; }","duration":"139.597471ms","start":"2026-02-12T02:08:27.312596Z","end":"2026-02-12T02:08:27.452194Z","steps":["trace[998622476] 'agreement among raft nodes before linearized reading' (duration: 137.14587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:27.449879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.291585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-12T02:08:27.453353Z","caller":"traceutil/trace.go:171","msg":"trace[1555103648] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:11; }","duration":"140.733941ms","start":"2026-02-12T02:08:27.312581Z","end":"2026-02-12T02:08:27.453315Z","steps":["trace[1555103648] 'agreement among raft nodes before linearized reading' (duration: 137.250873ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.457865Z","caller":"traceutil/trace.go:171","msg":"trace[1813347976] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"137.591212ms","start":"2026-02-12T02:08:27.320262Z","end":"2026-02-12T02:08:27.457853Z","steps":["trace[1813347976] 'process raft request' (duration: 137.214132ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.460599Z","caller":"traceutil/trace.go:171","msg":"trace[456528847] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"137.035308ms","start":"2026-02-12T02:08:27.323554Z","end":"2026-02-12T02:08:27.46059Z","steps":["trace[456528847] 'process raft request' (duration: 133.997241ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.463764Z","caller":"traceutil/trace.go:171","msg":"trace[474213013] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"139.620433ms","start":"2026-02-12T02:08:27.324136Z","end":"2026-02-12T02:08:27.463757Z","steps":["trace[474213013] 'process raft request' (duration: 133.433586ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.464288Z","caller":"traceutil/trace.go:171","msg":"trace[406713925] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"133.95572ms","start":"2026-02-12T02:08:27.330326Z","end":"2026-02-12T02:08:27.464282Z","steps":["trace[406713925] 'process raft request' (duration: 127.26386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:27.464587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.754898ms","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-12T02:08:27.465235Z","caller":"traceutil/trace.go:171","msg":"trace[879778043] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:15; }","duration":"148.421675ms","start":"2026-02-12T02:08:27.316805Z","end":"2026-02-12T02:08:27.465226Z","steps":["trace[879778043] 'agreement among raft nodes before linearized reading' (duration: 147.756069ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.641991Z","caller":"traceutil/trace.go:171","msg":"trace[987940906] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"103.242356ms","start":"2026-02-12T02:08:27.538726Z","end":"2026-02-12T02:08:27.641968Z","steps":["trace[987940906] 'process raft request' (duration: 28.683455ms)","trace[987940906] 'compare' (duration: 74.459908ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:08:27.648013Z","caller":"traceutil/trace.go:171","msg":"trace[605107172] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"105.163222ms","start":"2026-02-12T02:08:27.542833Z","end":"2026-02-12T02:08:27.647996Z","steps":["trace[605107172] 'process raft request' (duration: 105.040629ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.648211Z","caller":"traceutil/trace.go:171","msg":"trace[1517428183] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"104.808594ms","start":"2026-02-12T02:08:27.543397Z","end":"2026-02-12T02:08:27.648206Z","steps":["trace[1517428183] 'process raft request' (duration: 104.544707ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.648403Z","caller":"traceutil/trace.go:171","msg":"trace[644228497] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"104.956777ms","start":"2026-02-12T02:08:27.54344Z","end":"2026-02-12T02:08:27.648397Z","steps":["trace[644228497] 'process raft request' (duration: 104.553537ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.648547Z","caller":"traceutil/trace.go:171","msg":"trace[101804487] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"104.184939ms","start":"2026-02-12T02:08:27.544357Z","end":"2026-02-12T02:08:27.648542Z","steps":["trace[101804487] 'process raft request' (duration: 103.659645ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.648699Z","caller":"traceutil/trace.go:171","msg":"trace[1076590904] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"104.266982ms","start":"2026-02-12T02:08:27.544427Z","end":"2026-02-12T02:08:27.648694Z","steps":["trace[1076590904] 'process raft request' (duration: 103.606814ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.648888Z","caller":"traceutil/trace.go:171","msg":"trace[1834373395] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"104.371155ms","start":"2026-02-12T02:08:27.544512Z","end":"2026-02-12T02:08:27.648883Z","steps":["trace[1834373395] 'process raft request' (duration: 104.016475ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.649116Z","caller":"traceutil/trace.go:171","msg":"trace[1578584667] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"103.8472ms","start":"2026-02-12T02:08:27.545263Z","end":"2026-02-12T02:08:27.64911Z","steps":["trace[1578584667] 'process raft request' (duration: 103.538753ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:27.649251Z","caller":"traceutil/trace.go:171","msg":"trace[691018679] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:29; }","duration":"104.327243ms","start":"2026-02-12T02:08:27.54466Z","end":"2026-02-12T02:08:27.648988Z","steps":["trace[691018679] 'read index received' (duration: 22.839427ms)","trace[691018679] 'applied index is now lower than readState.Index' (duration: 81.487126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:08:27.649339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.666332ms","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-12T02:08:27.64963Z","caller":"traceutil/trace.go:171","msg":"trace[1992044893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33; }","duration":"104.98804ms","start":"2026-02-12T02:08:27.544627Z","end":"2026-02-12T02:08:27.649615Z","steps":["trace[1992044893] 'agreement among raft nodes before linearized reading' (duration: 104.638851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:27.719798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.607135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-12T02:08:27.719844Z","caller":"traceutil/trace.go:171","msg":"trace[690822248] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:34; }","duration":"174.673076ms","start":"2026-02-12T02:08:27.54516Z","end":"2026-02-12T02:08:27.719833Z","steps":["trace[690822248] 'agreement among raft nodes before linearized reading' (duration: 174.537543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:27.720078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.690282ms","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-12T02:08:27.720101Z","caller":"traceutil/trace.go:171","msg":"trace[735374383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:34; }","duration":"173.732393ms","start":"2026-02-12T02:08:27.546363Z","end":"2026-02-12T02:08:27.720095Z","steps":["trace[735374383] 'agreement among raft nodes before linearized reading' (duration: 173.688712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:38.341742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468479,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T02:08:38.635301Z","caller":"traceutil/trace.go:171","msg":"trace[2097521899] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"807.970644ms","start":"2026-02-12T02:08:37.827303Z","end":"2026-02-12T02:08:38.635274Z","steps":["trace[2097521899] 'read index received' (duration: 807.649947ms)","trace[2097521899] 'applied index is now lower than readState.Index' (duration: 319.357µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:08:38.635506Z","caller":"traceutil/trace.go:171","msg":"trace[674030313] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"822.625748ms","start":"2026-02-12T02:08:37.812865Z","end":"2026-02-12T02:08:38.635491Z","steps":["trace[674030313] 'process raft request' (duration: 822.198179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:38.635698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.663043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:08:38.635757Z","caller":"traceutil/trace.go:171","msg":"trace[419364586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"795.759446ms","start":"2026-02-12T02:08:37.839981Z","end":"2026-02-12T02:08:38.635741Z","steps":["trace[419364586] 'agreement among raft nodes before linearized reading' (duration: 795.649523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:38.635814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:37.839969Z","time spent":"795.817757ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:08:38.636078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.764483ms","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-12T02:08:38.636121Z","caller":"traceutil/trace.go:171","msg":"trace[1163057856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"808.837094ms","start":"2026-02-12T02:08:37.82727Z","end":"2026-02-12T02:08:38.636107Z","steps":["trace[1163057856] 'agreement among raft nodes before linearized reading' (duration: 808.763462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:38.636158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:37.827257Z","time spent":"808.891356ms","remote":"127.0.0.1:56336","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-12T02:08:38.636232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:37.812844Z","time spent":"822.70008ms","remote":"127.0.0.1:56336","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-12T02:08:39.111347Z","caller":"traceutil/trace.go:171","msg":"trace[2039658182] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"467.49552ms","start":"2026-02-12T02:08:38.643829Z","end":"2026-02-12T02:08:39.111325Z","steps":["trace[2039658182] 'process raft request' (duration: 467.328997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:39.111499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:38.64376Z","time spent":"467.655923ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:08:39.116884Z","caller":"traceutil/trace.go:171","msg":"trace[2038341311] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"472.410949ms","start":"2026-02-12T02:08:38.644455Z","end":"2026-02-12T02:08:39.116866Z","steps":["trace[2038341311] 'process raft request' (duration: 472.168094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:39.117042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:38.644448Z","time spent":"472.526202ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:08:39.117616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.377019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T02:08:39.117663Z","caller":"traceutil/trace.go:171","msg":"trace[406184479] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:410; }","duration":"460.445191ms","start":"2026-02-12T02:08:38.657211Z","end":"2026-02-12T02:08:39.117657Z","steps":["trace[406184479] 'agreement among raft nodes before linearized reading' (duration: 460.377689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:39.117692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:38.657202Z","time spent":"460.485011ms","remote":"127.0.0.1:56070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-02-12T02:08:39.118569Z","caller":"traceutil/trace.go:171","msg":"trace[759462915] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"459.472178ms","start":"2026-02-12T02:08:38.657235Z","end":"2026-02-12T02:08:39.116707Z","steps":["trace[759462915] 'read index received' (duration: 453.856302ms)","trace[759462915] 'applied index is now lower than readState.Index' (duration: 5.615006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:08:39.12467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.891336ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:08:39.124718Z","caller":"traceutil/trace.go:171","msg":"trace[127886020] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:410; }","duration":"282.940707ms","start":"2026-02-12T02:08:38.841771Z","end":"2026-02-12T02:08:39.124712Z","steps":["trace[127886020] 'agreement among raft nodes before linearized reading' (duration: 282.885476ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:39.670134Z","caller":"traceutil/trace.go:171","msg":"trace[858140364] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"130.555882ms","start":"2026-02-12T02:08:39.539549Z","end":"2026-02-12T02:08:39.670105Z","steps":["trace[858140364] 'process raft request' (duration: 130.371918ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:43.298906Z","caller":"traceutil/trace.go:171","msg":"trace[65972920] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"157.288058ms","start":"2026-02-12T02:08:43.141599Z","end":"2026-02-12T02:08:43.298887Z","steps":["trace[65972920] 'process raft request' (duration: 87.597407ms)","trace[65972920] 'compare' (duration: 69.330764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:08:43.300062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.698144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-12T02:08:43.300161Z","caller":"traceutil/trace.go:171","msg":"trace[1426591368] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:427; }","duration":"148.827367ms","start":"2026-02-12T02:08:43.151321Z","end":"2026-02-12T02:08:43.300148Z","steps":["trace[1426591368] 'agreement among raft nodes before linearized reading' (duration: 148.681214ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:43.3006Z","caller":"traceutil/trace.go:171","msg":"trace[608190371] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:440; }","duration":"147.313104ms","start":"2026-02-12T02:08:43.151345Z","end":"2026-02-12T02:08:43.298658Z","steps":["trace[608190371] 'read index received' (duration: 77.816036ms)","trace[608190371] 'applied index is now lower than readState.Index' (duration: 69.496158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:08:43.300868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.209038ms","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-12T02:08:43.300937Z","caller":"traceutil/trace.go:171","msg":"trace[1738599852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"132.308841ms","start":"2026-02-12T02:08:43.168618Z","end":"2026-02-12T02:08:43.300927Z","steps":["trace[1738599852] 'agreement among raft nodes before linearized reading' (duration: 132.184848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:43.622478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.144828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:08:43.622541Z","caller":"traceutil/trace.go:171","msg":"trace[450327476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"101.262181ms","start":"2026-02-12T02:08:43.521265Z","end":"2026-02-12T02:08:43.622527Z","steps":["trace[450327476] 'range keys from in-memory index tree' (duration: 101.090037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:51.716707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:08:52.217033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:08:52.671437Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.579880815s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T02:08:52.671847Z","caller":"traceutil/trace.go:171","msg":"trace[2051198626] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"1.482588707s","start":"2026-02-12T02:08:51.189243Z","end":"2026-02-12T02:08:52.671832Z","steps":["trace[2051198626] 'read index received' (duration: 1.482475935s)","trace[2051198626] 'applied index is now lower than readState.Index' (duration: 112.212µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:08:52.671991Z","caller":"traceutil/trace.go:171","msg":"trace[1344738129] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"1.580506797s","start":"2026-02-12T02:08:51.091477Z","end":"2026-02-12T02:08:52.671983Z","steps":["trace[1344738129] 'process raft request' (duration: 1.580280523s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:52.672057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:51.091458Z","time spent":"1.580551408s","remote":"127.0.0.1:56336","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-12T02:08:52.672163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482923614s","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-12T02:08:52.67218Z","caller":"traceutil/trace.go:171","msg":"trace[490847283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"1.482964995s","start":"2026-02-12T02:08:51.189209Z","end":"2026-02-12T02:08:52.672174Z","steps":["trace[490847283] 'agreement among raft nodes before linearized reading' (duration: 1.482934944s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:52.672198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:51.189192Z","time spent":"1.482999485s","remote":"127.0.0.1:56336","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-12T02:08:52.672266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342088486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:08:52.672279Z","caller":"traceutil/trace.go:171","msg":"trace[1940428230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"1.342127116s","start":"2026-02-12T02:08:51.330148Z","end":"2026-02-12T02:08:52.672275Z","steps":["trace[1940428230] 'agreement among raft nodes before linearized reading' (duration: 1.342104635s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:52.672292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:51.330133Z","time spent":"1.342155346s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:08:52.672412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148408988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:08:52.672434Z","caller":"traceutil/trace.go:171","msg":"trace[2023102458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"1.14845798s","start":"2026-02-12T02:08:51.523969Z","end":"2026-02-12T02:08:52.672427Z","steps":["trace[2023102458] 'agreement among raft nodes before linearized reading' (duration: 1.148425709s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:52.672448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:51.523955Z","time spent":"1.14849025s","remote":"127.0.0.1:56016","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-12T02:08:52.672555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196807097s","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-12T02:08:52.672568Z","caller":"traceutil/trace.go:171","msg":"trace[1336869345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"1.196846747s","start":"2026-02-12T02:08:51.475718Z","end":"2026-02-12T02:08:52.672564Z","steps":["trace[1336869345] 'agreement among raft nodes before linearized reading' (duration: 1.196818567s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:52.67258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:51.475702Z","time spent":"1.196875899s","remote":"127.0.0.1:56336","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-12T02:08:52.67265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276456091s","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-12T02:08:52.672663Z","caller":"traceutil/trace.go:171","msg":"trace[65557356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:473; }","duration":"1.276490731s","start":"2026-02-12T02:08:51.396168Z","end":"2026-02-12T02:08:52.672659Z","steps":["trace[65557356] 'agreement among raft nodes before linearized reading' (duration: 1.276463191s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:52.672674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:51.396155Z","time spent":"1.276516822s","remote":"127.0.0.1:56336","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-12T02:08:54.844787Z","caller":"traceutil/trace.go:171","msg":"trace[141737485] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"124.10589ms","start":"2026-02-12T02:08:54.720622Z","end":"2026-02-12T02:08:54.844728Z","steps":["trace[141737485] 'process raft request' (duration: 122.585ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:54.845008Z","caller":"traceutil/trace.go:171","msg":"trace[2118177350] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"123.168031ms","start":"2026-02-12T02:08:54.721821Z","end":"2026-02-12T02:08:54.844989Z","steps":["trace[2118177350] 'process raft request' (duration: 122.828725ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:54.845169Z","caller":"traceutil/trace.go:171","msg":"trace[1106242029] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"121.686092ms","start":"2026-02-12T02:08:54.723478Z","end":"2026-02-12T02:08:54.845164Z","steps":["trace[1106242029] 'process raft request' (duration: 121.455628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:57.352073Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:08:57.853271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:08:58.353989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:08:58.512318Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.783489735s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T02:08:58.51287Z","caller":"traceutil/trace.go:171","msg":"trace[1254660713] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"1.661773823s","start":"2026-02-12T02:08:56.851073Z","end":"2026-02-12T02:08:58.512847Z","steps":["trace[1254660713] 'read index received' (duration: 1.661360256s)","trace[1254660713] 'applied index is now lower than readState.Index' (duration: 412.397µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:08:58.513244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.611675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:08:58.513291Z","caller":"traceutil/trace.go:171","msg":"trace[1017334579] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:482; }","duration":"699.696756ms","start":"2026-02-12T02:08:57.813585Z","end":"2026-02-12T02:08:58.513281Z","steps":["trace[1017334579] 'agreement among raft nodes before linearized reading' (duration: 699.612115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:58.513325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:57.813571Z","time spent":"699.746077ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-12T02:08:58.513246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661732782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-12T02:08:58.513552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662475506s","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-12T02:08:58.513588Z","caller":"traceutil/trace.go:171","msg":"trace[1462881213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"1.662547137s","start":"2026-02-12T02:08:56.851033Z","end":"2026-02-12T02:08:58.51358Z","steps":["trace[1462881213] 'agreement among raft nodes before linearized reading' (duration: 1.662486646s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:58.513613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:56.850933Z","time spent":"1.662674449s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T02:08:58.513817Z","caller":"traceutil/trace.go:171","msg":"trace[774668765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"1.662113609s","start":"2026-02-12T02:08:56.851448Z","end":"2026-02-12T02:08:58.513562Z","steps":["trace[774668765] 'agreement among raft nodes before linearized reading' (duration: 1.661703261s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:58.51401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660150421s","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-12T02:08:58.514048Z","caller":"traceutil/trace.go:171","msg":"trace[1973211209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"1.660198432s","start":"2026-02-12T02:08:56.85384Z","end":"2026-02-12T02:08:58.514039Z","steps":["trace[1973211209] 'agreement among raft nodes before linearized reading' (duration: 1.66010617s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:58.514074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:56.853834Z","time spent":"1.660233854s","remote":"127.0.0.1:56336","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":"info","ts":"2026-02-12T02:08:58.513501Z","caller":"traceutil/trace.go:171","msg":"trace[156467468] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.784720198s","start":"2026-02-12T02:08:56.72877Z","end":"2026-02-12T02:08:58.51349Z","steps":["trace[156467468] 'process raft request' (duration: 1.78374292s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:58.514309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:56.728762Z","time spent":"1.785499963s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:08:58.514219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:56.851429Z","time spent":"1.662770752s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:08:58.513906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.687354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:08:58.514602Z","caller":"traceutil/trace.go:171","msg":"trace[643490685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"990.414637ms","start":"2026-02-12T02:08:57.524171Z","end":"2026-02-12T02:08:58.514585Z","steps":["trace[643490685] 'agreement among raft nodes before linearized reading' (duration: 989.711733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:58.514767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:08:57.524151Z","time spent":"990.569341ms","remote":"127.0.0.1:56016","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-12T02:08:58.650151Z","caller":"traceutil/trace.go:171","msg":"trace[757946530] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"126.255598ms","start":"2026-02-12T02:08:58.523876Z","end":"2026-02-12T02:08:58.650132Z","steps":["trace[757946530] 'read index received' (duration: 63.592838ms)","trace[757946530] 'applied index is now lower than readState.Index' (duration: 62.661369ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:08:58.650413Z","caller":"traceutil/trace.go:171","msg":"trace[1757240004] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"127.750025ms","start":"2026-02-12T02:08:58.522636Z","end":"2026-02-12T02:08:58.650386Z","steps":["trace[1757240004] 'process raft request' (duration: 64.908423ms)","trace[1757240004] 'compare' (duration: 62.505066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:08:58.650477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.576153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:08:58.650573Z","caller":"traceutil/trace.go:171","msg":"trace[1877611771] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:483; }","duration":"126.694605ms","start":"2026-02-12T02:08:58.523854Z","end":"2026-02-12T02:08:58.650549Z","steps":["trace[1877611771] 'agreement among raft nodes before linearized reading' (duration: 126.558853ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:58.692239Z","caller":"traceutil/trace.go:171","msg":"trace[2097085663] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"166.912473ms","start":"2026-02-12T02:08:58.525301Z","end":"2026-02-12T02:08:58.692213Z","steps":["trace[2097085663] 'process raft request' (duration: 165.346214ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:08:58.692669Z","caller":"traceutil/trace.go:171","msg":"trace[123553969] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"167.31702ms","start":"2026-02-12T02:08:58.52534Z","end":"2026-02-12T02:08:58.692657Z","steps":["trace[123553969] 'process raft request' (duration: 166.361923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:08:58.693068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.23382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:08:58.693132Z","caller":"traceutil/trace.go:171","msg":"trace[1662790652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"166.309242ms","start":"2026-02-12T02:08:58.52681Z","end":"2026-02-12T02:08:58.693119Z","steps":["trace[1662790652] 'agreement among raft nodes before linearized reading' (duration: 166.058167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:05.311586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.981304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:09:05.311677Z","caller":"traceutil/trace.go:171","msg":"trace[1013907650] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"260.256955ms","start":"2026-02-12T02:09:05.051407Z","end":"2026-02-12T02:09:05.311664Z","steps":["trace[1013907650] 'process raft request' (duration: 87.030176ms)","trace[1013907650] 'compare' (duration: 172.879203ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:05.345806Z","caller":"traceutil/trace.go:171","msg":"trace[196599607] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"112.038841ms","start":"2026-02-12T02:09:05.23375Z","end":"2026-02-12T02:09:05.345789Z","steps":["trace[196599607] 'process raft request' (duration: 111.802557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:05.701495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.865195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:09:05.702002Z","caller":"traceutil/trace.go:171","msg":"trace[410697179] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"281.775266ms","start":"2026-02-12T02:09:05.420215Z","end":"2026-02-12T02:09:05.701991Z","steps":["trace[410697179] 'process raft request' (duration: 281.718155ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:05.702455Z","caller":"traceutil/trace.go:171","msg":"trace[650509075] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:609; }","duration":"181.978824ms","start":"2026-02-12T02:09:05.520443Z","end":"2026-02-12T02:09:05.702422Z","steps":["trace[650509075] 'read index received' (duration: 181.974304ms)","trace[650509075] 'applied index is now lower than readState.Index' (duration: 3.61µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:05.7016Z","caller":"traceutil/trace.go:171","msg":"trace[916677729] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"349.391097ms","start":"2026-02-12T02:09:05.35219Z","end":"2026-02-12T02:09:05.701582Z","steps":["trace[916677729] 'process raft request' (duration: 151.37846ms)","trace[916677729] 'compare' (duration: 197.739033ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:05.703407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.959321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:05.705557Z","caller":"traceutil/trace.go:171","msg":"trace[1518649181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"185.126209ms","start":"2026-02-12T02:09:05.520413Z","end":"2026-02-12T02:09:05.705539Z","steps":["trace[1518649181] 'agreement among raft nodes before linearized reading' (duration: 182.078385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:05.70331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:05.352173Z","time spent":"350.949846ms","remote":"127.0.0.1:56080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:09:05.738665Z","caller":"traceutil/trace.go:171","msg":"trace[1658657930] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"114.378102ms","start":"2026-02-12T02:09:05.624276Z","end":"2026-02-12T02:09:05.738654Z","steps":["trace[1658657930] 'process raft request' (duration: 114.25443ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:06.957517Z","caller":"traceutil/trace.go:171","msg":"trace[1181908036] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"170.668928ms","start":"2026-02-12T02:09:06.786823Z","end":"2026-02-12T02:09:06.957492Z","steps":["trace[1181908036] 'read index received' (duration: 170.441703ms)","trace[1181908036] 'applied index is now lower than readState.Index' (duration: 226.315µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:06.957824Z","caller":"traceutil/trace.go:171","msg":"trace[2020842522] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"197.595884ms","start":"2026-02-12T02:09:06.760211Z","end":"2026-02-12T02:09:06.957807Z","steps":["trace[2020842522] 'process raft request' (duration: 197.109134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:06.958195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.35233ms","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-12T02:09:06.958285Z","caller":"traceutil/trace.go:171","msg":"trace[1798325771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"171.474763ms","start":"2026-02-12T02:09:06.786797Z","end":"2026-02-12T02:09:06.958272Z","steps":["trace[1798325771] 'agreement among raft nodes before linearized reading' (duration: 171.255049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:06.958521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.223628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:09:06.958586Z","caller":"traceutil/trace.go:171","msg":"trace[505201054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"146.273218ms","start":"2026-02-12T02:09:06.812275Z","end":"2026-02-12T02:09:06.958548Z","steps":["trace[505201054] 'agreement among raft nodes before linearized reading' (duration: 146.171817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:06.95884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.579417ms","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-12T02:09:06.958876Z","caller":"traceutil/trace.go:171","msg":"trace[608498880] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:599; }","duration":"140.630679ms","start":"2026-02-12T02:09:06.818235Z","end":"2026-02-12T02:09:06.958866Z","steps":["trace[608498880] 'agreement among raft nodes before linearized reading' (duration: 140.525977ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:07.892715Z","caller":"traceutil/trace.go:171","msg":"trace[528507374] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"148.750981ms","start":"2026-02-12T02:09:07.743924Z","end":"2026-02-12T02:09:07.892675Z","steps":["trace[528507374] 'process raft request' (duration: 147.893677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:11.511026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468914,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:12.011381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468914,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:12.382327Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.407731913s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T02:09:12.382704Z","caller":"traceutil/trace.go:171","msg":"trace[779352058] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"1.372121767s","start":"2026-02-12T02:09:11.010557Z","end":"2026-02-12T02:09:12.382678Z","steps":["trace[779352058] 'read index received' (duration: 1.371917743s)","trace[779352058] 'applied index is now lower than readState.Index' (duration: 202.644µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:12.383028Z","caller":"traceutil/trace.go:171","msg":"trace[1020824555] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.408452964s","start":"2026-02-12T02:09:10.97452Z","end":"2026-02-12T02:09:12.382973Z","steps":["trace[1020824555] 'process raft request' (duration: 1.408012567s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:12.383162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:10.974508Z","time spent":"1.408560177s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:09:12.383786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369207018s","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-12T02:09:12.383828Z","caller":"traceutil/trace.go:171","msg":"trace[333560178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.369268258s","start":"2026-02-12T02:09:11.014551Z","end":"2026-02-12T02:09:12.38382Z","steps":["trace[333560178] 'agreement among raft nodes before linearized reading' (duration: 1.369159147s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:12.383848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:11.014546Z","time spent":"1.369297599s","remote":"127.0.0.1:56336","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-12T02:09:12.383996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.071857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:12.384014Z","caller":"traceutil/trace.go:171","msg":"trace[458740345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"860.107879ms","start":"2026-02-12T02:09:11.523901Z","end":"2026-02-12T02:09:12.384008Z","steps":["trace[458740345] 'agreement among raft nodes before linearized reading' (duration: 860.081068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:12.384029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:11.523887Z","time spent":"860.138599ms","remote":"127.0.0.1:56016","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-12T02:09:12.385617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375058276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:09:12.38571Z","caller":"traceutil/trace.go:171","msg":"trace[364223516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.375174537s","start":"2026-02-12T02:09:11.010529Z","end":"2026-02-12T02:09:12.385703Z","steps":["trace[364223516] 'agreement among raft nodes before linearized reading' (duration: 1.372886319s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:12.385922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:11.010515Z","time spent":"1.375398862s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:09:14.889763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:15.390752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:15.891753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468926,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:16.02639Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.977304257s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T02:09:16.296978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.447444ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-12T02:09:16.297405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.90788264s","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-12T02:09:16.297455Z","caller":"traceutil/trace.go:171","msg":"trace[27163718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"1.907962331s","start":"2026-02-12T02:09:14.389478Z","end":"2026-02-12T02:09:16.29744Z","steps":["trace[27163718] 'agreement among raft nodes before linearized reading' (duration: 1.907781738s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:16.297524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:14.389464Z","time spent":"1.908026802s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T02:09:16.297215Z","caller":"traceutil/trace.go:171","msg":"trace[1601264829] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"1.907687847s","start":"2026-02-12T02:09:14.389505Z","end":"2026-02-12T02:09:16.297193Z","steps":["trace[1601264829] 'read index received' (duration: 1.637014149s)","trace[1601264829] 'applied index is now lower than readState.Index' (duration: 270.671528ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:16.299396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904285169s","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-12T02:09:16.299686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.723882231s","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-12T02:09:16.2998Z","caller":"traceutil/trace.go:171","msg":"trace[1890637067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"1.723982502s","start":"2026-02-12T02:09:14.575761Z","end":"2026-02-12T02:09:16.299744Z","steps":["trace[1890637067] 'agreement among raft nodes before linearized reading' (duration: 1.723757688s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:16.300036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:14.575745Z","time spent":"1.724272078s","remote":"127.0.0.1:56336","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-12T02:09:16.300504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895324752s","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-12T02:09:16.300847Z","caller":"traceutil/trace.go:171","msg":"trace[1245645705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.895673917s","start":"2026-02-12T02:09:14.405154Z","end":"2026-02-12T02:09:16.300828Z","steps":["trace[1245645705] 'agreement among raft nodes before linearized reading' (duration: 1.895175139s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:16.301196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:14.40515Z","time spent":"1.896027463s","remote":"127.0.0.1:56336","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-12T02:09:16.301539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.896536712s","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-12T02:09:16.30213Z","caller":"traceutil/trace.go:171","msg":"trace[1815016999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.897141531s","start":"2026-02-12T02:09:14.40497Z","end":"2026-02-12T02:09:16.302112Z","steps":["trace[1815016999] 'agreement among raft nodes before linearized reading' (duration: 1.89646425s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:16.302497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:14.404961Z","time spent":"1.897514547s","remote":"127.0.0.1:56336","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-12T02:09:16.301618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.221162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:16.303221Z","caller":"traceutil/trace.go:171","msg":"trace[1689783747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"781.839888ms","start":"2026-02-12T02:09:15.521363Z","end":"2026-02-12T02:09:16.303203Z","steps":["trace[1689783747] 'agreement among raft nodes before linearized reading' (duration: 780.229182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:16.303553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:15.521348Z","time spent":"782.184555ms","remote":"127.0.0.1:56016","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-12T02:09:16.299459Z","caller":"traceutil/trace.go:171","msg":"trace[1066425003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:623; }","duration":"1.90436497s","start":"2026-02-12T02:09:14.395077Z","end":"2026-02-12T02:09:16.299441Z","steps":["trace[1066425003] 'agreement among raft nodes before linearized reading' (duration: 1.904200188s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:16.304599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:14.395059Z","time spent":"1.909519435s","remote":"127.0.0.1:56336","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":"info","ts":"2026-02-12T02:09:16.530738Z","caller":"traceutil/trace.go:171","msg":"trace[335148456] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"221.736199ms","start":"2026-02-12T02:09:16.308975Z","end":"2026-02-12T02:09:16.530711Z","steps":["trace[335148456] 'process raft request' (duration: 192.540301ms)","trace[335148456] 'compare' (duration: 28.981484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:16.530949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.896782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:16.531081Z","caller":"traceutil/trace.go:171","msg":"trace[2068836370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"222.040264ms","start":"2026-02-12T02:09:16.30901Z","end":"2026-02-12T02:09:16.531051Z","steps":["trace[2068836370] 'agreement among raft nodes before linearized reading' (duration: 221.81067ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:16.531468Z","caller":"traceutil/trace.go:171","msg":"trace[487325571] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"221.429594ms","start":"2026-02-12T02:09:16.310027Z","end":"2026-02-12T02:09:16.531456Z","steps":["trace[487325571] 'process raft request' (duration: 220.588811ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:16.530783Z","caller":"traceutil/trace.go:171","msg":"trace[1600975890] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"221.733659ms","start":"2026-02-12T02:09:16.309029Z","end":"2026-02-12T02:09:16.530762Z","steps":["trace[1600975890] 'read index received' (duration: 49.785733ms)","trace[1600975890] 'applied index is now lower than readState.Index' (duration: 171.946946ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:16.531792Z","caller":"traceutil/trace.go:171","msg":"trace[949579989] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"221.312012ms","start":"2026-02-12T02:09:16.310441Z","end":"2026-02-12T02:09:16.531753Z","steps":["trace[949579989] 'process raft request' (duration: 220.209275ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:16.532155Z","caller":"traceutil/trace.go:171","msg":"trace[548387829] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"220.432747ms","start":"2026-02-12T02:09:16.31171Z","end":"2026-02-12T02:09:16.532143Z","steps":["trace[548387829] 'process raft request' (duration: 218.990554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:22.749179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.435642ms","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-12T02:09:22.749217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.992301ms","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-12T02:09:22.749283Z","caller":"traceutil/trace.go:171","msg":"trace[1559854805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"176.085693ms","start":"2026-02-12T02:09:22.573183Z","end":"2026-02-12T02:09:22.749269Z","steps":["trace[1559854805] 'range keys from in-memory index tree' (duration: 175.885479ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:22.749273Z","caller":"traceutil/trace.go:171","msg":"trace[1391880312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"189.559683ms","start":"2026-02-12T02:09:22.559687Z","end":"2026-02-12T02:09:22.749246Z","steps":["trace[1391880312] 'range keys from in-memory index tree' (duration: 189.290329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:22.74943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.382426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:09:22.749445Z","caller":"traceutil/trace.go:171","msg":"trace[2129186980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"174.410116ms","start":"2026-02-12T02:09:22.575031Z","end":"2026-02-12T02:09:22.749441Z","steps":["trace[2129186980] 'range keys from in-memory index tree' (duration: 174.331925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:22.749573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.927666ms","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-12T02:09:22.749587Z","caller":"traceutil/trace.go:171","msg":"trace[1195508898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"199.978686ms","start":"2026-02-12T02:09:22.549604Z","end":"2026-02-12T02:09:22.749582Z","steps":["trace[1195508898] 'range keys from in-memory index tree' (duration: 199.835484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:22.74963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.220798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:22.749643Z","caller":"traceutil/trace.go:171","msg":"trace[2119774952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"228.232538ms","start":"2026-02-12T02:09:22.521406Z","end":"2026-02-12T02:09:22.749638Z","steps":["trace[2119774952] 'range keys from in-memory index tree' (duration: 228.138607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:24.023099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898468989,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T02:09:24.156719Z","caller":"traceutil/trace.go:171","msg":"trace[1091635274] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"890.930753ms","start":"2026-02-12T02:09:23.265772Z","end":"2026-02-12T02:09:24.156702Z","steps":["trace[1091635274] 'process raft request' (duration: 890.848611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:24.156805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:23.265753Z","time spent":"891.012394ms","remote":"127.0.0.1:56124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T02:09:25.523741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000804797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T02:09:25.523833Z","caller":"traceutil/trace.go:171","msg":"trace[2010060997] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000934248s","start":"2026-02-12T02:09:23.522883Z","end":"2026-02-12T02:09:25.523818Z","steps":["trace[2010060997] 'agreement among raft nodes before linearized reading' (duration: 2.000817627s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:25.52387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:23.522868Z","time spent":"2.00099458s","remote":"127.0.0.1:56016","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-12T02:09:25.586135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.473609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T02:09:25.586219Z","caller":"traceutil/trace.go:171","msg":"trace[732066537] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"2.063298273s","start":"2026-02-12T02:09:23.522905Z","end":"2026-02-12T02:09:25.586204Z","steps":["trace[732066537] 'read index received' (duration: 633.793171ms)","trace[732066537] 'applied index is now lower than readState.Index' (duration: 1.429503872s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:25.586611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.217078ms","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-12T02:09:25.586675Z","caller":"traceutil/trace.go:171","msg":"trace[1747260969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:649; }","duration":"749.300969ms","start":"2026-02-12T02:09:24.837357Z","end":"2026-02-12T02:09:25.586658Z","steps":["trace[1747260969] 'agreement among raft nodes before linearized reading' (duration: 749.081326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:25.586717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:24.837349Z","time spent":"749.355181ms","remote":"127.0.0.1:56336","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-12T02:09:25.586774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.938149591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k7ctf\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-12T02:09:25.586801Z","caller":"traceutil/trace.go:171","msg":"trace[1941424122] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k7ctf; range_end:; response_count:1; response_revision:649; }","duration":"1.938208652s","start":"2026-02-12T02:09:23.648584Z","end":"2026-02-12T02:09:25.586793Z","steps":["trace[1941424122] 'agreement among raft nodes before linearized reading' (duration: 1.937716684s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:25.586826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:23.648571Z","time spent":"1.938249072s","remote":"127.0.0.1:56244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-k7ctf\" "} {"level":"warn","ts":"2026-02-12T02:09:25.587104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.128246ms","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-12T02:09:25.587137Z","caller":"traceutil/trace.go:171","msg":"trace[649694847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:649; }","duration":"749.161797ms","start":"2026-02-12T02:09:24.837966Z","end":"2026-02-12T02:09:25.587128Z","steps":["trace[649694847] 'agreement among raft nodes before linearized reading' (duration: 749.074716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:25.587164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:24.837945Z","time spent":"749.213088ms","remote":"127.0.0.1:56336","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-12T02:09:25.587219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.878388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:09:25.587243Z","caller":"traceutil/trace.go:171","msg":"trace[1052864722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:649; }","duration":"749.935729ms","start":"2026-02-12T02:09:24.8373Z","end":"2026-02-12T02:09:25.587236Z","steps":["trace[1052864722] 'agreement among raft nodes before linearized reading' (duration: 749.860107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:25.587261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:24.837282Z","time spent":"749.97498ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:09:25.587595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.365223ms","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-12T02:09:25.587627Z","caller":"traceutil/trace.go:171","msg":"trace[1541144121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"794.433314ms","start":"2026-02-12T02:09:24.793183Z","end":"2026-02-12T02:09:25.587617Z","steps":["trace[1541144121] 'agreement among raft nodes before linearized reading' (duration: 794.347933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:25.587653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:24.793164Z","time spent":"794.483205ms","remote":"127.0.0.1:56336","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-12T02:09:25.587758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168154023s","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-12T02:09:25.587781Z","caller":"traceutil/trace.go:171","msg":"trace[2076634132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"1.168202195s","start":"2026-02-12T02:09:24.419571Z","end":"2026-02-12T02:09:25.587774Z","steps":["trace[2076634132] 'agreement among raft nodes before linearized reading' (duration: 1.168139344s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:25.587804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:24.419552Z","time spent":"1.168246605s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T02:09:25.719568Z","caller":"traceutil/trace.go:171","msg":"trace[558734267] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"120.506555ms","start":"2026-02-12T02:09:25.599042Z","end":"2026-02-12T02:09:25.719548Z","steps":["trace[558734267] 'process raft request' (duration: 120.381723ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:25.720095Z","caller":"traceutil/trace.go:171","msg":"trace[743189603] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:673; }","duration":"120.84194ms","start":"2026-02-12T02:09:25.599238Z","end":"2026-02-12T02:09:25.72008Z","steps":["trace[743189603] 'read index received' (duration: 120.83762ms)","trace[743189603] 'applied index is now lower than readState.Index' (duration: 3.52µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:25.72019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.936991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:25.720224Z","caller":"traceutil/trace.go:171","msg":"trace[1081350878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"120.995842ms","start":"2026-02-12T02:09:25.59922Z","end":"2026-02-12T02:09:25.720215Z","steps":["trace[1081350878] 'agreement among raft nodes before linearized reading' (duration: 120.916441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.024422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898469001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:27.202218Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.482939796s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T02:09:27.202544Z","caller":"traceutil/trace.go:171","msg":"trace[917022862] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:673; }","duration":"678.311193ms","start":"2026-02-12T02:09:26.524219Z","end":"2026-02-12T02:09:27.202531Z","steps":["trace[917022862] 'read index received' (duration: 678.056209ms)","trace[917022862] 'applied index is now lower than readState.Index' (duration: 254.504µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:27.202598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.367474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:27.202612Z","caller":"traceutil/trace.go:171","msg":"trace[1849010575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"678.420745ms","start":"2026-02-12T02:09:26.524187Z","end":"2026-02-12T02:09:27.202608Z","steps":["trace[1849010575] 'agreement among raft nodes before linearized reading' (duration: 678.375194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.202628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:26.524171Z","time spent":"678.453175ms","remote":"127.0.0.1:56024","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-12T02:09:27.202777Z","caller":"traceutil/trace.go:171","msg":"trace[919577493] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.601020665s","start":"2026-02-12T02:09:25.601751Z","end":"2026-02-12T02:09:27.202772Z","steps":["trace[919577493] 'process raft request' (duration: 1.600554997s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.202822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:25.60174Z","time spent":"1.601048855s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:09:27.202881Z","caller":"traceutil/trace.go:171","msg":"trace[1006503218] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.598965203s","start":"2026-02-12T02:09:25.603911Z","end":"2026-02-12T02:09:27.202877Z","steps":["trace[1006503218] 'process raft request' (duration: 1.598479315s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.202903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:25.603903Z","time spent":"1.598986783s","remote":"127.0.0.1:56336","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-12T02:09:27.202951Z","caller":"traceutil/trace.go:171","msg":"trace[897902858] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"1.598992563s","start":"2026-02-12T02:09:25.603955Z","end":"2026-02-12T02:09:27.202947Z","steps":["trace[897902858] 'process raft request' (duration: 1.598453104s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.202973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:25.60395Z","time spent":"1.599011663s","remote":"127.0.0.1:56336","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-12T02:09:27.203052Z","caller":"traceutil/trace.go:171","msg":"trace[739016265] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"1.598424845s","start":"2026-02-12T02:09:25.604623Z","end":"2026-02-12T02:09:27.203047Z","steps":["trace[739016265] 'process raft request' (duration: 1.597798644s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.203076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:25.604617Z","time spent":"1.598446635s","remote":"127.0.0.1:56336","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-12T02:09:27.203164Z","caller":"traceutil/trace.go:171","msg":"trace[97502400] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.595782634s","start":"2026-02-12T02:09:25.607377Z","end":"2026-02-12T02:09:27.203159Z","steps":["trace[97502400] 'process raft request' (duration: 1.595083573s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.203188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:25.60737Z","time spent":"1.595804554s","remote":"127.0.0.1:56244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:09:27.203235Z","caller":"traceutil/trace.go:171","msg":"trace[1180774114] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.594590286s","start":"2026-02-12T02:09:25.60864Z","end":"2026-02-12T02:09:27.203231Z","steps":["trace[1180774114] 'process raft request' (duration: 1.593874575s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.203254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:25.608635Z","time spent":"1.594608326s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:09:27.931767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.177946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:27.931835Z","caller":"traceutil/trace.go:171","msg":"trace[18360802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"410.286767ms","start":"2026-02-12T02:09:27.521537Z","end":"2026-02-12T02:09:27.931824Z","steps":["trace[18360802] 'range keys from in-memory index tree' (duration: 410.115205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:27.931856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:27.521518Z","time spent":"410.334267ms","remote":"127.0.0.1:56016","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-12T02:09:29.712082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898469007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:30.212562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898469007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:30.713379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898469007,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:09:30.865736Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.172721847s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T02:09:30.868102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:28.692891Z","time spent":"2.175206104s","remote":"127.0.0.1:56070","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T02:09:30.868583Z","caller":"traceutil/trace.go:171","msg":"trace[1041603936] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"1.656672835s","start":"2026-02-12T02:09:29.211891Z","end":"2026-02-12T02:09:30.868564Z","steps":["trace[1041603936] 'read index received' (duration: 1.656080406s)","trace[1041603936] 'applied index is now lower than readState.Index' (duration: 591.719µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:30.868684Z","caller":"traceutil/trace.go:171","msg":"trace[651142968] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.452925999s","start":"2026-02-12T02:09:29.415715Z","end":"2026-02-12T02:09:30.868641Z","steps":["trace[651142968] 'process raft request' (duration: 1.452684685s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:30.86877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:29.415697Z","time spent":"1.45300838s","remote":"127.0.0.1:56336","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-12T02:09:30.869011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656666714s","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-12T02:09:30.86906Z","caller":"traceutil/trace.go:171","msg":"trace[1047830822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"1.656761846s","start":"2026-02-12T02:09:29.21228Z","end":"2026-02-12T02:09:30.869041Z","steps":["trace[1047830822] 'agreement among raft nodes before linearized reading' (duration: 1.656569953s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:30.869096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.651361745s","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-12T02:09:30.869119Z","caller":"traceutil/trace.go:171","msg":"trace[1877090999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"1.651400296s","start":"2026-02-12T02:09:29.217712Z","end":"2026-02-12T02:09:30.869113Z","steps":["trace[1877090999] 'agreement among raft nodes before linearized reading' (duration: 1.651325805s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:30.869123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:29.212272Z","time spent":"1.656843077s","remote":"127.0.0.1:56336","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-12T02:09:30.869135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:29.217706Z","time spent":"1.651426126s","remote":"127.0.0.1:56336","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-12T02:09:30.869257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657363805s","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-12T02:09:30.86928Z","caller":"traceutil/trace.go:171","msg":"trace[811702773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"1.657419715s","start":"2026-02-12T02:09:29.211854Z","end":"2026-02-12T02:09:30.869274Z","steps":["trace[811702773] 'agreement among raft nodes before linearized reading' (duration: 1.657362784s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:30.869298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:29.211836Z","time spent":"1.657456606s","remote":"127.0.0.1:56336","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-12T02:09:30.869387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347180353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:30.869403Z","caller":"traceutil/trace.go:171","msg":"trace[424757824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"1.347230323s","start":"2026-02-12T02:09:29.522167Z","end":"2026-02-12T02:09:30.869397Z","steps":["trace[424757824] 'agreement among raft nodes before linearized reading' (duration: 1.347204782s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:30.869414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:29.522148Z","time spent":"1.347264244s","remote":"127.0.0.1:56024","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-12T02:09:30.86964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654721735s","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-12T02:09:30.869663Z","caller":"traceutil/trace.go:171","msg":"trace[1851438465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"1.654755436s","start":"2026-02-12T02:09:29.214901Z","end":"2026-02-12T02:09:30.869656Z","steps":["trace[1851438465] 'agreement among raft nodes before linearized reading' (duration: 1.654694535s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:30.869679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:29.214896Z","time spent":"1.654778406s","remote":"127.0.0.1:56336","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-12T02:09:30.869399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.653329475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:09:30.874918Z","caller":"traceutil/trace.go:171","msg":"trace[1154645408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"1.658857226s","start":"2026-02-12T02:09:29.216049Z","end":"2026-02-12T02:09:30.874906Z","steps":["trace[1154645408] 'agreement among raft nodes before linearized reading' (duration: 1.653252193s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:30.874975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:29.216044Z","time spent":"1.658899357s","remote":"127.0.0.1:56336","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-12T02:09:31.977855Z","caller":"traceutil/trace.go:171","msg":"trace[1716652500] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"170.66151ms","start":"2026-02-12T02:09:31.807172Z","end":"2026-02-12T02:09:31.977833Z","steps":["trace[1716652500] 'process raft request' (duration: 169.802898ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:42.141539Z","caller":"traceutil/trace.go:171","msg":"trace[1730969603] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"156.982089ms","start":"2026-02-12T02:09:41.98454Z","end":"2026-02-12T02:09:42.141523Z","steps":["trace[1730969603] 'process raft request' (duration: 154.791439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:43.263238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.244574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:09:43.263303Z","caller":"traceutil/trace.go:171","msg":"trace[2000090103] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"156.122064ms","start":"2026-02-12T02:09:43.107169Z","end":"2026-02-12T02:09:43.263291Z","steps":["trace[2000090103] 'process raft request' (duration: 13.774009ms)","trace[2000090103] 'compare' (duration: 142.173193ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:43.26485Z","caller":"traceutil/trace.go:171","msg":"trace[689282523] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"157.016836ms","start":"2026-02-12T02:09:43.107825Z","end":"2026-02-12T02:09:43.264841Z","steps":["trace[689282523] 'process raft request' (duration: 156.999316ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:43.265068Z","caller":"traceutil/trace.go:171","msg":"trace[1985796044] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"157.273499ms","start":"2026-02-12T02:09:43.107789Z","end":"2026-02-12T02:09:43.265063Z","steps":["trace[1985796044] 'process raft request' (duration: 156.996705ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:44.661961Z","caller":"traceutil/trace.go:171","msg":"trace[1878024559] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"119.417983ms","start":"2026-02-12T02:09:44.542487Z","end":"2026-02-12T02:09:44.661904Z","steps":["trace[1878024559] 'process raft request' (duration: 119.21325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.155283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.764985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:09:45.155351Z","caller":"traceutil/trace.go:171","msg":"trace[1296329157] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"192.14479ms","start":"2026-02-12T02:09:44.963195Z","end":"2026-02-12T02:09:45.15534Z","steps":["trace[1296329157] 'read index received' (duration: 33.617507ms)","trace[1296329157] 'applied index is now lower than readState.Index' (duration: 158.526683ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:45.155543Z","caller":"traceutil/trace.go:171","msg":"trace[867488433] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"192.458943ms","start":"2026-02-12T02:09:44.963077Z","end":"2026-02-12T02:09:45.155536Z","steps":["trace[867488433] 'process raft request' (duration: 90.359994ms)","trace[867488433] 'compare' (duration: 101.682864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:45.155701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.502625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-rzbc5\" ","response":"range_response_count:1 size:5022"} {"level":"info","ts":"2026-02-12T02:09:45.155716Z","caller":"traceutil/trace.go:171","msg":"trace[908868022] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-rzbc5; range_end:; response_count:1; response_revision:766; }","duration":"192.534945ms","start":"2026-02-12T02:09:44.963175Z","end":"2026-02-12T02:09:45.15571Z","steps":["trace[908868022] 'agreement among raft nodes before linearized reading' (duration: 192.460584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.155814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.829184ms","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-12T02:09:45.155844Z","caller":"traceutil/trace.go:171","msg":"trace[1785064228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"163.882874ms","start":"2026-02-12T02:09:44.991955Z","end":"2026-02-12T02:09:45.155838Z","steps":["trace[1785064228] 'agreement among raft nodes before linearized reading' (duration: 163.829274ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:45.457253Z","caller":"traceutil/trace.go:171","msg":"trace[1910496918] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"170.513045ms","start":"2026-02-12T02:09:45.286717Z","end":"2026-02-12T02:09:45.45723Z","steps":["trace[1910496918] 'read index received' (duration: 56.22874ms)","trace[1910496918] 'applied index is now lower than readState.Index' (duration: 114.283525ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:45.457332Z","caller":"traceutil/trace.go:171","msg":"trace[1878202435] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"170.849529ms","start":"2026-02-12T02:09:45.286476Z","end":"2026-02-12T02:09:45.457325Z","steps":["trace[1878202435] 'process raft request' (duration: 116.345762ms)","trace[1878202435] 'compare' (duration: 54.320044ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:45.457521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.789578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T02:09:45.457566Z","caller":"traceutil/trace.go:171","msg":"trace[755159336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"170.855579ms","start":"2026-02-12T02:09:45.286699Z","end":"2026-02-12T02:09:45.457554Z","steps":["trace[755159336] 'agreement among raft nodes before linearized reading' (duration: 170.740137ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:45.649846Z","caller":"traceutil/trace.go:171","msg":"trace[1617257317] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"302.948344ms","start":"2026-02-12T02:09:45.346874Z","end":"2026-02-12T02:09:45.649822Z","steps":["trace[1617257317] 'process raft request' (duration: 273.205612ms)","trace[1617257317] 'compare' (duration: 29.434187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:45.650182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.346858Z","time spent":"303.102036ms","remote":"127.0.0.1:56124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T02:09:45.650889Z","caller":"traceutil/trace.go:171","msg":"trace[339892872] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:803; }","duration":"193.558206ms","start":"2026-02-12T02:09:45.457299Z","end":"2026-02-12T02:09:45.650857Z","steps":["trace[339892872] 'read index received' (duration: 162.79015ms)","trace[339892872] 'applied index is now lower than readState.Index' (duration: 30.767266ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:45.652292Z","caller":"traceutil/trace.go:171","msg":"trace[1505776478] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"304.272661ms","start":"2026-02-12T02:09:45.348005Z","end":"2026-02-12T02:09:45.652278Z","steps":["trace[1505776478] 'process raft request' (duration: 301.750807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.652623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.347997Z","time spent":"304.332711ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:09:45.654438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.297002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-lgjr2\" ","response":"range_response_count:1 size:4811"} {"level":"info","ts":"2026-02-12T02:09:45.658161Z","caller":"traceutil/trace.go:171","msg":"trace[1171586015] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-lgjr2; range_end:; response_count:1; response_revision:777; }","duration":"307.496345ms","start":"2026-02-12T02:09:45.346976Z","end":"2026-02-12T02:09:45.654472Z","steps":["trace[1171586015] 'agreement among raft nodes before linearized reading' (duration: 307.369114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.658366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.346969Z","time spent":"311.236127ms","remote":"127.0.0.1:56244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4834,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-lgjr2\" "} {"level":"warn","ts":"2026-02-12T02:09:45.660303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.536417ms","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-12T02:09:45.660394Z","caller":"traceutil/trace.go:171","msg":"trace[255081076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"373.567457ms","start":"2026-02-12T02:09:45.286755Z","end":"2026-02-12T02:09:45.660322Z","steps":["trace[255081076] 'agreement among raft nodes before linearized reading' (duration: 373.123102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.660416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.286753Z","time spent":"373.657849ms","remote":"127.0.0.1:56336","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-12T02:09:45.662896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.140382ms","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-12T02:09:45.662925Z","caller":"traceutil/trace.go:171","msg":"trace[1333702754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"376.177553ms","start":"2026-02-12T02:09:45.286743Z","end":"2026-02-12T02:09:45.66292Z","steps":["trace[1333702754] 'agreement among raft nodes before linearized reading' (duration: 376.110242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.663404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.286739Z","time spent":"376.660039ms","remote":"127.0.0.1:56336","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-12T02:09:45.882348Z","caller":"traceutil/trace.go:171","msg":"trace[1782174578] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:805; }","duration":"231.404177ms","start":"2026-02-12T02:09:45.650924Z","end":"2026-02-12T02:09:45.882328Z","steps":["trace[1782174578] 'read index received' (duration: 140.811592ms)","trace[1782174578] 'applied index is now lower than readState.Index' (duration: 90.591935ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:45.882459Z","caller":"traceutil/trace.go:171","msg":"trace[939780290] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"420.112837ms","start":"2026-02-12T02:09:45.462338Z","end":"2026-02-12T02:09:45.882451Z","steps":["trace[939780290] 'process raft request' (duration: 329.445771ms)","trace[939780290] 'compare' (duration: 90.393772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:45.882532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.46232Z","time spent":"420.156677ms","remote":"127.0.0.1:56342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:09:45.88267Z","caller":"traceutil/trace.go:171","msg":"trace[1742663280] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"417.752585ms","start":"2026-02-12T02:09:45.464911Z","end":"2026-02-12T02:09:45.882664Z","steps":["trace[1742663280] 'process raft request' (duration: 417.38743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.882705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.464902Z","time spent":"417.782516ms","remote":"127.0.0.1:56336","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-12T02:09:45.882753Z","caller":"traceutil/trace.go:171","msg":"trace[1591734691] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"420.33609ms","start":"2026-02-12T02:09:45.462405Z","end":"2026-02-12T02:09:45.882741Z","steps":["trace[1591734691] 'process raft request' (duration: 419.857253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.88279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.756825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:45.88281Z","caller":"traceutil/trace.go:171","msg":"trace[167382423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"360.797565ms","start":"2026-02-12T02:09:45.522007Z","end":"2026-02-12T02:09:45.882804Z","steps":["trace[167382423] 'agreement among raft nodes before linearized reading' (duration: 360.765725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:45.882808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.46239Z","time spent":"420.387371ms","remote":"127.0.0.1:56226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:09:45.882827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.521997Z","time spent":"360.827017ms","remote":"127.0.0.1:56016","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-12T02:09:46.055741Z","caller":"traceutil/trace.go:171","msg":"trace[930011412] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:808; }","duration":"173.298537ms","start":"2026-02-12T02:09:45.882429Z","end":"2026-02-12T02:09:46.055728Z","steps":["trace[930011412] 'read index received' (duration: 105.745219ms)","trace[930011412] 'applied index is now lower than readState.Index' (duration: 67.552748ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:46.05581Z","caller":"traceutil/trace.go:171","msg":"trace[2140560362] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"390.093387ms","start":"2026-02-12T02:09:45.665712Z","end":"2026-02-12T02:09:46.055806Z","steps":["trace[2140560362] 'process raft request' (duration: 389.969616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:46.055865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.665704Z","time spent":"390.118668ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:09:46.05597Z","caller":"traceutil/trace.go:171","msg":"trace[1614286572] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"389.428408ms","start":"2026-02-12T02:09:45.666537Z","end":"2026-02-12T02:09:46.055966Z","steps":["trace[1614286572] 'process raft request' (duration: 389.168084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:46.055994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.666532Z","time spent":"389.448259ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T02:09:46.056016Z","caller":"traceutil/trace.go:171","msg":"trace[760941477] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"392.640902ms","start":"2026-02-12T02:09:45.663333Z","end":"2026-02-12T02:09:46.055974Z","steps":["trace[760941477] 'process raft request' (duration: 324.919911ms)","trace[760941477] 'compare' (duration: 67.362166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:46.056083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.663326Z","time spent":"392.723783ms","remote":"127.0.0.1:56244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4808,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:09:46.056095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.957894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-xkczg\" ","response":"range_response_count:1 size:4732"} {"level":"info","ts":"2026-02-12T02:09:46.056111Z","caller":"traceutil/trace.go:171","msg":"trace[780788826] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-xkczg; range_end:; response_count:1; response_revision:783; }","duration":"198.999565ms","start":"2026-02-12T02:09:45.857106Z","end":"2026-02-12T02:09:46.056106Z","steps":["trace[780788826] 'agreement among raft nodes before linearized reading' (duration: 198.938584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:46.056195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.957666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-02-12T02:09:46.056207Z","caller":"traceutil/trace.go:171","msg":"trace[368565649] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:783; }","duration":"392.978636ms","start":"2026-02-12T02:09:45.663225Z","end":"2026-02-12T02:09:46.056204Z","steps":["trace[368565649] 'agreement among raft nodes before linearized reading' (duration: 392.943606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:46.056218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:09:45.663215Z","time spent":"393.000308ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4229,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-12T02:09:46.056337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.446759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:09:46.056367Z","caller":"traceutil/trace.go:171","msg":"trace[864023908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"170.505311ms","start":"2026-02-12T02:09:45.885852Z","end":"2026-02-12T02:09:46.056358Z","steps":["trace[864023908] 'agreement among raft nodes before linearized reading' (duration: 170.45761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:46.273061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.292642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:09:46.273391Z","caller":"traceutil/trace.go:171","msg":"trace[463827866] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"163.660359ms","start":"2026-02-12T02:09:46.109692Z","end":"2026-02-12T02:09:46.273352Z","steps":["trace[463827866] 'process raft request' (duration: 53.025862ms)","trace[463827866] 'compare' (duration: 110.227501ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:09:46.282617Z","caller":"traceutil/trace.go:171","msg":"trace[972585410] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"134.200173ms","start":"2026-02-12T02:09:46.148398Z","end":"2026-02-12T02:09:46.282598Z","steps":["trace[972585410] 'read index received' (duration: 14.265222ms)","trace[972585410] 'applied index is now lower than readState.Index' (duration: 119.933991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:09:46.282862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.439471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4206"} {"level":"info","ts":"2026-02-12T02:09:46.282882Z","caller":"traceutil/trace.go:171","msg":"trace[1994651746] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:792; }","duration":"173.502251ms","start":"2026-02-12T02:09:46.109374Z","end":"2026-02-12T02:09:46.282876Z","steps":["trace[1994651746] 'agreement among raft nodes before linearized reading' (duration: 173.339208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:09:46.282934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.312817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-02-12T02:09:46.282978Z","caller":"traceutil/trace.go:171","msg":"trace[1156754031] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:792; }","duration":"120.386197ms","start":"2026-02-12T02:09:46.162579Z","end":"2026-02-12T02:09:46.282965Z","steps":["trace[1156754031] 'agreement among raft nodes before linearized reading' (duration: 120.252176ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:46.400759Z","caller":"traceutil/trace.go:171","msg":"trace[1592847894] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"103.940517ms","start":"2026-02-12T02:09:46.296803Z","end":"2026-02-12T02:09:46.400743Z","steps":["trace[1592847894] 'process raft request' (duration: 103.917207ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:46.400844Z","caller":"traceutil/trace.go:171","msg":"trace[154079067] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"109.41033ms","start":"2026-02-12T02:09:46.291356Z","end":"2026-02-12T02:09:46.400767Z","steps":["trace[154079067] 'process raft request' (duration: 109.251038ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:46.401018Z","caller":"traceutil/trace.go:171","msg":"trace[1763151568] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"108.093912ms","start":"2026-02-12T02:09:46.292918Z","end":"2026-02-12T02:09:46.401012Z","steps":["trace[1763151568] 'process raft request' (duration: 107.775958ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:09:48.102868Z","caller":"traceutil/trace.go:171","msg":"trace[1412654148] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"157.01372ms","start":"2026-02-12T02:09:47.94584Z","end":"2026-02-12T02:09:48.102854Z","steps":["trace[1412654148] 'process raft request' (duration: 64.941626ms)","trace[1412654148] 'compare' (duration: 91.605318ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:10:24.483563Z","caller":"traceutil/trace.go:171","msg":"trace[1584733517] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"153.696155ms","start":"2026-02-12T02:10:24.329838Z","end":"2026-02-12T02:10:24.483534Z","steps":["trace[1584733517] 'process raft request' (duration: 128.585422ms)","trace[1584733517] 'compare' (duration: 24.711649ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:10:31.117272Z","caller":"traceutil/trace.go:171","msg":"trace[840311452] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"108.251711ms","start":"2026-02-12T02:10:31.008998Z","end":"2026-02-12T02:10:31.11725Z","steps":["trace[840311452] 'process raft request' (duration: 107.740136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:47.024262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420485898469863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T02:10:47.123935Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419095011s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T02:10:47.327513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.169542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T02:10:47.329237Z","caller":"traceutil/trace.go:171","msg":"trace[1127379500] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"805.606793ms","start":"2026-02-12T02:10:46.523615Z","end":"2026-02-12T02:10:47.329222Z","steps":["trace[1127379500] 'read index received' (duration: 600.465762ms)","trace[1127379500] 'applied index is now lower than readState.Index' (duration: 205.139581ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:10:47.329403Z","caller":"traceutil/trace.go:171","msg":"trace[1827861175] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.624638268s","start":"2026-02-12T02:10:45.704754Z","end":"2026-02-12T02:10:47.329392Z","steps":["trace[1827861175] 'process raft request' (duration: 1.419533627s)","trace[1827861175] 'compare' (duration: 203.070791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:10:47.329489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:10:45.704734Z","time spent":"1.624728649s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T02:10:47.329668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.067329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:10:47.329763Z","caller":"traceutil/trace.go:171","msg":"trace[1056799729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"806.19585ms","start":"2026-02-12T02:10:46.523559Z","end":"2026-02-12T02:10:47.329755Z","steps":["trace[1056799729] 'agreement among raft nodes before linearized reading' (duration: 806.083029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:47.329818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:10:46.523539Z","time spent":"806.27263ms","remote":"127.0.0.1:56016","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-12T02:10:47.329895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.686813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:10:47.329944Z","caller":"traceutil/trace.go:171","msg":"trace[25235182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"782.772075ms","start":"2026-02-12T02:10:46.547162Z","end":"2026-02-12T02:10:47.329934Z","steps":["trace[25235182] 'agreement among raft nodes before linearized reading' (duration: 782.639653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:47.329968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:10:46.547146Z","time spent":"782.818385ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T02:10:47.330119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.789405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T02:10:47.330142Z","caller":"traceutil/trace.go:171","msg":"trace[826140583] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1014; }","duration":"196.843046ms","start":"2026-02-12T02:10:47.13329Z","end":"2026-02-12T02:10:47.330133Z","steps":["trace[826140583] 'agreement among raft nodes before linearized reading' (duration: 196.794245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:47.330354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.217022ms","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-12T02:10:47.330374Z","caller":"traceutil/trace.go:171","msg":"trace[1308077159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"596.237512ms","start":"2026-02-12T02:10:46.734132Z","end":"2026-02-12T02:10:47.33037Z","steps":["trace[1308077159] 'agreement among raft nodes before linearized reading' (duration: 596.192302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:47.330388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:10:46.734041Z","time spent":"596.342563ms","remote":"127.0.0.1:56336","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-12T02:10:47.330915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.966029ms","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-12T02:10:47.33097Z","caller":"traceutil/trace.go:171","msg":"trace[1035877735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"751.466904ms","start":"2026-02-12T02:10:46.579494Z","end":"2026-02-12T02:10:47.330961Z","steps":["trace[1035877735] 'agreement among raft nodes before linearized reading' (duration: 750.635866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:47.33109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T02:10:46.579472Z","time spent":"751.608455ms","remote":"127.0.0.1:56336","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":"info","ts":"2026-02-12T02:10:51.563254Z","caller":"traceutil/trace.go:171","msg":"trace[111778650] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"124.986528ms","start":"2026-02-12T02:10:51.438247Z","end":"2026-02-12T02:10:51.563233Z","steps":["trace[111778650] 'read index received' (duration: 44.150498ms)","trace[111778650] 'applied index is now lower than readState.Index' (duration: 80.83513ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:10:51.56336Z","caller":"traceutil/trace.go:171","msg":"trace[1743047379] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"127.642695ms","start":"2026-02-12T02:10:51.435705Z","end":"2026-02-12T02:10:51.563348Z","steps":["trace[1743047379] 'process raft request' (duration: 46.736105ms)","trace[1743047379] 'compare' (duration: 80.679598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T02:10:51.563654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.389432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-12T02:10:51.563711Z","caller":"traceutil/trace.go:171","msg":"trace[727322047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1031; }","duration":"125.465302ms","start":"2026-02-12T02:10:51.438231Z","end":"2026-02-12T02:10:51.563696Z","steps":["trace[727322047] 'agreement among raft nodes before linearized reading' (duration: 125.330741ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:10:52.001025Z","caller":"traceutil/trace.go:171","msg":"trace[1798157044] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"216.87937ms","start":"2026-02-12T02:10:51.784122Z","end":"2026-02-12T02:10:52.001002Z","steps":["trace[1798157044] 'read index received' (duration: 216.700398ms)","trace[1798157044] 'applied index is now lower than readState.Index' (duration: 177.952µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:10:52.001301Z","caller":"traceutil/trace.go:171","msg":"trace[918198142] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"220.846692ms","start":"2026-02-12T02:10:51.780442Z","end":"2026-02-12T02:10:52.001289Z","steps":["trace[918198142] 'process raft request' (duration: 220.430348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:52.002024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.470575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T02:10:52.002055Z","caller":"traceutil/trace.go:171","msg":"trace[740377602] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1033; }","duration":"215.522196ms","start":"2026-02-12T02:10:51.786525Z","end":"2026-02-12T02:10:52.002047Z","steps":["trace[740377602] 'agreement among raft nodes before linearized reading' (duration: 215.342804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:52.001518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.382955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-02-12T02:10:52.002394Z","caller":"traceutil/trace.go:171","msg":"trace[2089379673] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1033; }","duration":"218.225165ms","start":"2026-02-12T02:10:51.784116Z","end":"2026-02-12T02:10:52.002341Z","steps":["trace[2089379673] 'agreement among raft nodes before linearized reading' (duration: 217.239144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:10:52.002643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.383619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-02-12T02:10:52.002672Z","caller":"traceutil/trace.go:171","msg":"trace[1512155920] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1033; }","duration":"143.440649ms","start":"2026-02-12T02:10:51.859225Z","end":"2026-02-12T02:10:52.002666Z","steps":["trace[1512155920] 'agreement among raft nodes before linearized reading' (duration: 143.388028ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T02:11:13.395325Z","caller":"traceutil/trace.go:171","msg":"trace[1409486118] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"123.948854ms","start":"2026-02-12T02:11:13.271354Z","end":"2026-02-12T02:11:13.395303Z","steps":["trace[1409486118] 'read index received' (duration: 123.724212ms)","trace[1409486118] 'applied index is now lower than readState.Index' (duration: 223.762µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T02:11:13.395438Z","caller":"traceutil/trace.go:171","msg":"trace[792468784] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"138.694819ms","start":"2026-02-12T02:11:13.256733Z","end":"2026-02-12T02:11:13.395428Z","steps":["trace[792468784] 'process raft request' (duration: 138.392116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:11:13.395764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.392038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-12T02:11:13.395804Z","caller":"traceutil/trace.go:171","msg":"trace[453296544] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:1091; }","duration":"124.444299ms","start":"2026-02-12T02:11:13.271351Z","end":"2026-02-12T02:11:13.395795Z","steps":["trace[453296544] 'agreement among raft nodes before linearized reading' (duration: 124.357488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T02:11:13.396095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.249339ms","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-12T02:11:13.396137Z","caller":"traceutil/trace.go:171","msg":"trace[1581989470] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1091; }","duration":"110.3064ms","start":"2026-02-12T02:11:13.285821Z","end":"2026-02-12T02:11:13.396127Z","steps":["trace[1581989470] 'agreement among raft nodes before linearized reading' (duration: 110.234899ms)"],"step_count":1}