{"level":"warn","ts":"2026-02-18T14:24:36.970056Z","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-18T14:24:36.970141Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-18T14:24:36.970342Z","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-18T14:24:36.970358Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-18T14:24:36.970417Z","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-18T14:24:36.971076Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-18T14:24:36.971214Z","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://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-18T14:24:36.977493Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.010573ms"} {"level":"info","ts":"2026-02-18T14:24:36.994099Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-18T14:24:36.99417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:24:36.994192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-18T14:24:36.994205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:24:36.994217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-18T14:24:36.994253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-18T14:24:37.007492Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:24:37.009672Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:24:37.014408Z","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-18T14:24:37.021491Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:24:37.02324Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:24:37.023413Z","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-18T14:24:37.02345Z","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-18T14:24:37.023458Z","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-18T14:24:37.027301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-18T14:24:37.027499Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-18T14:24:37.052551Z","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-18T14:24:37.052677Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-18T14:24:37.052767Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-18T14:24:37.052969Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:24:37.053008Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:24:37.794724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:24:37.794815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:24:37.79485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-18T14:24:37.794886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:24:37.794909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-18T14:24:37.794928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-18T14:24:37.794944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-18T14:24:37.826858Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:24:37.827029Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:24:37.827564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:24:37.829639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:24:37.830114Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:24:37.830129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T14:24:37.831286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-18T14:24:37.837349Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T14:24:37.844009Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:24:37.84409Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:24:37.844121Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-18T14:24:38.054345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.026057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:24:38.054389Z","caller":"traceutil/trace.go:171","msg":"trace[526965282] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"109.09847ms","start":"2026-02-18T14:24:37.945281Z","end":"2026-02-18T14:24:38.054379Z","steps":["trace[526965282] 'count revisions from in-memory index tree' (duration: 109.005556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:24:38.054593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.338387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:24:38.054692Z","caller":"traceutil/trace.go:171","msg":"trace[299631558] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"109.4397ms","start":"2026-02-18T14:24:37.945246Z","end":"2026-02-18T14:24:38.054685Z","steps":["trace[299631558] 'range keys from in-memory index tree' (duration: 109.252984ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.476282Z","caller":"traceutil/trace.go:171","msg":"trace[1627326641] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"100.521148ms","start":"2026-02-18T14:24:39.375741Z","end":"2026-02-18T14:24:39.476262Z","steps":["trace[1627326641] 'process raft request' (duration: 100.26391ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.476881Z","caller":"traceutil/trace.go:171","msg":"trace[40232076] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"101.045114ms","start":"2026-02-18T14:24:39.375822Z","end":"2026-02-18T14:24:39.476868Z","steps":["trace[40232076] 'process raft request' (duration: 100.2373ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.477621Z","caller":"traceutil/trace.go:171","msg":"trace[115900540] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"101.576081ms","start":"2026-02-18T14:24:39.376033Z","end":"2026-02-18T14:24:39.477609Z","steps":["trace[115900540] 'process raft request' (duration: 101.375535ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.477992Z","caller":"traceutil/trace.go:171","msg":"trace[1851571463] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"102.081766ms","start":"2026-02-18T14:24:39.375898Z","end":"2026-02-18T14:24:39.47798Z","steps":["trace[1851571463] 'process raft request' (duration: 100.326642ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.478545Z","caller":"traceutil/trace.go:171","msg":"trace[1899829866] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"102.485229ms","start":"2026-02-18T14:24:39.376042Z","end":"2026-02-18T14:24:39.478528Z","steps":["trace[1899829866] 'process raft request' (duration: 101.519079ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.479194Z","caller":"traceutil/trace.go:171","msg":"trace[561172117] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"100.077244ms","start":"2026-02-18T14:24:39.379104Z","end":"2026-02-18T14:24:39.479181Z","steps":["trace[561172117] 'process raft request' (duration: 99.374793ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.479548Z","caller":"traceutil/trace.go:171","msg":"trace[88778019] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"100.310941ms","start":"2026-02-18T14:24:39.379225Z","end":"2026-02-18T14:24:39.479536Z","steps":["trace[88778019] 'process raft request' (duration: 99.744014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:24:39.480287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.649729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:24:39.480335Z","caller":"traceutil/trace.go:171","msg":"trace[1294091557] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"109.726611ms","start":"2026-02-18T14:24:39.370596Z","end":"2026-02-18T14:24:39.480322Z","steps":["trace[1294091557] 'agreement among raft nodes before linearized reading' (duration: 109.660419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:24:39.480662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.367381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:24:39.480709Z","caller":"traceutil/trace.go:171","msg":"trace[240984682] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:12; }","duration":"138.422832ms","start":"2026-02-18T14:24:39.342275Z","end":"2026-02-18T14:24:39.480698Z","steps":["trace[240984682] 'agreement among raft nodes before linearized reading' (duration: 138.35691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:24:39.48117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.840215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:24:39.481213Z","caller":"traceutil/trace.go:171","msg":"trace[420945014] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"140.891668ms","start":"2026-02-18T14:24:39.340311Z","end":"2026-02-18T14:24:39.481202Z","steps":["trace[420945014] 'agreement among raft nodes before linearized reading' (duration: 140.833986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:24:39.482009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.503827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:24:39.482051Z","caller":"traceutil/trace.go:171","msg":"trace[2043167352] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:0; response_revision:12; }","duration":"142.567139ms","start":"2026-02-18T14:24:39.339474Z","end":"2026-02-18T14:24:39.482041Z","steps":["trace[2043167352] 'agreement among raft nodes before linearized reading' (duration: 142.508997ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.935345Z","caller":"traceutil/trace.go:171","msg":"trace[322252315] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"117.192929ms","start":"2026-02-18T14:24:39.81813Z","end":"2026-02-18T14:24:39.935323Z","steps":["trace[322252315] 'process raft request' (duration: 117.099486ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:39.935759Z","caller":"traceutil/trace.go:171","msg":"trace[1856540055] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"117.925321ms","start":"2026-02-18T14:24:39.8178Z","end":"2026-02-18T14:24:39.935725Z","steps":["trace[1856540055] 'process raft request' (duration: 95.381128ms)","trace[1856540055] 'compare' (duration: 21.916284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:24:46.426585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.749942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:24:46.42675Z","caller":"traceutil/trace.go:171","msg":"trace[168353971] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:290; }","duration":"133.945948ms","start":"2026-02-18T14:24:46.292777Z","end":"2026-02-18T14:24:46.426723Z","steps":["trace[168353971] 'range keys from in-memory index tree' (duration: 133.69943ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:24:51.912726Z","caller":"traceutil/trace.go:171","msg":"trace[1730116564] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"432.964675ms","start":"2026-02-18T14:24:51.479743Z","end":"2026-02-18T14:24:51.912707Z","steps":["trace[1730116564] 'process raft request' (duration: 432.850162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:24:51.913201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:24:51.479724Z","time spent":"433.128301ms","remote":"127.0.0.1:40542","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-18T14:24:52.625175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.822438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-18T14:24:52.625178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.503976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:24:52.62523Z","caller":"traceutil/trace.go:171","msg":"trace[1756127442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:362; }","duration":"517.935301ms","start":"2026-02-18T14:24:52.107282Z","end":"2026-02-18T14:24:52.625218Z","steps":["trace[1756127442] 'range keys from in-memory index tree' (duration: 517.727765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:24:52.625259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:24:52.107258Z","time spent":"517.995303ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T14:24:52.625255Z","caller":"traceutil/trace.go:171","msg":"trace[748885535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"165.654061ms","start":"2026-02-18T14:24:52.459583Z","end":"2026-02-18T14:24:52.625237Z","steps":["trace[748885535] 'range keys from in-memory index tree' (duration: 165.378603ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:25:11.166334Z","caller":"traceutil/trace.go:171","msg":"trace[903916212] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"265.700802ms","start":"2026-02-18T14:25:10.900618Z","end":"2026-02-18T14:25:11.166319Z","steps":["trace[903916212] 'process raft request' (duration: 265.61349ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:25:11.69786Z","caller":"traceutil/trace.go:171","msg":"trace[1675889833] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"125.122028ms","start":"2026-02-18T14:25:11.572726Z","end":"2026-02-18T14:25:11.697848Z","steps":["trace[1675889833] 'read index received' (duration: 125.025485ms)","trace[1675889833] 'applied index is now lower than readState.Index' (duration: 95.683µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:25:11.697988Z","caller":"traceutil/trace.go:171","msg":"trace[1495822712] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"200.013654ms","start":"2026-02-18T14:25:11.49797Z","end":"2026-02-18T14:25:11.697983Z","steps":["trace[1495822712] 'process raft request' (duration: 199.817088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:11.698097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.359405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:11.698115Z","caller":"traceutil/trace.go:171","msg":"trace[1776240135] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:514; }","duration":"125.386396ms","start":"2026-02-18T14:25:11.572722Z","end":"2026-02-18T14:25:11.698109Z","steps":["trace[1776240135] 'agreement among raft nodes before linearized reading' (duration: 125.337225ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:25:14.54096Z","caller":"traceutil/trace.go:171","msg":"trace[1936826399] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"384.016784ms","start":"2026-02-18T14:25:14.156936Z","end":"2026-02-18T14:25:14.540953Z","steps":["trace[1936826399] 'process raft request' (duration: 369.524939ms)","trace[1936826399] 'compare' (duration: 13.988535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:25:14.541062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:14.156918Z","time spent":"384.078576ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:25:14.540857Z","caller":"traceutil/trace.go:171","msg":"trace[2067404668] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"325.623924ms","start":"2026-02-18T14:25:14.215218Z","end":"2026-02-18T14:25:14.540842Z","steps":["trace[2067404668] 'read index received' (duration: 311.19501ms)","trace[2067404668] 'applied index is now lower than readState.Index' (duration: 14.428364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:25:14.541507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.264038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-18T14:25:14.541552Z","caller":"traceutil/trace.go:171","msg":"trace[188874877] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:553; }","duration":"244.33282ms","start":"2026-02-18T14:25:14.297206Z","end":"2026-02-18T14:25:14.541539Z","steps":["trace[188874877] 'agreement among raft nodes before linearized reading' (duration: 244.080205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:14.543435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.227748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:14.543464Z","caller":"traceutil/trace.go:171","msg":"trace[2094484045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"328.29256ms","start":"2026-02-18T14:25:14.215166Z","end":"2026-02-18T14:25:14.543459Z","steps":["trace[2094484045] 'agreement among raft nodes before linearized reading' (duration: 326.087793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:14.543529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:14.215128Z","time spent":"328.348041ms","remote":"127.0.0.1:40302","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-18T14:25:18.229333Z","caller":"traceutil/trace.go:171","msg":"trace[1511514875] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"204.341146ms","start":"2026-02-18T14:25:18.024978Z","end":"2026-02-18T14:25:18.229319Z","steps":["trace[1511514875] 'read index received' (duration: 204.205363ms)","trace[1511514875] 'applied index is now lower than readState.Index' (duration: 135.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:25:18.229486Z","caller":"traceutil/trace.go:171","msg":"trace[866967938] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"242.441251ms","start":"2026-02-18T14:25:17.987038Z","end":"2026-02-18T14:25:18.229479Z","steps":["trace[866967938] 'process raft request' (duration: 242.189636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.229604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.614782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T14:25:18.229622Z","caller":"traceutil/trace.go:171","msg":"trace[327091243] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:565; }","duration":"204.661943ms","start":"2026-02-18T14:25:18.024953Z","end":"2026-02-18T14:25:18.229615Z","steps":["trace[327091243] 'agreement among raft nodes before linearized reading' (duration: 204.621223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.668945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.588335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:18.668989Z","caller":"traceutil/trace.go:171","msg":"trace[1577594483] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:565; }","duration":"375.650596ms","start":"2026-02-18T14:25:18.293328Z","end":"2026-02-18T14:25:18.668979Z","steps":["trace[1577594483] 'count revisions from in-memory index tree' (duration: 375.565614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.669012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.293325Z","time spent":"375.680906ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:25:18.669127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.84433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:18.66914Z","caller":"traceutil/trace.go:171","msg":"trace[647490246] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:565; }","duration":"375.87074ms","start":"2026-02-18T14:25:18.293265Z","end":"2026-02-18T14:25:18.669136Z","steps":["trace[647490246] 'range keys from in-memory index tree' (duration: 375.740158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.669153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.29326Z","time spent":"375.889171ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-18T14:25:18.669204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.834239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:18.669215Z","caller":"traceutil/trace.go:171","msg":"trace[1513472606] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:565; }","duration":"375.85176ms","start":"2026-02-18T14:25:18.29336Z","end":"2026-02-18T14:25:18.669211Z","steps":["trace[1513472606] 'range keys from in-memory index tree' (duration: 375.816259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.669229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.293357Z","time spent":"375.866891ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T14:25:18.669608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.481072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:25:18.669635Z","caller":"traceutil/trace.go:171","msg":"trace[1190351386] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"357.587984ms","start":"2026-02-18T14:25:18.312042Z","end":"2026-02-18T14:25:18.66963Z","steps":["trace[1190351386] 'read index received' (duration: 22.939128ms)","trace[1190351386] 'applied index is now lower than readState.Index' (duration: 334.648326ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:25:18.669694Z","caller":"traceutil/trace.go:171","msg":"trace[1745127244] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"366.354154ms","start":"2026-02-18T14:25:18.303334Z","end":"2026-02-18T14:25:18.669688Z","steps":["trace[1745127244] 'process raft request' (duration: 31.690478ms)","trace[1745127244] 'compare' (duration: 334.267047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:25:18.669713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.303319Z","time spent":"366.385834ms","remote":"127.0.0.1:40364","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-18T14:25:18.669797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.752217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:18.669811Z","caller":"traceutil/trace.go:171","msg":"trace[680204119] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:566; }","duration":"357.782007ms","start":"2026-02-18T14:25:18.312024Z","end":"2026-02-18T14:25:18.669806Z","steps":["trace[680204119] 'agreement among raft nodes before linearized reading' (duration: 357.747087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.669823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.312012Z","time spent":"357.807658ms","remote":"127.0.0.1:43252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-18T14:25:18.669891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.807198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:18.669904Z","caller":"traceutil/trace.go:171","msg":"trace[1963188623] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:566; }","duration":"357.827128ms","start":"2026-02-18T14:25:18.312073Z","end":"2026-02-18T14:25:18.6699Z","steps":["trace[1963188623] 'agreement among raft nodes before linearized reading' (duration: 357.808278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.669918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.312061Z","time spent":"357.853819ms","remote":"127.0.0.1:43252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-18T14:25:18.670138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.041513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:18.670156Z","caller":"traceutil/trace.go:171","msg":"trace[23978995] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:566; }","duration":"358.063694ms","start":"2026-02-18T14:25:18.312085Z","end":"2026-02-18T14:25:18.670149Z","steps":["trace[23978995] 'agreement among raft nodes before linearized reading' (duration: 358.040913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:18.670169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.312083Z","time spent":"358.082284ms","remote":"127.0.0.1:43252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:25:19.322681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021453104711391,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:25:19.419196Z","caller":"traceutil/trace.go:171","msg":"trace[1507950868] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"597.525765ms","start":"2026-02-18T14:25:18.821657Z","end":"2026-02-18T14:25:19.419183Z","steps":["trace[1507950868] 'read index received' (duration: 597.371292ms)","trace[1507950868] 'applied index is now lower than readState.Index' (duration: 153.833µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:25:19.419366Z","caller":"traceutil/trace.go:171","msg":"trace[731290504] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"743.627646ms","start":"2026-02-18T14:25:18.675731Z","end":"2026-02-18T14:25:19.419359Z","steps":["trace[731290504] 'process raft request' (duration: 743.338059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:19.419435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.675712Z","time spent":"743.672267ms","remote":"127.0.0.1:40542","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-18T14:25:19.419594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.921693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T14:25:19.419619Z","caller":"traceutil/trace.go:171","msg":"trace[1221275161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"597.988865ms","start":"2026-02-18T14:25:18.821619Z","end":"2026-02-18T14:25:19.419608Z","steps":["trace[1221275161] 'agreement among raft nodes before linearized reading' (duration: 597.904732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:19.419636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.821592Z","time spent":"598.040917ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:25:19.419752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.874874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:25:19.419768Z","caller":"traceutil/trace.go:171","msg":"trace[1580699950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"580.912094ms","start":"2026-02-18T14:25:18.838851Z","end":"2026-02-18T14:25:19.419763Z","steps":["trace[1580699950] 'agreement among raft nodes before linearized reading' (duration: 580.865683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:19.419815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:18.83884Z","time spent":"580.945425ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:25:19.420028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.709766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xthsd\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T14:25:19.42006Z","caller":"traceutil/trace.go:171","msg":"trace[1431103169] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xthsd; range_end:; response_count:1; response_revision:567; }","duration":"118.763207ms","start":"2026-02-18T14:25:19.301291Z","end":"2026-02-18T14:25:19.420054Z","steps":["trace[1431103169] 'agreement among raft nodes before linearized reading' (duration: 118.690425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.306013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0248337s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:25:21.30608Z","caller":"traceutil/trace.go:171","msg":"trace[1101177059] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.860468359s","start":"2026-02-18T14:25:19.445596Z","end":"2026-02-18T14:25:21.306065Z","steps":["trace[1101177059] 'process raft request' (duration: 835.530476ms)","trace[1101177059] 'compare' (duration: 1.024517094s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:25:21.306111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:19.445579Z","time spent":"1.860517821s","remote":"127.0.0.1:40542","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-18T14:25:21.361585Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080505874s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:25:21.362002Z","caller":"traceutil/trace.go:171","msg":"trace[1883972758] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:584; }","duration":"1.148355518s","start":"2026-02-18T14:25:20.213626Z","end":"2026-02-18T14:25:21.361981Z","steps":["trace[1883972758] 'read index received' (duration: 67.507466ms)","trace[1883972758] 'applied index is now lower than readState.Index' (duration: 1.080846902s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:25:21.362291Z","caller":"traceutil/trace.go:171","msg":"trace[2090322415] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.916154034s","start":"2026-02-18T14:25:19.446128Z","end":"2026-02-18T14:25:21.362282Z","steps":["trace[2090322415] 'process raft request' (duration: 1.915660043s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.362364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:19.44612Z","time spent":"1.916191934s","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:25:21.362485Z","caller":"traceutil/trace.go:171","msg":"trace[264602986] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.916295337s","start":"2026-02-18T14:25:19.446184Z","end":"2026-02-18T14:25:21.362479Z","steps":["trace[264602986] 'process raft request' (duration: 1.915714034s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.362512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:19.44618Z","time spent":"1.916316977s","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:25:21.362656Z","caller":"traceutil/trace.go:171","msg":"trace[277240260] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.90682684s","start":"2026-02-18T14:25:19.455824Z","end":"2026-02-18T14:25:21.36265Z","steps":["trace[277240260] 'process raft request' (duration: 1.906108854s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.36268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:19.455816Z","time spent":"1.906851141s","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:25:21.362747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149123205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:25:21.362761Z","caller":"traceutil/trace.go:171","msg":"trace[1831650421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"1.149165445s","start":"2026-02-18T14:25:20.213591Z","end":"2026-02-18T14:25:21.362757Z","steps":["trace[1831650421] 'agreement among raft nodes before linearized reading' (duration: 1.149141255s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.362775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:20.213572Z","time spent":"1.149200746s","remote":"127.0.0.1:40302","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-18T14:25:21.36516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131402625s","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-18T14:25:21.365202Z","caller":"traceutil/trace.go:171","msg":"trace[1661567725] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:572; }","duration":"1.131481437s","start":"2026-02-18T14:25:20.233709Z","end":"2026-02-18T14:25:21.36519Z","steps":["trace[1661567725] 'agreement among raft nodes before linearized reading' (duration: 1.131340284s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.365231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:20.233674Z","time spent":"1.131553179s","remote":"127.0.0.1:40542","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-18T14:25:21.365307Z","caller":"traceutil/trace.go:171","msg":"trace[1071494709] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"323.861456ms","start":"2026-02-18T14:25:21.04142Z","end":"2026-02-18T14:25:21.365281Z","steps":["trace[1071494709] 'process raft request' (duration: 323.552829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.365371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.106955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:25:21.365391Z","caller":"traceutil/trace.go:171","msg":"trace[1382776256] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:572; }","duration":"168.168526ms","start":"2026-02-18T14:25:21.197218Z","end":"2026-02-18T14:25:21.365387Z","steps":["trace[1382776256] 'agreement among raft nodes before linearized reading' (duration: 168.122565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:21.365369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:25:21.041395Z","time spent":"323.947528ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:25:41.728447Z","caller":"traceutil/trace.go:171","msg":"trace[1810889937] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"152.11236ms","start":"2026-02-18T14:25:41.576324Z","end":"2026-02-18T14:25:41.728436Z","steps":["trace[1810889937] 'process raft request' (duration: 151.876375ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:25:41.728395Z","caller":"traceutil/trace.go:171","msg":"trace[75429641] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"150.979683ms","start":"2026-02-18T14:25:41.577395Z","end":"2026-02-18T14:25:41.728375Z","steps":["trace[75429641] 'read index received' (duration: 150.87276ms)","trace[75429641] 'applied index is now lower than readState.Index' (duration: 105.923µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:25:41.72974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.347113ms","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-18T14:25:41.729815Z","caller":"traceutil/trace.go:171","msg":"trace[110643830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"115.460688ms","start":"2026-02-18T14:25:41.614341Z","end":"2026-02-18T14:25:41.729802Z","steps":["trace[110643830] 'agreement among raft nodes before linearized reading' (duration: 115.263862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:41.730139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.593323ms","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-18T14:25:41.730177Z","caller":"traceutil/trace.go:171","msg":"trace[616158547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:723; }","duration":"152.781817ms","start":"2026-02-18T14:25:41.577385Z","end":"2026-02-18T14:25:41.730167Z","steps":["trace[616158547] 'agreement among raft nodes before linearized reading' (duration: 151.856004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:25:41.731089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.182972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:25:41.731136Z","caller":"traceutil/trace.go:171","msg":"trace[1829835994] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:724; }","duration":"109.254534ms","start":"2026-02-18T14:25:41.621871Z","end":"2026-02-18T14:25:41.731125Z","steps":["trace[1829835994] 'agreement among raft nodes before linearized reading' (duration: 109.144101ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:26:48.09754Z","caller":"traceutil/trace.go:171","msg":"trace[433679248] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"239.00313ms","start":"2026-02-18T14:26:47.858517Z","end":"2026-02-18T14:26:48.09752Z","steps":["trace[433679248] 'read index received' (duration: 238.796314ms)","trace[433679248] 'applied index is now lower than readState.Index' (duration: 206.106µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:26:48.097951Z","caller":"traceutil/trace.go:171","msg":"trace[668220111] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"324.198982ms","start":"2026-02-18T14:26:47.773743Z","end":"2026-02-18T14:26:48.097942Z","steps":["trace[668220111] 'process raft request' (duration: 323.622926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:26:48.098157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:26:47.773726Z","time spent":"324.367788ms","remote":"127.0.0.1:40542","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-18T14:26:48.098597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.076021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T14:26:48.098943Z","caller":"traceutil/trace.go:171","msg":"trace[424339739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"240.447452ms","start":"2026-02-18T14:26:47.858487Z","end":"2026-02-18T14:26:48.098934Z","steps":["trace[424339739] 'agreement among raft nodes before linearized reading' (duration: 240.04291ms)"],"step_count":1}