{"level":"warn","ts":"2026-02-18T14:39:41.962555Z","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:39:41.962661Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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:39:41.962749Z","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:39:41.962761Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-18T14:39:41.962803Z","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:39:41.963548Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-02-18T14:39:41.963656Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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:39:41.969217Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.301845ms"} {"level":"info","ts":"2026-02-18T14:39:41.986969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-02-18T14:39:41.98702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:39:41.987052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-02-18T14:39:41.987063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:39:41.987075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-02-18T14:39:41.987348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-02-18T14:39:41.995869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:39:42.000406Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:39:42.004418Z","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:39:42.010629Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:39:42.011144Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:39:42.011275Z","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:39:42.011313Z","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:39:42.011321Z","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:39:42.023842Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-18T14:39:42.023859Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-18T14:39:42.023749Z","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:39:42.024706Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:39:42.024733Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:39:42.025035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-02-18T14:39:42.025205Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-18T14:39:42.488116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:39:42.488178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:39:42.488199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-02-18T14:39:42.488209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:39:42.488214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-18T14:39:42.488233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-02-18T14:39:42.48824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-18T14:39:42.494471Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:39:42.498392Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:39:42.498415Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:39:42.498634Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:39:42.500043Z","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:39:42.506494Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-02-18T14:39:42.506573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:39:42.506576Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:39:42.506627Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:39:42.506646Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T14:39:42.506655Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-18T14:39:44.814277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101182710915960,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:39:45.05277Z","caller":"traceutil/trace.go:171","msg":"trace[861756551] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"749.279283ms","start":"2026-02-18T14:39:44.303466Z","end":"2026-02-18T14:39:45.052745Z","steps":["trace[861756551] 'process raft request' (duration: 749.038961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:45.053981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.303456Z","time spent":"749.432424ms","remote":"127.0.0.1:56320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:39:46.422778Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.362240236s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:39:46.424002Z","caller":"traceutil/trace.go:171","msg":"trace[1335796322] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"2.110161478s","start":"2026-02-18T14:39:44.313812Z","end":"2026-02-18T14:39:46.423974Z","steps":["trace[1335796322] 'read index received' (duration: 749.487205ms)","trace[1335796322] 'applied index is now lower than readState.Index' (duration: 1.360673023s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:39:46.424137Z","caller":"traceutil/trace.go:171","msg":"trace[1603168648] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"2.108292422s","start":"2026-02-18T14:39:44.315835Z","end":"2026-02-18T14:39:46.424127Z","steps":["trace[1603168648] 'process raft request' (duration: 2.107870378s)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:39:46.424145Z","caller":"traceutil/trace.go:171","msg":"trace[2061054690] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"2.110308779s","start":"2026-02-18T14:39:44.313738Z","end":"2026-02-18T14:39:46.424046Z","steps":["trace[2061054690] 'process raft request' (duration: 2.109779625s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.424234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.315822Z","time spent":"2.108351172s","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:39:46.424405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.313722Z","time spent":"2.110560151s","remote":"127.0.0.1:56308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:39:46.424674Z","caller":"traceutil/trace.go:171","msg":"trace[841204763] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"2.09129292s","start":"2026-02-18T14:39:44.333333Z","end":"2026-02-18T14:39:46.424626Z","steps":["trace[841204763] 'process raft request' (duration: 2.090407503s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.424736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.333318Z","time spent":"2.091391742s","remote":"127.0.0.1:56436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2846,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:39:46.424924Z","caller":"traceutil/trace.go:171","msg":"trace[1750788115] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"2.044938754s","start":"2026-02-18T14:39:44.379975Z","end":"2026-02-18T14:39:46.424914Z","steps":["trace[1750788115] 'process raft request' (duration: 2.043796265s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.424985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.379956Z","time spent":"2.044998675s","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:39:46.425268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.111387878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-18T14:39:46.425332Z","caller":"traceutil/trace.go:171","msg":"trace[1504316078] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"2.111492709s","start":"2026-02-18T14:39:44.313828Z","end":"2026-02-18T14:39:46.42532Z","steps":["trace[1504316078] 'agreement among raft nodes before linearized reading' (duration: 2.111321087s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.425365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.313826Z","time spent":"2.111530009s","remote":"127.0.0.1:56436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":2881,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-18T14:39:46.425528Z","caller":"traceutil/trace.go:171","msg":"trace[43751674] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"2.045173566s","start":"2026-02-18T14:39:44.380334Z","end":"2026-02-18T14:39:46.425507Z","steps":["trace[43751674] 'process raft request' (duration: 2.043465012s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.425624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041515621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:39:46.425664Z","caller":"traceutil/trace.go:171","msg":"trace[336604345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"1.041606541s","start":"2026-02-18T14:39:45.384048Z","end":"2026-02-18T14:39:46.425654Z","steps":["trace[336604345] 'agreement among raft nodes before linearized reading' (duration: 1.041549801s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.425692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:45.384029Z","time spent":"1.041656163s","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:39:46.425637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.380324Z","time spent":"2.045275657s","remote":"127.0.0.1:56756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:39:46.425838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211468388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:39:46.425873Z","caller":"traceutil/trace.go:171","msg":"trace[563666688] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"1.211519818s","start":"2026-02-18T14:39:45.214344Z","end":"2026-02-18T14:39:46.425864Z","steps":["trace[563666688] 'agreement among raft nodes before linearized reading' (duration: 1.211470358s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.425904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:45.214337Z","time spent":"1.211560408s","remote":"127.0.0.1:56594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-02-18T14:39:46.426034Z","caller":"traceutil/trace.go:171","msg":"trace[786226129] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"2.0456215s","start":"2026-02-18T14:39:44.380399Z","end":"2026-02-18T14:39:46.426021Z","steps":["trace[786226129] 'process raft request' (duration: 2.043430182s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.426087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972240069s","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:39:46.426132Z","caller":"traceutil/trace.go:171","msg":"trace[1908558626] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.97231013s","start":"2026-02-18T14:39:44.453806Z","end":"2026-02-18T14:39:46.426116Z","steps":["trace[1908558626] 'agreement among raft nodes before linearized reading' (duration: 1.97226647s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.426197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.453789Z","time spent":"1.972393301s","remote":"127.0.0.1:56358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-18T14:39:46.426322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.380393Z","time spent":"2.045710061s","remote":"127.0.0.1:56756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:39:46.426588Z","caller":"traceutil/trace.go:171","msg":"trace[407799941] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.046002043s","start":"2026-02-18T14:39:44.380575Z","end":"2026-02-18T14:39:46.426577Z","steps":["trace[407799941] 'process raft request' (duration: 2.043292431s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.426697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.380562Z","time spent":"2.046108134s","remote":"127.0.0.1:56756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:39:46.426931Z","caller":"traceutil/trace.go:171","msg":"trace[1193126260] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.045403078s","start":"2026-02-18T14:39:44.381517Z","end":"2026-02-18T14:39:46.42692Z","steps":["trace[1193126260] 'process raft request' (duration: 2.042380663s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.430545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.381479Z","time spent":"2.049015458s","remote":"127.0.0.1:56756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:39:46.430798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.04686123s","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:39:46.430849Z","caller":"traceutil/trace.go:171","msg":"trace[1427332919] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.046938191s","start":"2026-02-18T14:39:44.383902Z","end":"2026-02-18T14:39:46.43084Z","steps":["trace[1427332919] 'agreement among raft nodes before linearized reading' (duration: 2.046704039s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.431076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.383894Z","time spent":"2.047107542s","remote":"127.0.0.1:56358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-18T14:39:46.431068Z","caller":"traceutil/trace.go:171","msg":"trace[292097820] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"2.048907307s","start":"2026-02-18T14:39:44.381919Z","end":"2026-02-18T14:39:46.430826Z","steps":["trace[292097820] 'process raft request' (duration: 2.04201436s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.43138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.381911Z","time spent":"2.049366251s","remote":"127.0.0.1:56756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:39:46.431525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.053346054s","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:39:46.431561Z","caller":"traceutil/trace.go:171","msg":"trace[1822297938] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.053432885s","start":"2026-02-18T14:39:44.378119Z","end":"2026-02-18T14:39:46.431552Z","steps":["trace[1822297938] 'agreement among raft nodes before linearized reading' (duration: 2.053354294s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.431674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.3781Z","time spent":"2.053564306s","remote":"127.0.0.1:56358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-18T14:39:46.431812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.065804177s","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:39:46.431851Z","caller":"traceutil/trace.go:171","msg":"trace[1411624019] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.065872448s","start":"2026-02-18T14:39:44.36597Z","end":"2026-02-18T14:39:46.431842Z","steps":["trace[1411624019] 'agreement among raft nodes before linearized reading' (duration: 2.065801158s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.432049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.365938Z","time spent":"2.06610137s","remote":"127.0.0.1:56358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-18T14:39:46.426055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212766799s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:39:46.432454Z","caller":"traceutil/trace.go:171","msg":"trace[252637073] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"1.219196121s","start":"2026-02-18T14:39:45.213241Z","end":"2026-02-18T14:39:46.432437Z","steps":["trace[252637073] 'agreement among raft nodes before linearized reading' (duration: 1.212781828s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.432896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:45.213205Z","time spent":"1.219674575s","remote":"127.0.0.1:56612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-18T14:39:46.436495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.120526023s","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:39:46.436539Z","caller":"traceutil/trace.go:171","msg":"trace[1796089322] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.120651024s","start":"2026-02-18T14:39:44.315878Z","end":"2026-02-18T14:39:46.436529Z","steps":["trace[1796089322] 'agreement among raft nodes before linearized reading' (duration: 2.120515773s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.437258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.315875Z","time spent":"2.120896686s","remote":"127.0.0.1:56358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-18T14:39:46.425286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.111458178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T14:39:46.438147Z","caller":"traceutil/trace.go:171","msg":"trace[1691304582] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:0; response_revision:11; }","duration":"2.124346294s","start":"2026-02-18T14:39:44.313791Z","end":"2026-02-18T14:39:46.438137Z","steps":["trace[1691304582] 'agreement among raft nodes before linearized reading' (duration: 2.111332647s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.438207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.313785Z","time spent":"2.124412145s","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-02-18T14:39:46.440924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.12500591s","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:39:46.441044Z","caller":"traceutil/trace.go:171","msg":"trace[2124599778] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"2.12506497s","start":"2026-02-18T14:39:44.315894Z","end":"2026-02-18T14:39:46.440959Z","steps":["trace[2124599778] 'agreement among raft nodes before linearized reading' (duration: 2.116722662s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:46.441119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:44.315892Z","time spent":"2.125212622s","remote":"127.0.0.1:56344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-02-18T14:39:49.414821Z","caller":"traceutil/trace.go:171","msg":"trace[695482439] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"101.256921ms","start":"2026-02-18T14:39:49.313546Z","end":"2026-02-18T14:39:49.414803Z","steps":["trace[695482439] 'process raft request' (duration: 101.12086ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:39:53.892924Z","caller":"traceutil/trace.go:171","msg":"trace[649597906] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"325.141487ms","start":"2026-02-18T14:39:53.56776Z","end":"2026-02-18T14:39:53.892901Z","steps":["trace[649597906] 'process raft request' (duration: 324.828914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:53.893037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:53.567739Z","time spent":"325.251148ms","remote":"127.0.0.1:56468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:39:54.229463Z","caller":"traceutil/trace.go:171","msg":"trace[1726940851] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:302; }","duration":"327.390087ms","start":"2026-02-18T14:39:53.902047Z","end":"2026-02-18T14:39:54.229437Z","steps":["trace[1726940851] 'read index received' (duration: 322.453577ms)","trace[1726940851] 'applied index is now lower than readState.Index' (duration: 4.93518ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:39:54.230214Z","caller":"traceutil/trace.go:171","msg":"trace[167793547] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"328.779427ms","start":"2026-02-18T14:39:53.901416Z","end":"2026-02-18T14:39:54.230195Z","steps":["trace[167793547] 'process raft request' (duration: 323.132483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:54.23064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:53.901403Z","time spent":"329.00802ms","remote":"127.0.0.1:56526","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:39:54.230921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.856488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-18T14:39:54.230972Z","caller":"traceutil/trace.go:171","msg":"trace[1901827138] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:293; }","duration":"328.9294ms","start":"2026-02-18T14:39:53.902031Z","end":"2026-02-18T14:39:54.230961Z","steps":["trace[1901827138] 'agreement among raft nodes before linearized reading' (duration: 328.845529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:39:54.231106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:39:53.902025Z","time spent":"329.071351ms","remote":"127.0.0.1:56468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-02-18T14:40:01.044687Z","caller":"traceutil/trace.go:171","msg":"trace[2100067562] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"298.245344ms","start":"2026-02-18T14:40:00.746418Z","end":"2026-02-18T14:40:01.044663Z","steps":["trace[2100067562] 'process raft request' (duration: 298.094436ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:01.612975Z","caller":"traceutil/trace.go:171","msg":"trace[641753527] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"106.222971ms","start":"2026-02-18T14:40:01.506728Z","end":"2026-02-18T14:40:01.612951Z","steps":["trace[641753527] 'process raft request' (duration: 106.042322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:10.748587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.331606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:10.748665Z","caller":"traceutil/trace.go:171","msg":"trace[1263996299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"128.445232ms","start":"2026-02-18T14:40:10.620207Z","end":"2026-02-18T14:40:10.748652Z","steps":["trace[1263996299] 'range keys from in-memory index tree' (duration: 128.244062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:10.749007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.570502ms","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-18T14:40:10.749045Z","caller":"traceutil/trace.go:171","msg":"trace[1556807493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"101.630335ms","start":"2026-02-18T14:40:10.647408Z","end":"2026-02-18T14:40:10.749038Z","steps":["trace[1556807493] 'range keys from in-memory index tree' (duration: 101.515081ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:10.90362Z","caller":"traceutil/trace.go:171","msg":"trace[974352515] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"150.573482ms","start":"2026-02-18T14:40:10.753031Z","end":"2026-02-18T14:40:10.903604Z","steps":["trace[974352515] 'process raft request' (duration: 150.473038ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:16.939839Z","caller":"traceutil/trace.go:171","msg":"trace[1946445945] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:485; }","duration":"323.977973ms","start":"2026-02-18T14:40:16.615851Z","end":"2026-02-18T14:40:16.939829Z","steps":["trace[1946445945] 'read index received' (duration: 323.973363ms)","trace[1946445945] 'applied index is now lower than readState.Index' (duration: 4.04µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:16.939954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.091388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:16.939971Z","caller":"traceutil/trace.go:171","msg":"trace[606498613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"324.14439ms","start":"2026-02-18T14:40:16.615821Z","end":"2026-02-18T14:40:16.939965Z","steps":["trace[606498613] 'agreement among raft nodes before linearized reading' (duration: 324.070737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:16.939987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:16.615808Z","time spent":"324.175021ms","remote":"127.0.0.1:56260","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:40:16.939827Z","caller":"traceutil/trace.go:171","msg":"trace[942353877] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"426.887284ms","start":"2026-02-18T14:40:16.512899Z","end":"2026-02-18T14:40:16.939787Z","steps":["trace[942353877] 'process raft request' (duration: 426.736897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:16.940418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:16.512888Z","time spent":"427.453208ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:17.251321Z","caller":"traceutil/trace.go:171","msg":"trace[480734365] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"307.090683ms","start":"2026-02-18T14:40:16.944218Z","end":"2026-02-18T14:40:17.251308Z","steps":["trace[480734365] 'process raft request' (duration: 303.406467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:17.251455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:16.944204Z","time spent":"307.173067ms","remote":"127.0.0.1:56526","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:40:17.251092Z","caller":"traceutil/trace.go:171","msg":"trace[2053342749] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"174.231531ms","start":"2026-02-18T14:40:17.076836Z","end":"2026-02-18T14:40:17.251068Z","steps":["trace[2053342749] 'read index received' (duration: 170.851688ms)","trace[2053342749] 'applied index is now lower than readState.Index' (duration: 3.378933ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:17.252457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.509254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4063"} {"level":"info","ts":"2026-02-18T14:40:17.252495Z","caller":"traceutil/trace.go:171","msg":"trace[1617609016] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:471; }","duration":"175.679611ms","start":"2026-02-18T14:40:17.076803Z","end":"2026-02-18T14:40:17.252482Z","steps":["trace[1617609016] 'agreement among raft nodes before linearized reading' (duration: 175.503544ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:19.359291Z","caller":"traceutil/trace.go:171","msg":"trace[1925070836] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"100.525117ms","start":"2026-02-18T14:40:19.258747Z","end":"2026-02-18T14:40:19.359272Z","steps":["trace[1925070836] 'process raft request' (duration: 100.407192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:21.384445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028617696s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:40:21.650282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.016718119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:40:21.650358Z","caller":"traceutil/trace.go:171","msg":"trace[1654209000] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.016849894s","start":"2026-02-18T14:40:19.633488Z","end":"2026-02-18T14:40:21.650338Z","steps":["trace[1654209000] 'agreement among raft nodes before linearized reading' (duration: 2.016740779s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:21.650402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:19.633472Z","time spent":"2.016920877s","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:40:21.697008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.818558593s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:21.698049Z","caller":"traceutil/trace.go:171","msg":"trace[1181189822] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.721473278s","start":"2026-02-18T14:40:19.976564Z","end":"2026-02-18T14:40:21.698038Z","steps":["trace[1181189822] 'process raft request' (duration: 1.721446937s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:21.698097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:19.976546Z","time spent":"1.721531561s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:21.698342Z","caller":"traceutil/trace.go:171","msg":"trace[2068910224] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"2.33295453s","start":"2026-02-18T14:40:19.365378Z","end":"2026-02-18T14:40:21.698332Z","steps":["trace[2068910224] 'process raft request' (duration: 2.331693429s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:21.698381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:19.365363Z","time spent":"2.332999641s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:21.698056Z","caller":"traceutil/trace.go:171","msg":"trace[730723171] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"2.334811446s","start":"2026-02-18T14:40:19.363226Z","end":"2026-02-18T14:40:21.698038Z","steps":["trace[730723171] 'process raft request' (duration: 515.086346ms)","trace[730723171] 'compare' (duration: 1.817938448s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:21.698482Z","caller":"traceutil/trace.go:171","msg":"trace[1555585406] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:496; }","duration":"2.064958875s","start":"2026-02-18T14:40:19.633517Z","end":"2026-02-18T14:40:21.698476Z","steps":["trace[1555585406] 'read index received' (duration: 244.87457ms)","trace[1555585406] 'applied index is now lower than readState.Index' (duration: 1.820083705s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:21.698709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:19.363209Z","time spent":"2.335337728s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:40:21.698902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670721746s","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:40:21.698926Z","caller":"traceutil/trace.go:171","msg":"trace[429559724] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:483; }","duration":"1.670780589s","start":"2026-02-18T14:40:20.028139Z","end":"2026-02-18T14:40:21.69892Z","steps":["trace[429559724] 'agreement among raft nodes before linearized reading' (duration: 1.670592991s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:21.698943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:20.028126Z","time spent":"1.670813869s","remote":"127.0.0.1:56348","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-18T14:40:21.699207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.243704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T14:40:21.699228Z","caller":"traceutil/trace.go:171","msg":"trace[1584147004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"335.296116ms","start":"2026-02-18T14:40:21.363926Z","end":"2026-02-18T14:40:21.699222Z","steps":["trace[1584147004] 'agreement among raft nodes before linearized reading' (duration: 335.260314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:21.699246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:21.363906Z","time spent":"335.333708ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:40:21.699332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.810624ms","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:40:21.699346Z","caller":"traceutil/trace.go:171","msg":"trace[1466733327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"741.849236ms","start":"2026-02-18T14:40:20.957493Z","end":"2026-02-18T14:40:21.699342Z","steps":["trace[1466733327] 'agreement among raft nodes before linearized reading' (duration: 741.822505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:21.699358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:20.957479Z","time spent":"741.876137ms","remote":"127.0.0.1:56526","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:40:22.154602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.188737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:22.155016Z","caller":"traceutil/trace.go:171","msg":"trace[1974391915] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"445.871679ms","start":"2026-02-18T14:40:21.709062Z","end":"2026-02-18T14:40:22.154933Z","steps":["trace[1974391915] 'process raft request' (duration: 279.284317ms)","trace[1974391915] 'compare' (duration: 165.751669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:22.15655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:21.709038Z","time spent":"447.465222ms","remote":"127.0.0.1:56526","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:40:22.155615Z","caller":"traceutil/trace.go:171","msg":"trace[1949277343] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:499; }","duration":"444.012993ms","start":"2026-02-18T14:40:21.711585Z","end":"2026-02-18T14:40:22.155598Z","steps":["trace[1949277343] 'read index received' (duration: 276.775065ms)","trace[1949277343] 'applied index is now lower than readState.Index' (duration: 167.236918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:22.155706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.110507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:22.155741Z","caller":"traceutil/trace.go:171","msg":"trace[1916415277] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"444.196941ms","start":"2026-02-18T14:40:21.711536Z","end":"2026-02-18T14:40:22.155733Z","steps":["trace[1916415277] 'process raft request' (duration: 443.840817ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:22.156856Z","caller":"traceutil/trace.go:171","msg":"trace[1913771238] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"444.148869ms","start":"2026-02-18T14:40:21.712543Z","end":"2026-02-18T14:40:22.156691Z","steps":["trace[1913771238] 'process raft request' (duration: 443.002023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:22.157603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:21.712536Z","time spent":"445.017513ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:22.15784Z","caller":"traceutil/trace.go:171","msg":"trace[654635701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"446.259413ms","start":"2026-02-18T14:40:21.711568Z","end":"2026-02-18T14:40:22.157827Z","steps":["trace[654635701] 'agreement among raft nodes before linearized reading' (duration: 444.081256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:22.15855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:21.711563Z","time spent":"446.972211ms","remote":"127.0.0.1:56260","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:40:22.157929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.312927ms","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:40:22.161901Z","caller":"traceutil/trace.go:171","msg":"trace[1867656728] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:486; }","duration":"367.265075ms","start":"2026-02-18T14:40:21.794604Z","end":"2026-02-18T14:40:22.161869Z","steps":["trace[1867656728] 'agreement among raft nodes before linearized reading' (duration: 363.294186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:22.157981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:21.711528Z","time spent":"446.43037ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:40:22.162244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:21.794566Z","time spent":"367.659291ms","remote":"127.0.0.1:56348","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-18T14:40:22.709923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.338912ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:22.710113Z","caller":"traceutil/trace.go:171","msg":"trace[390760044] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:491; }","duration":"109.54399ms","start":"2026-02-18T14:40:22.600551Z","end":"2026-02-18T14:40:22.710095Z","steps":["trace[390760044] 'range keys from in-memory index tree' (duration: 109.321801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:22.710032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.803397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:22.711152Z","caller":"traceutil/trace.go:171","msg":"trace[2005020186] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"192.348944ms","start":"2026-02-18T14:40:22.518754Z","end":"2026-02-18T14:40:22.711103Z","steps":["trace[2005020186] 'process raft request' (duration: 45.410842ms)","trace[2005020186] 'compare' (duration: 145.659911ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:23.010731Z","caller":"traceutil/trace.go:171","msg":"trace[1856239714] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"125.287185ms","start":"2026-02-18T14:40:22.885363Z","end":"2026-02-18T14:40:23.01065Z","steps":["trace[1856239714] 'process raft request' (duration: 48.568568ms)","trace[1856239714] 'compare' (duration: 76.609443ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:23.229937Z","caller":"traceutil/trace.go:171","msg":"trace[458876116] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"208.890613ms","start":"2026-02-18T14:40:23.021019Z","end":"2026-02-18T14:40:23.22991Z","steps":["trace[458876116] 'process raft request' (duration: 125.44088ms)","trace[458876116] 'compare' (duration: 83.278587ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:23.233967Z","caller":"traceutil/trace.go:171","msg":"trace[13768015] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"212.557538ms","start":"2026-02-18T14:40:23.021387Z","end":"2026-02-18T14:40:23.233944Z","steps":["trace[13768015] 'process raft request' (duration: 212.442623ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:30.216565Z","caller":"traceutil/trace.go:171","msg":"trace[1202383541] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"356.569393ms","start":"2026-02-18T14:40:29.859977Z","end":"2026-02-18T14:40:30.216546Z","steps":["trace[1202383541] 'process raft request' (duration: 356.188319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:30.217061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:29.859965Z","time spent":"356.936437ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:40:32.775169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.743016ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:32.775247Z","caller":"traceutil/trace.go:171","msg":"trace[1315665932] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:526; }","duration":"175.827779ms","start":"2026-02-18T14:40:32.599407Z","end":"2026-02-18T14:40:32.775234Z","steps":["trace[1315665932] 'range keys from in-memory index tree' (duration: 175.734576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:32.775423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.872431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:32.775506Z","caller":"traceutil/trace.go:171","msg":"trace[1733434180] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"964.980845ms","start":"2026-02-18T14:40:31.810519Z","end":"2026-02-18T14:40:32.7755Z","steps":["trace[1733434180] 'process raft request' (duration: 964.946293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:32.775535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:31.810503Z","time spent":"965.017276ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:32.775687Z","caller":"traceutil/trace.go:171","msg":"trace[2136834586] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"2.914513992s","start":"2026-02-18T14:40:29.861167Z","end":"2026-02-18T14:40:32.775681Z","steps":["trace[2136834586] 'process raft request' (duration: 2.45834964s)","trace[2136834586] 'compare' (duration: 455.440746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:32.775713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:29.861151Z","time spent":"2.914548464s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:32.775767Z","caller":"traceutil/trace.go:171","msg":"trace[1547215413] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:543; }","duration":"2.655862062s","start":"2026-02-18T14:40:30.1199Z","end":"2026-02-18T14:40:32.775762Z","steps":["trace[1547215413] 'read index received' (duration: 96.319874ms)","trace[1547215413] 'applied index is now lower than readState.Index' (duration: 2.559541528s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:32.775848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.655947255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdw7n\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-18T14:40:32.775864Z","caller":"traceutil/trace.go:171","msg":"trace[1719770828] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdw7n; range_end:; response_count:1; response_revision:528; }","duration":"2.655963076s","start":"2026-02-18T14:40:30.119896Z","end":"2026-02-18T14:40:32.775859Z","steps":["trace[1719770828] 'agreement among raft nodes before linearized reading' (duration: 2.655930844s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:32.775877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:30.11986Z","time spent":"2.656013598s","remote":"127.0.0.1:56438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-hdw7n\" "} {"level":"warn","ts":"2026-02-18T14:40:33.616358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000017653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:40:33.61643Z","caller":"traceutil/trace.go:171","msg":"trace[938327390] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000102286s","start":"2026-02-18T14:40:31.616311Z","end":"2026-02-18T14:40:33.616414Z","steps":["trace[938327390] 'agreement among raft nodes before linearized reading' (duration: 2.000015143s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:33.616569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:31.616262Z","time spent":"2.000201279s","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:40:33 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:40:34.170035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296744703s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T14:40:34.170107Z","caller":"traceutil/trace.go:171","msg":"trace[149260810] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"1.394319307s","start":"2026-02-18T14:40:32.775776Z","end":"2026-02-18T14:40:34.170096Z","steps":["trace[149260810] 'read index received' (duration: 97.606066ms)","trace[149260810] 'applied index is now lower than readState.Index' (duration: 1.296712311s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:34.170459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945893458s","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-18T14:40:34.170485Z","caller":"traceutil/trace.go:171","msg":"trace[805689801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"1.94595076s","start":"2026-02-18T14:40:32.224526Z","end":"2026-02-18T14:40:34.170477Z","steps":["trace[805689801] 'agreement among raft nodes before linearized reading' (duration: 1.945894028s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:34.170502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:32.22451Z","time spent":"1.945987932s","remote":"127.0.0.1:56526","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-18T14:40:34.170652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.981854349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:40:34.170666Z","caller":"traceutil/trace.go:171","msg":"trace[1638975255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"3.98188904s","start":"2026-02-18T14:40:30.188773Z","end":"2026-02-18T14:40:34.170662Z","steps":["trace[1638975255] 'agreement among raft nodes before linearized reading' (duration: 3.981830518s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:34.170678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:30.188763Z","time spent":"3.981912731s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:40:34.170757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.990167224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T14:40:34.170771Z","caller":"traceutil/trace.go:171","msg":"trace[1464275837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"3.990210315s","start":"2026-02-18T14:40:30.180555Z","end":"2026-02-18T14:40:34.170766Z","steps":["trace[1464275837] 'agreement among raft nodes before linearized reading' (duration: 3.990171614s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:34.170782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:30.180542Z","time spent":"3.990237506s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T14:40:34.197402Z","caller":"traceutil/trace.go:171","msg":"trace[1178454281] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.388986097s","start":"2026-02-18T14:40:32.808399Z","end":"2026-02-18T14:40:34.197385Z","steps":["trace[1178454281] 'process raft request' (duration: 1.388875083s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:34.197511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:32.808383Z","time spent":"1.38907212s","remote":"127.0.0.1:56438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:40:34.197871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.302058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:34.197895Z","caller":"traceutil/trace.go:171","msg":"trace[1339292241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"581.35211ms","start":"2026-02-18T14:40:33.616536Z","end":"2026-02-18T14:40:34.197888Z","steps":["trace[1339292241] 'agreement among raft nodes before linearized reading' (duration: 581.317039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:34.197913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:33.616522Z","time spent":"581.387981ms","remote":"127.0.0.1:56266","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:40:34.507271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.387887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:34.507527Z","caller":"traceutil/trace.go:171","msg":"trace[182019538] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"293.190276ms","start":"2026-02-18T14:40:34.21432Z","end":"2026-02-18T14:40:34.50751Z","steps":["trace[182019538] 'read index received' (duration: 69.418336ms)","trace[182019538] 'applied index is now lower than readState.Index' (duration: 223.77063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:34.509385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.055411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955e37af860fe6\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T14:40:34.509549Z","caller":"traceutil/trace.go:171","msg":"trace[780590915] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18955e37af860fe6; range_end:; response_count:1; response_revision:532; }","duration":"295.292719ms","start":"2026-02-18T14:40:34.214244Z","end":"2026-02-18T14:40:34.509537Z","steps":["trace[780590915] 'agreement among raft nodes before linearized reading' (duration: 295.04288ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:34.509863Z","caller":"traceutil/trace.go:171","msg":"trace[1539392129] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"332.416482ms","start":"2026-02-18T14:40:34.177435Z","end":"2026-02-18T14:40:34.509852Z","steps":["trace[1539392129] 'process raft request' (duration: 106.356802ms)","trace[1539392129] 'compare' (duration: 222.532876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:34.510039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:34.177405Z","time spent":"332.594608ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:34.510224Z","caller":"traceutil/trace.go:171","msg":"trace[1808417221] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"331.22673ms","start":"2026-02-18T14:40:34.178987Z","end":"2026-02-18T14:40:34.510213Z","steps":["trace[1808417221] 'process raft request' (duration: 328.420561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:34.510422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:34.178973Z","time spent":"331.416387ms","remote":"127.0.0.1:56526","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:40:34.511121Z","caller":"traceutil/trace.go:171","msg":"trace[545954050] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"330.532026ms","start":"2026-02-18T14:40:34.180578Z","end":"2026-02-18T14:40:34.51111Z","steps":["trace[545954050] 'process raft request' (duration: 326.892558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:34.511299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:34.180572Z","time spent":"330.695482ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:40:34.510691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.800222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:34.512437Z","caller":"traceutil/trace.go:171","msg":"trace[758572571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"296.574414ms","start":"2026-02-18T14:40:34.215851Z","end":"2026-02-18T14:40:34.512426Z","steps":["trace[758572571] 'agreement among raft nodes before linearized reading' (duration: 294.814923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:35.12015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101182710917010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:40:35.621017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101182710917010,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:40:35.893114Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.373217341s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:40:36.619472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999814845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:40:36.619552Z","caller":"traceutil/trace.go:171","msg":"trace[1785400069] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999940299s","start":"2026-02-18T14:40:34.619593Z","end":"2026-02-18T14:40:36.619533Z","steps":["trace[1785400069] 'agreement among raft nodes before linearized reading' (duration: 1.999839035s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:36.619612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:34.619576Z","time spent":"2.000025632s","remote":"127.0.0.1:56266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:40:38.625409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000189499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T14:40:38.625469Z","caller":"traceutil/trace.go:171","msg":"trace[353752453] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000291303s","start":"2026-02-18T14:40:36.625164Z","end":"2026-02-18T14:40:38.625456Z","steps":["trace[353752453] 'agreement among raft nodes before linearized reading' (duration: 2.0002122s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:38.625502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:36.625147Z","time spent":"2.000348235s","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:40:39.193469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.300137388s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:39.193539Z","caller":"traceutil/trace.go:171","msg":"trace[550771831] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"4.57390421s","start":"2026-02-18T14:40:34.619626Z","end":"2026-02-18T14:40:39.19353Z","steps":["trace[550771831] 'read index received' (duration: 1.273599897s)","trace[550771831] 'applied index is now lower than readState.Index' (duration: 3.300303583s)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:39.193575Z","caller":"traceutil/trace.go:171","msg":"trace[1815425482] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"4.673763663s","start":"2026-02-18T14:40:34.519806Z","end":"2026-02-18T14:40:39.19357Z","steps":["trace[1815425482] 'process raft request' (duration: 1.373479631s)","trace[1815425482] 'compare' (duration: 3.299421313s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:39.193601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:34.519792Z","time spent":"4.673794904s","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:40:39.193754Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.557708425s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:40:39.19387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.410498755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T14:40:39.193884Z","caller":"traceutil/trace.go:171","msg":"trace[1767781002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"4.410570907s","start":"2026-02-18T14:40:34.783308Z","end":"2026-02-18T14:40:39.193879Z","steps":["trace[1767781002] 'agreement among raft nodes before linearized reading' (duration: 4.410526086s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.1939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:34.783274Z","time spent":"4.41062194s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T14:40:39.193998Z","caller":"traceutil/trace.go:171","msg":"trace[185901924] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.558012425s","start":"2026-02-18T14:40:37.63598Z","end":"2026-02-18T14:40:39.193992Z","steps":["trace[185901924] 'process raft request' (duration: 1.557823939s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.194026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:37.635965Z","time spent":"1.558044307s","remote":"127.0.0.1:56526","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-18T14:40:39.194098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.684632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:39.194111Z","caller":"traceutil/trace.go:171","msg":"trace[687788415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"567.715853ms","start":"2026-02-18T14:40:38.626391Z","end":"2026-02-18T14:40:39.194107Z","steps":["trace[687788415] 'agreement among raft nodes before linearized reading' (duration: 567.695232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.194122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:38.626379Z","time spent":"567.740163ms","remote":"127.0.0.1:56266","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:40:39.194214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004379689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T14:40:39.194225Z","caller":"traceutil/trace.go:171","msg":"trace[1607722538] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:534; }","duration":"2.004416531s","start":"2026-02-18T14:40:37.189805Z","end":"2026-02-18T14:40:39.194222Z","steps":["trace[1607722538] 'agreement among raft nodes before linearized reading' (duration: 2.004363519s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.194236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:37.189777Z","time spent":"2.004456952s","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-02-18T14:40:39.194337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.66850433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T14:40:39.19435Z","caller":"traceutil/trace.go:171","msg":"trace[132826035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"2.66851877s","start":"2026-02-18T14:40:36.525827Z","end":"2026-02-18T14:40:39.194346Z","steps":["trace[132826035] 'agreement among raft nodes before linearized reading' (duration: 2.668493989s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.194361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:36.525809Z","time spent":"2.66855s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:40:39.194414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.668719206s","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:40:39.194426Z","caller":"traceutil/trace.go:171","msg":"trace[1663160494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"2.668745598s","start":"2026-02-18T14:40:36.525677Z","end":"2026-02-18T14:40:39.194423Z","steps":["trace[1663160494] 'agreement among raft nodes before linearized reading' (duration: 2.668724097s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.194439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:36.525671Z","time spent":"2.668764718s","remote":"127.0.0.1:56526","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:40:39.194492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.678402019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T14:40:39.194503Z","caller":"traceutil/trace.go:171","msg":"trace[466118069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"2.67844838s","start":"2026-02-18T14:40:36.516051Z","end":"2026-02-18T14:40:39.194499Z","steps":["trace[466118069] 'agreement among raft nodes before linearized reading' (duration: 2.678428399s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.194513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:36.51603Z","time spent":"2.678480891s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T14:40:39.538616Z","caller":"traceutil/trace.go:171","msg":"trace[2106159006] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"327.175986ms","start":"2026-02-18T14:40:39.211422Z","end":"2026-02-18T14:40:39.538598Z","steps":["trace[2106159006] 'read index received' (duration: 50.338758ms)","trace[2106159006] 'applied index is now lower than readState.Index' (duration: 276.836628ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:39.538852Z","caller":"traceutil/trace.go:171","msg":"trace[139411523] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"329.523184ms","start":"2026-02-18T14:40:39.209318Z","end":"2026-02-18T14:40:39.538842Z","steps":["trace[139411523] 'process raft request' (duration: 329.0966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.538924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:39.209252Z","time spent":"329.621147ms","remote":"127.0.0.1:56526","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:40:39.539041Z","caller":"traceutil/trace.go:171","msg":"trace[1700619607] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"329.642688ms","start":"2026-02-18T14:40:39.20939Z","end":"2026-02-18T14:40:39.539033Z","steps":["trace[1700619607] 'process raft request' (duration: 329.128021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.539075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:39.209385Z","time spent":"329.671129ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:39.53915Z","caller":"traceutil/trace.go:171","msg":"trace[2091960464] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"326.852695ms","start":"2026-02-18T14:40:39.212291Z","end":"2026-02-18T14:40:39.539144Z","steps":["trace[2091960464] 'process raft request' (duration: 326.255575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.539184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:39.212285Z","time spent":"326.879406ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:39.539272Z","caller":"traceutil/trace.go:171","msg":"trace[550924027] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"324.700203ms","start":"2026-02-18T14:40:39.214565Z","end":"2026-02-18T14:40:39.539265Z","steps":["trace[550924027] 'process raft request' (duration: 324.00704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.539304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:39.214558Z","time spent":"324.728664ms","remote":"127.0.0.1:56526","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:40:39.5394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.975102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955e37af860fe6\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-18T14:40:39.539423Z","caller":"traceutil/trace.go:171","msg":"trace[1367641784] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18955e37af860fe6; range_end:; response_count:1; response_revision:538; }","duration":"328.015844ms","start":"2026-02-18T14:40:39.2114Z","end":"2026-02-18T14:40:39.539416Z","steps":["trace[1367641784] 'agreement among raft nodes before linearized reading' (duration: 327.973233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.539441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:39.211391Z","time spent":"328.045315ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18955e37af860fe6\" "} {"level":"warn","ts":"2026-02-18T14:40:39.766489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.354676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:39.766599Z","caller":"traceutil/trace.go:171","msg":"trace[553618947] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"223.723738ms","start":"2026-02-18T14:40:39.542864Z","end":"2026-02-18T14:40:39.766588Z","steps":["trace[553618947] 'process raft request' (duration: 223.687897ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:39.766732Z","caller":"traceutil/trace.go:171","msg":"trace[1177735786] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"504.508867ms","start":"2026-02-18T14:40:39.262216Z","end":"2026-02-18T14:40:39.766725Z","steps":["trace[1177735786] 'process raft request' (duration: 295.875533ms)","trace[1177735786] 'compare' (duration: 208.284833ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:39.766744Z","caller":"traceutil/trace.go:171","msg":"trace[1498331794] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"228.070453ms","start":"2026-02-18T14:40:39.538661Z","end":"2026-02-18T14:40:39.766732Z","steps":["trace[1498331794] 'read index received' (duration: 19.366606ms)","trace[1498331794] 'applied index is now lower than readState.Index' (duration: 208.702527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:39.766771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:39.262202Z","time spent":"504.548298ms","remote":"127.0.0.1:56280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:40:39.766817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.444768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:39.766836Z","caller":"traceutil/trace.go:171","msg":"trace[709678981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"553.47594ms","start":"2026-02-18T14:40:39.213353Z","end":"2026-02-18T14:40:39.766829Z","steps":["trace[709678981] 'agreement among raft nodes before linearized reading' (duration: 553.422948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:39.766855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:39.213348Z","time spent":"553.503061ms","remote":"127.0.0.1:56260","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:40:40.053693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.0907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:40.053771Z","caller":"traceutil/trace.go:171","msg":"trace[1763162593] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"281.228679ms","start":"2026-02-18T14:40:39.77253Z","end":"2026-02-18T14:40:40.053759Z","steps":["trace[1763162593] 'read index received' (duration: 105.974953ms)","trace[1763162593] 'applied index is now lower than readState.Index' (duration: 175.252765ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:40.053831Z","caller":"traceutil/trace.go:171","msg":"trace[101424041] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"281.458715ms","start":"2026-02-18T14:40:39.77236Z","end":"2026-02-18T14:40:40.053819Z","steps":["trace[101424041] 'process raft request' (duration: 106.185409ms)","trace[101424041] 'compare' (duration: 175.010918ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:40.053846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.312061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-18T14:40:40.053865Z","caller":"traceutil/trace.go:171","msg":"trace[47401420] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:541; }","duration":"281.348092ms","start":"2026-02-18T14:40:39.77251Z","end":"2026-02-18T14:40:40.053858Z","steps":["trace[47401420] 'agreement among raft nodes before linearized reading' (duration: 281.28021ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:41.704603Z","caller":"traceutil/trace.go:171","msg":"trace[107179582] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"153.705741ms","start":"2026-02-18T14:40:41.550883Z","end":"2026-02-18T14:40:41.704588Z","steps":["trace[107179582] 'process raft request' (duration: 153.653599ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:41.704818Z","caller":"traceutil/trace.go:171","msg":"trace[293879649] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"156.542064ms","start":"2026-02-18T14:40:41.548271Z","end":"2026-02-18T14:40:41.704813Z","steps":["trace[293879649] 'process raft request' (duration: 156.186352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:42.207438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101182710917042,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:40:42.404489Z","caller":"traceutil/trace.go:171","msg":"trace[1402833429] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"697.952904ms","start":"2026-02-18T14:40:41.706519Z","end":"2026-02-18T14:40:42.404471Z","steps":["trace[1402833429] 'read index received' (duration: 697.808849ms)","trace[1402833429] 'applied index is now lower than readState.Index' (duration: 143.305µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:42.404595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.057577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:42.404629Z","caller":"traceutil/trace.go:171","msg":"trace[798993559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"698.120709ms","start":"2026-02-18T14:40:41.706501Z","end":"2026-02-18T14:40:42.404621Z","steps":["trace[798993559] 'agreement among raft nodes before linearized reading' (duration: 698.036036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:42.404652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:41.706492Z","time spent":"698.15421ms","remote":"127.0.0.1:56266","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:40:42.405558Z","caller":"traceutil/trace.go:171","msg":"trace[1253187710] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"758.543733ms","start":"2026-02-18T14:40:41.647005Z","end":"2026-02-18T14:40:42.405549Z","steps":["trace[1253187710] 'process raft request' (duration: 757.369465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:42.405629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:41.646994Z","time spent":"758.583554ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:44.714351Z","caller":"traceutil/trace.go:171","msg":"trace[650118769] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"141.097485ms","start":"2026-02-18T14:40:44.573227Z","end":"2026-02-18T14:40:44.714324Z","steps":["trace[650118769] 'read index received' (duration: 140.852778ms)","trace[650118769] 'applied index is now lower than readState.Index' (duration: 243.927µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:44.714429Z","caller":"traceutil/trace.go:171","msg":"trace[1022348752] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"141.57862ms","start":"2026-02-18T14:40:44.572834Z","end":"2026-02-18T14:40:44.714412Z","steps":["trace[1022348752] 'process raft request' (duration: 141.295761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:44.714764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.442736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:44.714816Z","caller":"traceutil/trace.go:171","msg":"trace[1047449369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:565; }","duration":"141.595151ms","start":"2026-02-18T14:40:44.573205Z","end":"2026-02-18T14:40:44.7148Z","steps":["trace[1047449369] 'agreement among raft nodes before linearized reading' (duration: 141.25189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:45.226452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101182710917074,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:40:45.727561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101182710917074,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:40:46.037371Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.316116229s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:40:46.088547Z","caller":"traceutil/trace.go:171","msg":"trace[418085841] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"1.362955974s","start":"2026-02-18T14:40:44.725574Z","end":"2026-02-18T14:40:46.08853Z","steps":["trace[418085841] 'read index received' (duration: 1.311910746s)","trace[418085841] 'applied index is now lower than readState.Index' (duration: 51.044658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:46.088704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.425404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdw7n\" ","response":"range_response_count:1 size:17411"} {"level":"warn","ts":"2026-02-18T14:40:46.088686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363088018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:40:46.08872Z","caller":"traceutil/trace.go:171","msg":"trace[120327800] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdw7n; range_end:; response_count:1; response_revision:566; }","duration":"857.475166ms","start":"2026-02-18T14:40:45.231239Z","end":"2026-02-18T14:40:46.088714Z","steps":["trace[120327800] 'agreement among raft nodes before linearized reading' (duration: 857.371872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.088736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:45.231219Z","time spent":"857.513637ms","remote":"127.0.0.1:56438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17434,"request content":"key:\"/registry/pods/kube-system/cilium-hdw7n\" "} {"level":"info","ts":"2026-02-18T14:40:46.088757Z","caller":"traceutil/trace.go:171","msg":"trace[522712073] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:566; }","duration":"1.363175891s","start":"2026-02-18T14:40:44.725545Z","end":"2026-02-18T14:40:46.088721Z","steps":["trace[522712073] 'agreement among raft nodes before linearized reading' (duration: 1.363060667s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.088793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:44.72553Z","time spent":"1.363253553s","remote":"127.0.0.1:56712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:40:46.088848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.416591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T14:40:46.088868Z","caller":"traceutil/trace.go:171","msg":"trace[934767317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"371.463571ms","start":"2026-02-18T14:40:45.7174Z","end":"2026-02-18T14:40:46.088863Z","steps":["trace[934767317] 'agreement among raft nodes before linearized reading' (duration: 371.43233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.08888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:45.717386Z","time spent":"371.491763ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:40:46.08894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.69136ms","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-18T14:40:46.088953Z","caller":"traceutil/trace.go:171","msg":"trace[78799421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"372.737082ms","start":"2026-02-18T14:40:45.716213Z","end":"2026-02-18T14:40:46.08895Z","steps":["trace[78799421] 'agreement among raft nodes before linearized reading' (duration: 372.713971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.088964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:45.716197Z","time spent":"372.764352ms","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:40:46.089016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.175185ms","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-18T14:40:46.089027Z","caller":"traceutil/trace.go:171","msg":"trace[686939651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"437.211036ms","start":"2026-02-18T14:40:45.651812Z","end":"2026-02-18T14:40:46.089023Z","steps":["trace[686939651] 'agreement among raft nodes before linearized reading' (duration: 437.191936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.089039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:45.651799Z","time spent":"437.235947ms","remote":"127.0.0.1:56526","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-18T14:40:46.089042Z","caller":"traceutil/trace.go:171","msg":"trace[1020621411] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.367828558s","start":"2026-02-18T14:40:44.721183Z","end":"2026-02-18T14:40:46.089012Z","steps":["trace[1020621411] 'process raft request' (duration: 1.316358097s)","trace[1020621411] 'compare' (duration: 50.819451ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:46.089088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.40706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.089099Z","caller":"traceutil/trace.go:171","msg":"trace[744715484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"471.447671ms","start":"2026-02-18T14:40:45.617648Z","end":"2026-02-18T14:40:46.089096Z","steps":["trace[744715484] 'agreement among raft nodes before linearized reading' (duration: 471.430061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.08911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:45.617632Z","time spent":"471.474642ms","remote":"127.0.0.1:56260","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:40:46.089112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:44.721171Z","time spent":"1.36790121s","remote":"127.0.0.1:56526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:40:46.302747Z","caller":"traceutil/trace.go:171","msg":"trace[1071779464] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:588; }","duration":"119.838588ms","start":"2026-02-18T14:40:46.182894Z","end":"2026-02-18T14:40:46.302733Z","steps":["trace[1071779464] 'read index received' (duration: 119.617321ms)","trace[1071779464] 'applied index is now lower than readState.Index' (duration: 220.467µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:46.302908Z","caller":"traceutil/trace.go:171","msg":"trace[1400121508] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"193.642883ms","start":"2026-02-18T14:40:46.109258Z","end":"2026-02-18T14:40:46.302901Z","steps":["trace[1400121508] 'process raft request' (duration: 193.300412ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.303031Z","caller":"traceutil/trace.go:171","msg":"trace[310241769] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"190.830775ms","start":"2026-02-18T14:40:46.112192Z","end":"2026-02-18T14:40:46.303023Z","steps":["trace[310241769] 'process raft request' (duration: 190.456023ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.303179Z","caller":"traceutil/trace.go:171","msg":"trace[1568766842] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"180.921466ms","start":"2026-02-18T14:40:46.122251Z","end":"2026-02-18T14:40:46.303173Z","steps":["trace[1568766842] 'process raft request' (duration: 180.424751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.303295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.530508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.303327Z","caller":"traceutil/trace.go:171","msg":"trace[587823683] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"155.584739ms","start":"2026-02-18T14:40:46.147735Z","end":"2026-02-18T14:40:46.30332Z","steps":["trace[587823683] 'agreement among raft nodes before linearized reading' (duration: 155.526677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.303427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.892632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.303445Z","caller":"traceutil/trace.go:171","msg":"trace[702345] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"158.922803ms","start":"2026-02-18T14:40:46.144517Z","end":"2026-02-18T14:40:46.30344Z","steps":["trace[702345] 'agreement among raft nodes before linearized reading' (duration: 158.894442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.30354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.917835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.303565Z","caller":"traceutil/trace.go:171","msg":"trace[1200597228] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"160.965446ms","start":"2026-02-18T14:40:46.142593Z","end":"2026-02-18T14:40:46.303559Z","steps":["trace[1200597228] 'agreement among raft nodes before linearized reading' (duration: 160.929985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.303656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.752044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.303679Z","caller":"traceutil/trace.go:171","msg":"trace[235105547] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"173.786225ms","start":"2026-02-18T14:40:46.129886Z","end":"2026-02-18T14:40:46.303673Z","steps":["trace[235105547] 'agreement among raft nodes before linearized reading' (duration: 173.752774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.303773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.806317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.303798Z","caller":"traceutil/trace.go:171","msg":"trace[405104293] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"174.842158ms","start":"2026-02-18T14:40:46.128949Z","end":"2026-02-18T14:40:46.303791Z","steps":["trace[405104293] 'agreement among raft nodes before linearized reading' (duration: 174.806226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.303904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.83357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.303932Z","caller":"traceutil/trace.go:171","msg":"trace[1951338744] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"176.871131ms","start":"2026-02-18T14:40:46.127052Z","end":"2026-02-18T14:40:46.303923Z","steps":["trace[1951338744] 'agreement among raft nodes before linearized reading' (duration: 176.830879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.304028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.382897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.304052Z","caller":"traceutil/trace.go:171","msg":"trace[783501117] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"177.416967ms","start":"2026-02-18T14:40:46.126628Z","end":"2026-02-18T14:40:46.304045Z","steps":["trace[783501117] 'agreement among raft nodes before linearized reading' (duration: 177.382086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.304147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.43114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.30417Z","caller":"traceutil/trace.go:171","msg":"trace[1339014726] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"179.465461ms","start":"2026-02-18T14:40:46.124698Z","end":"2026-02-18T14:40:46.304164Z","steps":["trace[1339014726] 'agreement among raft nodes before linearized reading' (duration: 179.43004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.304272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.02501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.304296Z","caller":"traceutil/trace.go:171","msg":"trace[161601016] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"181.065961ms","start":"2026-02-18T14:40:46.123224Z","end":"2026-02-18T14:40:46.30429Z","steps":["trace[161601016] 'agreement among raft nodes before linearized reading' (duration: 181.02726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.304399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.755416ms","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:40:46.304423Z","caller":"traceutil/trace.go:171","msg":"trace[1231553691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:570; }","duration":"193.792797ms","start":"2026-02-18T14:40:46.110624Z","end":"2026-02-18T14:40:46.304417Z","steps":["trace[1231553691] 'agreement among raft nodes before linearized reading' (duration: 193.748786ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.46317Z","caller":"traceutil/trace.go:171","msg":"trace[686719694] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"141.209012ms","start":"2026-02-18T14:40:46.321941Z","end":"2026-02-18T14:40:46.46315Z","steps":["trace[686719694] 'process raft request' (duration: 141.084288ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.473824Z","caller":"traceutil/trace.go:171","msg":"trace[1610830772] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"149.351805ms","start":"2026-02-18T14:40:46.324459Z","end":"2026-02-18T14:40:46.473811Z","steps":["trace[1610830772] 'read index received' (duration: 139.179459ms)","trace[1610830772] 'applied index is now lower than readState.Index' (duration: 10.171736ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:46.473974Z","caller":"traceutil/trace.go:171","msg":"trace[1661936584] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"149.912812ms","start":"2026-02-18T14:40:46.324055Z","end":"2026-02-18T14:40:46.473968Z","steps":["trace[1661936584] 'process raft request' (duration: 149.654674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.47416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.688345ms","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-18T14:40:46.474203Z","caller":"traceutil/trace.go:171","msg":"trace[794562489] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"149.739686ms","start":"2026-02-18T14:40:46.324441Z","end":"2026-02-18T14:40:46.474181Z","steps":["trace[794562489] 'agreement among raft nodes before linearized reading' (duration: 149.690675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.793065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.361321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:46.793283Z","caller":"traceutil/trace.go:171","msg":"trace[789474227] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"265.587431ms","start":"2026-02-18T14:40:46.527682Z","end":"2026-02-18T14:40:46.793269Z","steps":["trace[789474227] 'process raft request' (duration: 265.470407ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.793432Z","caller":"traceutil/trace.go:171","msg":"trace[960731460] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"321.079726ms","start":"2026-02-18T14:40:46.472344Z","end":"2026-02-18T14:40:46.793424Z","steps":["trace[960731460] 'process raft request' (duration: 148.291522ms)","trace[960731460] 'compare' (duration: 172.043551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:46.793486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.472311Z","time spent":"321.138728ms","remote":"127.0.0.1:56732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:46.793582Z","caller":"traceutil/trace.go:171","msg":"trace[149019243] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"319.664762ms","start":"2026-02-18T14:40:46.473868Z","end":"2026-02-18T14:40:46.793533Z","steps":["trace[149019243] 'read index received' (duration: 146.740774ms)","trace[149019243] 'applied index is now lower than readState.Index' (duration: 172.922298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:46.793777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.060601ms","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:40:46.793808Z","caller":"traceutil/trace.go:171","msg":"trace[121239443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"326.113953ms","start":"2026-02-18T14:40:46.467685Z","end":"2026-02-18T14:40:46.793799Z","steps":["trace[121239443] 'agreement among raft nodes before linearized reading' (duration: 326.038761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.793834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.467674Z","time spent":"326.155064ms","remote":"127.0.0.1:56526","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:40:46.794004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.850737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hdw7n\" ","response":"range_response_count:1 size:17573"} {"level":"info","ts":"2026-02-18T14:40:46.79544Z","caller":"traceutil/trace.go:171","msg":"trace[1596851615] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hdw7n; range_end:; response_count:1; response_revision:575; }","duration":"329.201589ms","start":"2026-02-18T14:40:46.466101Z","end":"2026-02-18T14:40:46.795302Z","steps":["trace[1596851615] 'agreement among raft nodes before linearized reading' (duration: 327.731523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.796025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.466086Z","time spent":"329.401355ms","remote":"127.0.0.1:56438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17596,"request content":"key:\"/registry/pods/kube-system/cilium-hdw7n\" "} {"level":"info","ts":"2026-02-18T14:40:46.972181Z","caller":"traceutil/trace.go:171","msg":"trace[1244177228] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"178.47485ms","start":"2026-02-18T14:40:46.793662Z","end":"2026-02-18T14:40:46.972137Z","steps":["trace[1244177228] 'read index received' (duration: 125.685988ms)","trace[1244177228] 'applied index is now lower than readState.Index' (duration: 52.788032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:46.97229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.393029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:46.972312Z","caller":"traceutil/trace.go:171","msg":"trace[1616365510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"352.467111ms","start":"2026-02-18T14:40:46.619839Z","end":"2026-02-18T14:40:46.972306Z","steps":["trace[1616365510] 'agreement among raft nodes before linearized reading' (duration: 352.389909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.972333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.6198Z","time spent":"352.529474ms","remote":"127.0.0.1:56260","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:40:46.972547Z","caller":"traceutil/trace.go:171","msg":"trace[1560020173] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"245.458973ms","start":"2026-02-18T14:40:46.727081Z","end":"2026-02-18T14:40:46.97254Z","steps":["trace[1560020173] 'process raft request' (duration: 192.256828ms)","trace[1560020173] 'compare' (duration: 52.69787ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:46.974605Z","caller":"traceutil/trace.go:171","msg":"trace[2114256942] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"152.962337ms","start":"2026-02-18T14:40:46.821637Z","end":"2026-02-18T14:40:46.974599Z","steps":["trace[2114256942] 'process raft request' (duration: 152.940186ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.974753Z","caller":"traceutil/trace.go:171","msg":"trace[1062659636] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"158.958723ms","start":"2026-02-18T14:40:46.815788Z","end":"2026-02-18T14:40:46.974747Z","steps":["trace[1062659636] 'process raft request' (duration: 158.682405ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.974835Z","caller":"traceutil/trace.go:171","msg":"trace[485452451] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"158.934773ms","start":"2026-02-18T14:40:46.815897Z","end":"2026-02-18T14:40:46.974831Z","steps":["trace[485452451] 'process raft request' (duration: 158.613143ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.974933Z","caller":"traceutil/trace.go:171","msg":"trace[496284251] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"156.156766ms","start":"2026-02-18T14:40:46.818773Z","end":"2026-02-18T14:40:46.974929Z","steps":["trace[496284251] 'process raft request' (duration: 155.754073ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.975072Z","caller":"traceutil/trace.go:171","msg":"trace[1413923985] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"156.049313ms","start":"2026-02-18T14:40:46.819016Z","end":"2026-02-18T14:40:46.975065Z","steps":["trace[1413923985] 'process raft request' (duration: 155.536337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.41119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.504132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:40:47.411523Z","caller":"traceutil/trace.go:171","msg":"trace[1743148327] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"369.656796ms","start":"2026-02-18T14:40:47.04185Z","end":"2026-02-18T14:40:47.411507Z","steps":["trace[1743148327] 'process raft request' (duration: 369.615144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.41159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:47.041823Z","time spent":"369.742028ms","remote":"127.0.0.1:56336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:40:47.4119Z","caller":"traceutil/trace.go:171","msg":"trace[1684160336] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"484.354033ms","start":"2026-02-18T14:40:46.927533Z","end":"2026-02-18T14:40:47.411887Z","steps":["trace[1684160336] 'process raft request' (duration: 163.093678ms)","trace[1684160336] 'compare' (duration: 320.258475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:47.411961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.927387Z","time spent":"484.549379ms","remote":"127.0.0.1:56304","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-18T14:40:47.412083Z","caller":"traceutil/trace.go:171","msg":"trace[2116717938] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"426.052439ms","start":"2026-02-18T14:40:46.986021Z","end":"2026-02-18T14:40:47.412074Z","steps":["trace[2116717938] 'process raft request' (duration: 425.263895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.412147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.986005Z","time spent":"426.105621ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:47.412266Z","caller":"traceutil/trace.go:171","msg":"trace[1080947434] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"418.008109ms","start":"2026-02-18T14:40:46.99425Z","end":"2026-02-18T14:40:47.412258Z","steps":["trace[1080947434] 'process raft request' (duration: 417.170083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.412335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.994234Z","time spent":"418.059011ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:47.422829Z","caller":"traceutil/trace.go:171","msg":"trace[901044794] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:602; }","duration":"232.479741ms","start":"2026-02-18T14:40:47.190337Z","end":"2026-02-18T14:40:47.422816Z","steps":["trace[901044794] 'read index received' (duration: 59.894895ms)","trace[901044794] 'applied index is now lower than readState.Index' (duration: 172.584376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:47.422924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.575714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T14:40:47.422945Z","caller":"traceutil/trace.go:171","msg":"trace[13415118] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:586; }","duration":"232.693688ms","start":"2026-02-18T14:40:47.190245Z","end":"2026-02-18T14:40:47.422939Z","steps":["trace[13415118] 'agreement among raft nodes before linearized reading' (duration: 232.615995ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:47.423065Z","caller":"traceutil/trace.go:171","msg":"trace[660769468] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"296.250096ms","start":"2026-02-18T14:40:47.12681Z","end":"2026-02-18T14:40:47.42306Z","steps":["trace[660769468] 'process raft request' (duration: 291.177229ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:47.425415Z","caller":"traceutil/trace.go:171","msg":"trace[1121063777] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"100.191056ms","start":"2026-02-18T14:40:47.325214Z","end":"2026-02-18T14:40:47.425405Z","steps":["trace[1121063777] 'process raft request' (duration: 100.035261ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:47.859082Z","caller":"traceutil/trace.go:171","msg":"trace[784796586] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"132.361037ms","start":"2026-02-18T14:40:47.726694Z","end":"2026-02-18T14:40:47.859055Z","steps":["trace[784796586] 'process raft request' (duration: 132.13202ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:48.139775Z","caller":"traceutil/trace.go:171","msg":"trace[271714259] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"271.677949ms","start":"2026-02-18T14:40:47.868079Z","end":"2026-02-18T14:40:48.139757Z","steps":["trace[271714259] 'process raft request' (duration: 271.592436ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:48.281214Z","caller":"traceutil/trace.go:171","msg":"trace[1925579822] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"316.481545ms","start":"2026-02-18T14:40:47.964717Z","end":"2026-02-18T14:40:48.281198Z","steps":["trace[1925579822] 'read index received' (duration: 175.666751ms)","trace[1925579822] 'applied index is now lower than readState.Index' (duration: 140.814224ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:48.281281Z","caller":"traceutil/trace.go:171","msg":"trace[160798738] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"326.523596ms","start":"2026-02-18T14:40:47.954752Z","end":"2026-02-18T14:40:48.281276Z","steps":["trace[160798738] 'process raft request' (duration: 311.443706ms)","trace[160798738] 'compare' (duration: 14.828762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:48.28134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:47.954734Z","time spent":"326.562886ms","remote":"127.0.0.1:56304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:40:48.281441Z","caller":"traceutil/trace.go:171","msg":"trace[1507544423] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"151.677266ms","start":"2026-02-18T14:40:48.129741Z","end":"2026-02-18T14:40:48.281419Z","steps":["trace[1507544423] 'process raft request' (duration: 151.424308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:48.281563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.840707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:40:48.281584Z","caller":"traceutil/trace.go:171","msg":"trace[140982737] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:608; }","duration":"316.95791ms","start":"2026-02-18T14:40:47.96462Z","end":"2026-02-18T14:40:48.281578Z","steps":["trace[140982737] 'agreement among raft nodes before linearized reading' (duration: 316.901888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:48.2816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:47.96456Z","time spent":"317.036623ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:40:48.281899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.447886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:40:48.281916Z","caller":"traceutil/trace.go:171","msg":"trace[565205642] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:609; }","duration":"263.498177ms","start":"2026-02-18T14:40:48.018413Z","end":"2026-02-18T14:40:48.281911Z","steps":["trace[565205642] 'agreement among raft nodes before linearized reading' (duration: 263.445896ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:48.28199Z","caller":"traceutil/trace.go:171","msg":"trace[822698781] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"136.376429ms","start":"2026-02-18T14:40:48.145609Z","end":"2026-02-18T14:40:48.281986Z","steps":["trace[822698781] 'process raft request' (duration: 136.213174ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:56.802733Z","caller":"traceutil/trace.go:171","msg":"trace[376634223] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"116.266332ms","start":"2026-02-18T14:40:56.686448Z","end":"2026-02-18T14:40:56.802714Z","steps":["trace[376634223] 'process raft request' (duration: 86.286403ms)","trace[376634223] 'compare' (duration: 29.885216ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:56.804661Z","caller":"traceutil/trace.go:171","msg":"trace[1865253609] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"112.064543ms","start":"2026-02-18T14:40:56.692588Z","end":"2026-02-18T14:40:56.804652Z","steps":["trace[1865253609] 'process raft request' (duration: 112.007731ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:03.833122Z","caller":"traceutil/trace.go:171","msg":"trace[151004887] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"125.848778ms","start":"2026-02-18T14:41:03.707257Z","end":"2026-02-18T14:41:03.833106Z","steps":["trace[151004887] 'process raft request' (duration: 125.734615ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:06.49095Z","caller":"traceutil/trace.go:171","msg":"trace[312018068] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"100.368132ms","start":"2026-02-18T14:41:06.390566Z","end":"2026-02-18T14:41:06.490934Z","steps":["trace[312018068] 'process raft request' (duration: 100.235229ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:06.624086Z","caller":"traceutil/trace.go:171","msg":"trace[309044791] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"125.100154ms","start":"2026-02-18T14:41:06.498969Z","end":"2026-02-18T14:41:06.62407Z","steps":["trace[309044791] 'process raft request' (duration: 124.836867ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:11.004958Z","caller":"traceutil/trace.go:171","msg":"trace[340826688] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"101.684049ms","start":"2026-02-18T14:41:10.903257Z","end":"2026-02-18T14:41:11.004941Z","steps":["trace[340826688] 'process raft request' (duration: 101.580707ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:30.964715Z","caller":"traceutil/trace.go:171","msg":"trace[1689219230] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"108.006175ms","start":"2026-02-18T14:41:30.856683Z","end":"2026-02-18T14:41:30.96469Z","steps":["trace[1689219230] 'process raft request' (duration: 107.658678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:31.229084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.496051ms","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-18T14:41:31.229163Z","caller":"traceutil/trace.go:171","msg":"trace[690093544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"116.644464ms","start":"2026-02-18T14:41:31.112502Z","end":"2026-02-18T14:41:31.229146Z","steps":["trace[690093544] 'range keys from in-memory index tree' (duration: 116.251055ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:34.875003Z","caller":"traceutil/trace.go:171","msg":"trace[72956163] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"102.336113ms","start":"2026-02-18T14:41:34.772648Z","end":"2026-02-18T14:41:34.874984Z","steps":["trace[72956163] 'process raft request' (duration: 102.205631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:56.211262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.139575ms","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:41:56.211342Z","caller":"traceutil/trace.go:171","msg":"trace[1376248512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"615.251916ms","start":"2026-02-18T14:41:55.596073Z","end":"2026-02-18T14:41:56.211325Z","steps":["trace[1376248512] 'range keys from in-memory index tree' (duration: 614.991612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:56.212477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:55.596058Z","time spent":"616.176673ms","remote":"127.0.0.1:56526","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":"info","ts":"2026-02-18T14:42:05.310467Z","caller":"traceutil/trace.go:171","msg":"trace[1751732112] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"249.153343ms","start":"2026-02-18T14:42:05.061274Z","end":"2026-02-18T14:42:05.310427Z","steps":["trace[1751732112] 'read index received' (duration: 248.920649ms)","trace[1751732112] 'applied index is now lower than readState.Index' (duration: 231.714µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:42:05.310758Z","caller":"traceutil/trace.go:171","msg":"trace[870455171] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"600.227861ms","start":"2026-02-18T14:42:04.710453Z","end":"2026-02-18T14:42:05.31068Z","steps":["trace[870455171] 'process raft request' (duration: 599.836064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:05.310911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:04.710431Z","time spent":"600.396584ms","remote":"127.0.0.1:56526","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-18T14:42:05.311204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.941726ms","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:42:05.311236Z","caller":"traceutil/trace.go:171","msg":"trace[519079869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"250.035428ms","start":"2026-02-18T14:42:05.061193Z","end":"2026-02-18T14:42:05.311228Z","steps":["trace[519079869] 'agreement among raft nodes before linearized reading' (duration: 249.920976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:05.311562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.561311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:42:05.311591Z","caller":"traceutil/trace.go:171","msg":"trace[1008683809] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:964; }","duration":"185.648613ms","start":"2026-02-18T14:42:05.125936Z","end":"2026-02-18T14:42:05.311584Z","steps":["trace[1008683809] 'agreement among raft nodes before linearized reading' (duration: 185.602012ms)"],"step_count":1}