{"level":"warn","ts":"2026-02-17T18:32:20.601004Z","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-17T18:32:20.60117Z","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-17T18:32:20.601279Z","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-17T18:32:20.601353Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-17T18:32:20.601391Z","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-17T18:32:20.602075Z","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-17T18:32:20.602196Z","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-17T18:32:20.606511Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.02542ms"} {"level":"info","ts":"2026-02-17T18:32:20.620464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-17T18:32:20.620609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T18:32:20.620644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-17T18:32:20.620686Z","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-17T18:32:20.620709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-17T18:32:20.620771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-17T18:32:20.627611Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T18:32:20.630748Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T18:32:20.636447Z","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-17T18:32:20.641607Z","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-17T18:32:20.647766Z","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-17T18:32:20.648032Z","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-17T18:32:20.651534Z","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-17T18:32:20.651564Z","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-17T18:32:20.648373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-17T18:32:20.652178Z","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-17T18:32:20.652607Z","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-17T18:32:20.652359Z","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-17T18:32:20.652437Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-17T18:32:20.653634Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-17T18:32:20.653507Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T18:32:21.322511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T18:32:21.322593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T18:32:21.322657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-17T18:32:21.32268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-17T18:32:21.32271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-17T18:32:21.322733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-17T18:32:21.32275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-17T18:32:21.359257Z","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-17T18:32:21.359474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T18:32:21.360092Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T18:32:21.361495Z","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-17T18:32:21.361762Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T18:32:21.361834Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T18:32:21.362432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T18:32:21.370804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-17T18:32:21.374932Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T18:32:21.375005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T18:32:21.378739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-17T18:32:39.205695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.106349ms","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-17T18:32:39.205765Z","caller":"traceutil/trace.go:171","msg":"trace[1970764889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:447; }","duration":"375.212719ms","start":"2026-02-17T18:32:38.830539Z","end":"2026-02-17T18:32:39.205751Z","steps":["trace[1970764889] 'range keys from in-memory index tree' (duration: 374.949087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:39.2058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:38.830526Z","time spent":"375.26848ms","remote":"127.0.0.1:57442","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-17T18:32:47.535145Z","caller":"traceutil/trace.go:171","msg":"trace[26970392] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"170.664654ms","start":"2026-02-17T18:32:47.36446Z","end":"2026-02-17T18:32:47.535125Z","steps":["trace[26970392] 'process raft request' (duration: 145.128897ms)","trace[26970392] 'compare' (duration: 25.461766ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:32:47.594523Z","caller":"traceutil/trace.go:171","msg":"trace[1449396396] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"229.740909ms","start":"2026-02-17T18:32:47.364756Z","end":"2026-02-17T18:32:47.594496Z","steps":["trace[1449396396] 'process raft request' (duration: 229.614388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:54.605355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021434791399554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:32:54.889088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013295933s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T18:32:54.890613Z","caller":"traceutil/trace.go:171","msg":"trace[1205802332] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.014889054s","start":"2026-02-17T18:32:53.875702Z","end":"2026-02-17T18:32:54.890591Z","steps":["trace[1205802332] 'process raft request' (duration: 1.014599747s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:54.891221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:53.875686Z","time spent":"1.015030298s","remote":"127.0.0.1:57644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:32:55.059647Z","caller":"traceutil/trace.go:171","msg":"trace[1186525845] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:555; }","duration":"955.287885ms","start":"2026-02-17T18:32:54.104338Z","end":"2026-02-17T18:32:55.059626Z","steps":["trace[1186525845] 'read index received' (duration: 787.421286ms)","trace[1186525845] 'applied index is now lower than readState.Index' (duration: 167.865749ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:32:55.060009Z","caller":"traceutil/trace.go:171","msg":"trace[116615889] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.155940349s","start":"2026-02-17T18:32:53.904056Z","end":"2026-02-17T18:32:55.059997Z","steps":["trace[116615889] 'process raft request' (duration: 1.155214371s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.060152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:53.90404Z","time spent":"1.156061813s","remote":"127.0.0.1:57204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:32:55.060485Z","caller":"traceutil/trace.go:171","msg":"trace[1274660757] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.071769632s","start":"2026-02-17T18:32:53.988703Z","end":"2026-02-17T18:32:55.060473Z","steps":["trace[1274660757] 'process raft request' (duration: 1.070713956s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.060611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:53.988683Z","time spent":"1.071882835s","remote":"127.0.0.1:57442","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-17T18:32:55.060796Z","caller":"traceutil/trace.go:171","msg":"trace[1421883310] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"958.743093ms","start":"2026-02-17T18:32:54.102043Z","end":"2026-02-17T18:32:55.060786Z","steps":["trace[1421883310] 'process raft request' (duration: 957.42878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.0609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:54.102025Z","time spent":"958.846835ms","remote":"127.0.0.1:57204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:32:55.061083Z","caller":"traceutil/trace.go:171","msg":"trace[562159740] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"757.998656ms","start":"2026-02-17T18:32:54.303075Z","end":"2026-02-17T18:32:55.061073Z","steps":["trace[562159740] 'process raft request' (duration: 756.432587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.061213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:54.303056Z","time spent":"758.12922ms","remote":"127.0.0.1:57204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T18:32:55.061414Z","caller":"traceutil/trace.go:171","msg":"trace[1439436997] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"525.547099ms","start":"2026-02-17T18:32:54.535855Z","end":"2026-02-17T18:32:55.061402Z","steps":["trace[1439436997] 'process raft request' (duration: 523.708934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.061837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:54.535838Z","time spent":"525.96654ms","remote":"127.0.0.1:57204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T18:32:55.062068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.727267ms","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-17T18:32:55.062155Z","caller":"traceutil/trace.go:171","msg":"trace[1905990167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:542; }","duration":"957.8306ms","start":"2026-02-17T18:32:54.104313Z","end":"2026-02-17T18:32:55.062144Z","steps":["trace[1905990167] 'agreement among raft nodes before linearized reading' (duration: 957.713237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.062222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:54.104305Z","time spent":"957.905952ms","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T18:32:55.070792Z","caller":"traceutil/trace.go:171","msg":"trace[191907495] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"164.46086ms","start":"2026-02-17T18:32:54.906322Z","end":"2026-02-17T18:32:55.070783Z","steps":["trace[191907495] 'process raft request' (duration: 164.406128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.0711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.435701ms","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-17T18:32:55.071193Z","caller":"traceutil/trace.go:171","msg":"trace[463481658] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:543; }","duration":"433.556884ms","start":"2026-02-17T18:32:54.637626Z","end":"2026-02-17T18:32:55.071183Z","steps":["trace[463481658] 'agreement among raft nodes before linearized reading' (duration: 433.438931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.071253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:54.63761Z","time spent":"433.625476ms","remote":"127.0.0.1:57250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-17T18:32:55.071431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.579568ms","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-17T18:32:55.071499Z","caller":"traceutil/trace.go:171","msg":"trace[631489453] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:543; }","duration":"423.66738ms","start":"2026-02-17T18:32:54.647826Z","end":"2026-02-17T18:32:55.071494Z","steps":["trace[631489453] 'agreement among raft nodes before linearized reading' (duration: 423.580498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.071481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.658298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:32:55.071623Z","caller":"traceutil/trace.go:171","msg":"trace[1315560273] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:543; }","duration":"369.856223ms","start":"2026-02-17T18:32:54.701723Z","end":"2026-02-17T18:32:55.071579Z","steps":["trace[1315560273] 'agreement among raft nodes before linearized reading' (duration: 369.623888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:55.071718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:54.701703Z","time spent":"369.954356ms","remote":"127.0.0.1:57204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-17T18:32:55.07155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:54.647816Z","time spent":"423.728182ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"info","ts":"2026-02-17T18:32:58.051028Z","caller":"traceutil/trace.go:171","msg":"trace[153357131] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"349.530627ms","start":"2026-02-17T18:32:57.701481Z","end":"2026-02-17T18:32:58.051012Z","steps":["trace[153357131] 'read index received' (duration: 349.329652ms)","trace[153357131] 'applied index is now lower than readState.Index' (duration: 200.395µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:32:58.051232Z","caller":"traceutil/trace.go:171","msg":"trace[908676110] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"366.370135ms","start":"2026-02-17T18:32:57.684856Z","end":"2026-02-17T18:32:58.051226Z","steps":["trace[908676110] 'process raft request' (duration: 366.002646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:58.051297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:57.684837Z","time spent":"366.410485ms","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:32:58.051482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.998919ms","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-17T18:32:58.051501Z","caller":"traceutil/trace.go:171","msg":"trace[1346548043] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:595; }","duration":"350.05751ms","start":"2026-02-17T18:32:57.701437Z","end":"2026-02-17T18:32:58.051494Z","steps":["trace[1346548043] 'agreement among raft nodes before linearized reading' (duration: 349.946997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:58.051515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:32:57.701424Z","time spent":"350.087281ms","remote":"127.0.0.1:57204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-17T18:32:58.051638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.71962ms","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-17T18:32:58.05165Z","caller":"traceutil/trace.go:171","msg":"trace[1897037348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"223.755721ms","start":"2026-02-17T18:32:57.82789Z","end":"2026-02-17T18:32:58.051646Z","steps":["trace[1897037348] 'agreement among raft nodes before linearized reading' (duration: 223.71503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:32:58.051772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.838053ms","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-17T18:32:58.051785Z","caller":"traceutil/trace.go:171","msg":"trace[625655231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"223.857573ms","start":"2026-02-17T18:32:57.827923Z","end":"2026-02-17T18:32:58.051781Z","steps":["trace[625655231] 'agreement among raft nodes before linearized reading' (duration: 223.819942ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:02.054395Z","caller":"traceutil/trace.go:171","msg":"trace[1464327601] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"257.365492ms","start":"2026-02-17T18:33:01.796996Z","end":"2026-02-17T18:33:02.054362Z","steps":["trace[1464327601] 'process raft request' (duration: 257.250459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:02.801284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.605449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:33:02.801352Z","caller":"traceutil/trace.go:171","msg":"trace[1995410093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"446.715292ms","start":"2026-02-17T18:33:02.354624Z","end":"2026-02-17T18:33:02.801339Z","steps":["trace[1995410093] 'range keys from in-memory index tree' (duration: 446.521528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:02.80141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:02.354586Z","time spent":"446.806855ms","remote":"127.0.0.1:57178","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-17T18:33:02.801645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.946838ms","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-17T18:33:02.801693Z","caller":"traceutil/trace.go:171","msg":"trace[533874283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"706.04025ms","start":"2026-02-17T18:33:02.095639Z","end":"2026-02-17T18:33:02.80168Z","steps":["trace[533874283] 'range keys from in-memory index tree' (duration: 705.834885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:02.801719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:02.095617Z","time spent":"706.095841ms","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T18:33:02.801778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.889289ms","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-17T18:33:02.801797Z","caller":"traceutil/trace.go:171","msg":"trace[1563851668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"703.946351ms","start":"2026-02-17T18:33:02.097845Z","end":"2026-02-17T18:33:02.801792Z","steps":["trace[1563851668] 'range keys from in-memory index tree' (duration: 703.826498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:02.801811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:02.097831Z","time spent":"703.976591ms","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T18:33:03.670882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021434791399728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:33:04.171288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021434791399728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T18:33:04.493562Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.683950305s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T18:33:04.493914Z","caller":"traceutil/trace.go:171","msg":"trace[105304471] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.684362045s","start":"2026-02-17T18:33:02.809537Z","end":"2026-02-17T18:33:04.493899Z","steps":["trace[105304471] 'process raft request' (duration: 1.684260302s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:04.494026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:02.80952Z","time spent":"1.684441587s","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:33:05.353422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000609141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-17T18:33:05.353546Z","caller":"traceutil/trace.go:171","msg":"trace[2097728598] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000777825s","start":"2026-02-17T18:33:03.352748Z","end":"2026-02-17T18:33:05.353526Z","steps":["trace[2097728598] 'agreement among raft nodes before linearized reading' (duration: 2.000632252s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:05.353603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:03.352735Z","time spent":"2.000854927s","remote":"127.0.0.1:57172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/17 18:33:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-17T18:33:05.796077Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302369372s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T18:33:05.969135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.679767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T18:33:05.969221Z","caller":"traceutil/trace.go:171","msg":"trace[1845482899] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"2.798385935s","start":"2026-02-17T18:33:03.17082Z","end":"2026-02-17T18:33:05.969206Z","steps":["trace[1845482899] 'read index received' (duration: 1.32346016s)","trace[1845482899] 'applied index is now lower than readState.Index' (duration: 1.474924545s)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:33:05.969385Z","caller":"traceutil/trace.go:171","msg":"trace[348701375] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"3.15970291s","start":"2026-02-17T18:33:02.809671Z","end":"2026-02-17T18:33:05.969373Z","steps":["trace[348701375] 'process raft request' (duration: 2.986592923s)","trace[348701375] 'compare' (duration: 172.33525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:33:05.969491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:02.809664Z","time spent":"3.159747351s","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T18:33:05.969673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.798848887s","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-17T18:33:05.969703Z","caller":"traceutil/trace.go:171","msg":"trace[239974138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"2.798909697s","start":"2026-02-17T18:33:03.170784Z","end":"2026-02-17T18:33:05.969694Z","steps":["trace[239974138] 'agreement among raft nodes before linearized reading' (duration: 2.798851586s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:05.969731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:03.170766Z","time spent":"2.798958039s","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T18:33:05.980062Z","caller":"traceutil/trace.go:171","msg":"trace[1473729418] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"553.786057ms","start":"2026-02-17T18:33:05.426258Z","end":"2026-02-17T18:33:05.980044Z","steps":["trace[1473729418] 'process raft request' (duration: 553.669414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:05.980315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:05.426243Z","time spent":"553.873878ms","remote":"127.0.0.1:57442","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-17T18:33:05.980788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.619955468s","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-17T18:33:05.980822Z","caller":"traceutil/trace.go:171","msg":"trace[553850267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"2.62001807s","start":"2026-02-17T18:33:03.360796Z","end":"2026-02-17T18:33:05.980814Z","steps":["trace[553850267] 'agreement among raft nodes before linearized reading' (duration: 2.619925648s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:05.980847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:03.360778Z","time spent":"2.620065021s","remote":"127.0.0.1:57442","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-17T18:33:05.982455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.285474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:33:05.982489Z","caller":"traceutil/trace.go:171","msg":"trace[181256310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"628.347195ms","start":"2026-02-17T18:33:05.354133Z","end":"2026-02-17T18:33:05.98248Z","steps":["trace[181256310] 'agreement among raft nodes before linearized reading' (duration: 628.288774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:05.98251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:05.354119Z","time spent":"628.387297ms","remote":"127.0.0.1:57178","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-17T18:33:05.982705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.584368799s","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-17T18:33:05.982734Z","caller":"traceutil/trace.go:171","msg":"trace[1044135109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:613; }","duration":"2.584434551s","start":"2026-02-17T18:33:03.398293Z","end":"2026-02-17T18:33:05.982728Z","steps":["trace[1044135109] 'agreement among raft nodes before linearized reading' (duration: 2.584348069s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:05.982757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:03.39828Z","time spent":"2.584472202s","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T18:33:05.982958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333922354s","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-17T18:33:05.982984Z","caller":"traceutil/trace.go:171","msg":"trace[467936142] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:613; }","duration":"1.333986675s","start":"2026-02-17T18:33:04.648991Z","end":"2026-02-17T18:33:05.982978Z","steps":["trace[467936142] 'agreement among raft nodes before linearized reading' (duration: 1.333941264s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:33:05.983121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:33:04.648972Z","time spent":"1.334141429s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"info","ts":"2026-02-17T18:33:06.265829Z","caller":"traceutil/trace.go:171","msg":"trace[1511037327] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"273.26046ms","start":"2026-02-17T18:33:05.992552Z","end":"2026-02-17T18:33:06.265813Z","steps":["trace[1511037327] 'read index received' (duration: 16.352152ms)","trace[1511037327] 'applied index is now lower than readState.Index' (duration: 256.907758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:33:06.265933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.365362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:33:06.265954Z","caller":"traceutil/trace.go:171","msg":"trace[1167901467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"273.422073ms","start":"2026-02-17T18:33:05.992526Z","end":"2026-02-17T18:33:06.265948Z","steps":["trace[1167901467] 'agreement among raft nodes before linearized reading' (duration: 273.350961ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:06.265971Z","caller":"traceutil/trace.go:171","msg":"trace[395588055] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"277.886755ms","start":"2026-02-17T18:33:05.988062Z","end":"2026-02-17T18:33:06.265949Z","steps":["trace[395588055] 'process raft request' (duration: 269.099937ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:06.266152Z","caller":"traceutil/trace.go:171","msg":"trace[1413529175] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"273.547726ms","start":"2026-02-17T18:33:05.992597Z","end":"2026-02-17T18:33:06.266145Z","steps":["trace[1413529175] 'process raft request' (duration: 273.129217ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:06.26623Z","caller":"traceutil/trace.go:171","msg":"trace[1999402540] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"271.498089ms","start":"2026-02-17T18:33:05.994727Z","end":"2026-02-17T18:33:06.266225Z","steps":["trace[1999402540] 'process raft request' (duration: 271.046079ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:46.692237Z","caller":"traceutil/trace.go:171","msg":"trace[578179932] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"142.302177ms","start":"2026-02-17T18:33:46.549918Z","end":"2026-02-17T18:33:46.69222Z","steps":["trace[578179932] 'read index received' (duration: 142.176905ms)","trace[578179932] 'applied index is now lower than readState.Index' (duration: 123.482µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:33:46.692381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.444989ms","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-17T18:33:46.692398Z","caller":"traceutil/trace.go:171","msg":"trace[583511200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"142.47701ms","start":"2026-02-17T18:33:46.549915Z","end":"2026-02-17T18:33:46.692392Z","steps":["trace[583511200] 'agreement among raft nodes before linearized reading' (duration: 142.367898ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:46.692467Z","caller":"traceutil/trace.go:171","msg":"trace[2051932963] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"186.717619ms","start":"2026-02-17T18:33:46.505745Z","end":"2026-02-17T18:33:46.692463Z","steps":["trace[2051932963] 'process raft request' (duration: 186.388894ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:48.826508Z","caller":"traceutil/trace.go:171","msg":"trace[1494500834] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"100.410102ms","start":"2026-02-17T18:33:48.726079Z","end":"2026-02-17T18:33:48.826489Z","steps":["trace[1494500834] 'read index received' (duration: 100.25737ms)","trace[1494500834] 'applied index is now lower than readState.Index' (duration: 151.982µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:33:48.826674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.579135ms","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-17T18:33:48.826709Z","caller":"traceutil/trace.go:171","msg":"trace[549721905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"100.637775ms","start":"2026-02-17T18:33:48.726052Z","end":"2026-02-17T18:33:48.82669Z","steps":["trace[549721905] 'agreement among raft nodes before linearized reading' (duration: 100.526734ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:48.826956Z","caller":"traceutil/trace.go:171","msg":"trace[1102635123] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"126.031531ms","start":"2026-02-17T18:33:48.700898Z","end":"2026-02-17T18:33:48.82693Z","steps":["trace[1102635123] 'process raft request' (duration: 125.485293ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:50.968022Z","caller":"traceutil/trace.go:171","msg":"trace[1179617652] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"123.136666ms","start":"2026-02-17T18:33:50.84487Z","end":"2026-02-17T18:33:50.968006Z","steps":["trace[1179617652] 'read index received' (duration: 123.020225ms)","trace[1179617652] 'applied index is now lower than readState.Index' (duration: 116.001µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:33:50.968165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.281919ms","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-17T18:33:50.968182Z","caller":"traceutil/trace.go:171","msg":"trace[734533485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"123.336459ms","start":"2026-02-17T18:33:50.844841Z","end":"2026-02-17T18:33:50.968177Z","steps":["trace[734533485] 'agreement among raft nodes before linearized reading' (duration: 123.225047ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:50.968234Z","caller":"traceutil/trace.go:171","msg":"trace[1140577456] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"134.943407ms","start":"2026-02-17T18:33:50.833282Z","end":"2026-02-17T18:33:50.968225Z","steps":["trace[1140577456] 'process raft request' (duration: 134.626692ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:33:51.124048Z","caller":"traceutil/trace.go:171","msg":"trace[1944252733] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"135.234117ms","start":"2026-02-17T18:33:50.988787Z","end":"2026-02-17T18:33:51.124021Z","steps":["trace[1944252733] 'read index received' (duration: 38.281293ms)","trace[1944252733] 'applied index is now lower than readState.Index' (duration: 96.951934ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T18:33:51.124186Z","caller":"traceutil/trace.go:171","msg":"trace[516724596] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"153.043591ms","start":"2026-02-17T18:33:50.971122Z","end":"2026-02-17T18:33:51.124165Z","steps":["trace[516724596] 'process raft request' (duration: 56.006985ms)","trace[516724596] 'compare' (duration: 96.739632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:33:51.124236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.42432ms","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-17T18:33:51.12429Z","caller":"traceutil/trace.go:171","msg":"trace[1473151572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:848; }","duration":"135.517102ms","start":"2026-02-17T18:33:50.988761Z","end":"2026-02-17T18:33:51.124279Z","steps":["trace[1473151572] 'agreement among raft nodes before linearized reading' (duration: 135.353419ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:34:29.211441Z","caller":"traceutil/trace.go:171","msg":"trace[728273008] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"142.152784ms","start":"2026-02-17T18:34:29.069271Z","end":"2026-02-17T18:34:29.211424Z","steps":["trace[728273008] 'process raft request' (duration: 141.76331ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:34:29.448359Z","caller":"traceutil/trace.go:171","msg":"trace[1884363708] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"108.606021ms","start":"2026-02-17T18:34:29.339691Z","end":"2026-02-17T18:34:29.448297Z","steps":["trace[1884363708] 'process raft request' (duration: 108.342138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:34:29.559299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.139749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:34:29.559376Z","caller":"traceutil/trace.go:171","msg":"trace[1240653981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"109.23862ms","start":"2026-02-17T18:34:29.45012Z","end":"2026-02-17T18:34:29.559359Z","steps":["trace[1240653981] 'range keys from in-memory index tree' (duration: 109.105438ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:34:29.559819Z","caller":"traceutil/trace.go:171","msg":"trace[939449443] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"106.701759ms","start":"2026-02-17T18:34:29.453106Z","end":"2026-02-17T18:34:29.559808Z","steps":["trace[939449443] 'process raft request' (duration: 102.026054ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:34:37.292405Z","caller":"traceutil/trace.go:171","msg":"trace[1238746166] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"581.589258ms","start":"2026-02-17T18:34:36.71079Z","end":"2026-02-17T18:34:37.29238Z","steps":["trace[1238746166] 'process raft request' (duration: 581.446487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:34:37.292569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:34:36.71077Z","time spent":"581.70266ms","remote":"127.0.0.1:57442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T18:34:37.293114Z","caller":"traceutil/trace.go:171","msg":"trace[738473832] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1054; }","duration":"402.514195ms","start":"2026-02-17T18:34:36.890587Z","end":"2026-02-17T18:34:37.293101Z","steps":["trace[738473832] 'read index received' (duration: 402.508785ms)","trace[738473832] 'applied index is now lower than readState.Index' (duration: 4.34µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T18:34:37.293315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.727998ms","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-17T18:34:37.293355Z","caller":"traceutil/trace.go:171","msg":"trace[657067497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"402.809888ms","start":"2026-02-17T18:34:36.890535Z","end":"2026-02-17T18:34:37.293345Z","steps":["trace[657067497] 'agreement among raft nodes before linearized reading' (duration: 402.685547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:34:37.29339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T18:34:36.890511Z","time spent":"402.871199ms","remote":"127.0.0.1:57442","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-17T18:34:55.907847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.584893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/default/\" range_end:\"/registry/networkpolicies/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T18:34:55.907926Z","caller":"traceutil/trace.go:171","msg":"trace[415410522] range","detail":"{range_begin:/registry/networkpolicies/default/; range_end:/registry/networkpolicies/default0; response_count:0; response_revision:1071; }","duration":"115.698705ms","start":"2026-02-17T18:34:55.792209Z","end":"2026-02-17T18:34:55.907908Z","steps":["trace[415410522] 'range keys from in-memory index tree' (duration: 115.537393ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T18:35:13.59554Z","caller":"traceutil/trace.go:171","msg":"trace[267035788] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"110.680981ms","start":"2026-02-17T18:35:13.484839Z","end":"2026-02-17T18:35:13.59552Z","steps":["trace[267035788] 'process raft request' (duration: 103.372827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:35:13.900646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.384578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-zj4wq\" ","response":"range_response_count:1 size:4848"} {"level":"info","ts":"2026-02-17T18:35:13.900732Z","caller":"traceutil/trace.go:171","msg":"trace[757038317] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-zj4wq; range_end:; response_count:1; response_revision:1121; }","duration":"143.515359ms","start":"2026-02-17T18:35:13.757199Z","end":"2026-02-17T18:35:13.900714Z","steps":["trace[757038317] 'range keys from in-memory index tree' (duration: 143.186966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T18:35:13.900976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.386588ms","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-17T18:35:13.901056Z","caller":"traceutil/trace.go:171","msg":"trace[18517892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"128.512569ms","start":"2026-02-17T18:35:13.772522Z","end":"2026-02-17T18:35:13.901034Z","steps":["trace[18517892] 'range keys from in-memory index tree' (duration: 128.231936ms)"],"step_count":1}