{"level":"warn","ts":"2026-02-18T05:19:28.994627Z","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-18T05:19:28.994974Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-18T05:19:28.995113Z","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-18T05:19:28.995147Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-18T05:19:28.995197Z","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-18T05:19:28.996109Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-02-18T05:19:28.996229Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-18T05:19:29.030509Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"33.926712ms"} {"level":"info","ts":"2026-02-18T05:19:29.160243Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-02-18T05:19:29.160369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T05:19:29.160447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-02-18T05:19:29.160465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T05:19:29.160485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-02-18T05:19:29.160541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-02-18T05:19:29.351727Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T05:19:29.356327Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T05:19:29.359417Z","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-18T05:19:29.445035Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T05:19:29.45984Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T05:19:29.460296Z","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-18T05:19:29.460317Z","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-18T05:19:29.460325Z","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-18T05:19:29.46224Z","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-18T05:19:29.462379Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-18T05:19:29.462481Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-18T05:19:29.463577Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T05:19:29.46367Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T05:19:29.475141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-02-18T05:19:29.475247Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-18T05:19:29.961941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T05:19:29.962193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T05:19:29.962234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-02-18T05:19:29.962278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-02-18T05:19:29.962303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-18T05:19:29.962327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-02-18T05:19:29.962361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-18T05:19:29.972422Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T05:19:29.972586Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:19:29.972601Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:19:29.972648Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:29.974923Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T05:19:29.982833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-02-18T05:19:29.97276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T05:19:29.98529Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T05:19:30.045366Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:30.045608Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:30.045668Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:32.825652Z","caller":"traceutil/trace.go:171","msg":"trace[985604679] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"121.956582ms","start":"2026-02-18T05:19:32.703649Z","end":"2026-02-18T05:19:32.825606Z","steps":["trace[985604679] 'read index received' (duration: 61.035642ms)","trace[985604679] 'applied index is now lower than readState.Index' (duration: 60.92003ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:32.826676Z","caller":"traceutil/trace.go:171","msg":"trace[1444480341] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"122.210178ms","start":"2026-02-18T05:19:32.704455Z","end":"2026-02-18T05:19:32.826665Z","steps":["trace[1444480341] 'process raft request' (duration: 122.169887ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:32.827667Z","caller":"traceutil/trace.go:171","msg":"trace[1555603432] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"123.874747ms","start":"2026-02-18T05:19:32.703779Z","end":"2026-02-18T05:19:32.827654Z","steps":["trace[1555603432] 'process raft request' (duration: 122.613397ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:32.828037Z","caller":"traceutil/trace.go:171","msg":"trace[554529805] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"124.148123ms","start":"2026-02-18T05:19:32.703877Z","end":"2026-02-18T05:19:32.828026Z","steps":["trace[554529805] 'process raft request' (duration: 122.559145ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:32.828017Z","caller":"traceutil/trace.go:171","msg":"trace[459104055] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"124.708647ms","start":"2026-02-18T05:19:32.703268Z","end":"2026-02-18T05:19:32.827976Z","steps":["trace[459104055] 'process raft request' (duration: 61.406661ms)","trace[459104055] 'compare' (duration: 60.761976ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:32.828452Z","caller":"traceutil/trace.go:171","msg":"trace[217410116] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"124.389139ms","start":"2026-02-18T05:19:32.704053Z","end":"2026-02-18T05:19:32.828442Z","steps":["trace[217410116] 'process raft request' (duration: 122.462803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:32.828671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.952403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-18T05:19:32.828815Z","caller":"traceutil/trace.go:171","msg":"trace[589444137] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"124.629075ms","start":"2026-02-18T05:19:32.704178Z","end":"2026-02-18T05:19:32.828807Z","steps":["trace[589444137] 'process raft request' (duration: 122.409502ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:32.8288Z","caller":"traceutil/trace.go:171","msg":"trace[284891135] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"125.140217ms","start":"2026-02-18T05:19:32.703641Z","end":"2026-02-18T05:19:32.828781Z","steps":["trace[284891135] 'agreement among raft nodes before linearized reading' (duration: 124.738107ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:32.829107Z","caller":"traceutil/trace.go:171","msg":"trace[1217552625] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"124.882401ms","start":"2026-02-18T05:19:32.704217Z","end":"2026-02-18T05:19:32.829099Z","steps":["trace[1217552625] 'process raft request' (duration: 122.387381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:32.829403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.671249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-zjms8\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-18T05:19:32.829518Z","caller":"traceutil/trace.go:171","msg":"trace[1397841664] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-zjms8; range_end:; response_count:1; response_revision:18; }","duration":"125.780233ms","start":"2026-02-18T05:19:32.703697Z","end":"2026-02-18T05:19:32.829477Z","steps":["trace[1397841664] 'agreement among raft nodes before linearized reading' (duration: 125.66289ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:32.828719Z","caller":"traceutil/trace.go:171","msg":"trace[1271946400] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"124.600384ms","start":"2026-02-18T05:19:32.70411Z","end":"2026-02-18T05:19:32.82871Z","steps":["trace[1271946400] 'process raft request' (duration: 122.457374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:32.830544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.075239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-18T05:19:32.831432Z","caller":"traceutil/trace.go:171","msg":"trace[886429865] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"126.98709ms","start":"2026-02-18T05:19:32.704427Z","end":"2026-02-18T05:19:32.831414Z","steps":["trace[886429865] 'agreement among raft nodes before linearized reading' (duration: 126.066799ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:33.138287Z","caller":"traceutil/trace.go:171","msg":"trace[1539107275] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"101.620051ms","start":"2026-02-18T05:19:33.036634Z","end":"2026-02-18T05:19:33.138254Z","steps":["trace[1539107275] 'process raft request' (duration: 101.57817ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:33.139247Z","caller":"traceutil/trace.go:171","msg":"trace[1790695455] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"105.889712ms","start":"2026-02-18T05:19:33.033339Z","end":"2026-02-18T05:19:33.139229Z","steps":["trace[1790695455] 'process raft request' (duration: 69.093253ms)","trace[1790695455] 'compare' (duration: 35.506759ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:33.14057Z","caller":"traceutil/trace.go:171","msg":"trace[153008060] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"105.7643ms","start":"2026-02-18T05:19:33.03479Z","end":"2026-02-18T05:19:33.140555Z","steps":["trace[153008060] 'process raft request' (duration: 103.350463ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:33.442379Z","caller":"traceutil/trace.go:171","msg":"trace[928252224] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"101.935668ms","start":"2026-02-18T05:19:33.340304Z","end":"2026-02-18T05:19:33.44224Z","steps":["trace[928252224] 'process raft request' (duration: 49.217753ms)","trace[928252224] 'compare' (duration: 52.50435ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:33.44361Z","caller":"traceutil/trace.go:171","msg":"trace[1649343166] linearizableReadLoop","detail":"{readStateIndex:65; appliedIndex:64; }","duration":"102.790239ms","start":"2026-02-18T05:19:33.340797Z","end":"2026-02-18T05:19:33.443587Z","steps":["trace[1649343166] 'read index received' (duration: 22.44372ms)","trace[1649343166] 'applied index is now lower than readState.Index' (duration: 80.344419ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:33.444156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.348282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/kube-controller-manager\" ","response":"range_response_count:1 size:1128"} {"level":"info","ts":"2026-02-18T05:19:33.444254Z","caller":"traceutil/trace.go:171","msg":"trace[780571426] range","detail":"{range_begin:/registry/flowschemas/kube-controller-manager; range_end:; response_count:1; response_revision:61; }","duration":"103.457535ms","start":"2026-02-18T05:19:33.340767Z","end":"2026-02-18T05:19:33.444224Z","steps":["trace[780571426] 'agreement among raft nodes before linearized reading' (duration: 102.940303ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:34.407073Z","caller":"traceutil/trace.go:171","msg":"trace[55990769] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"172.758993ms","start":"2026-02-18T05:19:34.234278Z","end":"2026-02-18T05:19:34.407037Z","steps":["trace[55990769] 'process raft request' (duration: 148.116751ms)","trace[55990769] 'compare' (duration: 24.443097ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:34.597939Z","caller":"traceutil/trace.go:171","msg":"trace[2002240285] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"106.828884ms","start":"2026-02-18T05:19:34.491067Z","end":"2026-02-18T05:19:34.597896Z","steps":["trace[2002240285] 'process raft request' (duration: 36.143634ms)","trace[2002240285] 'compare' (duration: 70.501346ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:35.187225Z","caller":"traceutil/trace.go:171","msg":"trace[419879671] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"343.860095ms","start":"2026-02-18T05:19:34.843333Z","end":"2026-02-18T05:19:35.187193Z","steps":["trace[419879671] 'process raft request' (duration: 284.114933ms)","trace[419879671] 'compare' (duration: 59.566578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:35.187788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:34.843311Z","time spent":"343.995699ms","remote":"127.0.0.1:46602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:19:36.175931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.863181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:19:36.176072Z","caller":"traceutil/trace.go:171","msg":"trace[1533095157] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"200.751084ms","start":"2026-02-18T05:19:35.975297Z","end":"2026-02-18T05:19:36.176048Z","steps":["trace[1533095157] 'process raft request' (duration: 72.481513ms)","trace[1533095157] 'compare' (duration: 127.663977ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:36.184992Z","caller":"traceutil/trace.go:171","msg":"trace[1624974305] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"192.020137ms","start":"2026-02-18T05:19:35.992952Z","end":"2026-02-18T05:19:36.184973Z","steps":["trace[1624974305] 'process raft request' (duration: 183.876234ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:36.870674Z","caller":"traceutil/trace.go:171","msg":"trace[1600812740] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:267; }","duration":"111.31461ms","start":"2026-02-18T05:19:36.759319Z","end":"2026-02-18T05:19:36.870634Z","steps":["trace[1600812740] 'read index received' (duration: 106.817474ms)","trace[1600812740] 'applied index is now lower than readState.Index' (duration: 4.496166ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:36.870939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.552666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T05:19:36.871098Z","caller":"traceutil/trace.go:171","msg":"trace[1205559855] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"111.810003ms","start":"2026-02-18T05:19:36.759269Z","end":"2026-02-18T05:19:36.871079Z","steps":["trace[1205559855] 'agreement among raft nodes before linearized reading' (duration: 111.503215ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:36.871215Z","caller":"traceutil/trace.go:171","msg":"trace[1433680628] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"119.418572ms","start":"2026-02-18T05:19:36.751585Z","end":"2026-02-18T05:19:36.871004Z","steps":["trace[1433680628] 'process raft request' (duration: 114.580748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:38.940083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.645624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T05:19:38.940193Z","caller":"traceutil/trace.go:171","msg":"trace[2135166880] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"231.828028ms","start":"2026-02-18T05:19:38.708342Z","end":"2026-02-18T05:19:38.94017Z","steps":["trace[2135166880] 'range keys from in-memory index tree' (duration: 231.511731ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:40.554843Z","caller":"traceutil/trace.go:171","msg":"trace[356105689] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"910.381273ms","start":"2026-02-18T05:19:39.644431Z","end":"2026-02-18T05:19:40.554812Z","steps":["trace[356105689] 'process raft request' (duration: 910.107566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:40.555247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:39.644401Z","time spent":"910.531176ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:40.555772Z","caller":"traceutil/trace.go:171","msg":"trace[2133119090] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"310.61057ms","start":"2026-02-18T05:19:40.245145Z","end":"2026-02-18T05:19:40.555755Z","steps":["trace[2133119090] 'read index received' (duration: 310.520348ms)","trace[2133119090] 'applied index is now lower than readState.Index' (duration: 89.502µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:40.555965Z","caller":"traceutil/trace.go:171","msg":"trace[1943367452] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"895.726426ms","start":"2026-02-18T05:19:39.660228Z","end":"2026-02-18T05:19:40.555954Z","steps":["trace[1943367452] 'process raft request' (duration: 895.46525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:40.55602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:39.660209Z","time spent":"895.780207ms","remote":"127.0.0.1:46454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:19:40.55624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.100102ms","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-18T05:19:40.556268Z","caller":"traceutil/trace.go:171","msg":"trace[2080750232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:331; }","duration":"311.198914ms","start":"2026-02-18T05:19:40.245061Z","end":"2026-02-18T05:19:40.55626Z","steps":["trace[2080750232] 'agreement among raft nodes before linearized reading' (duration: 311.080601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:40.556291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:40.245037Z","time spent":"311.248805ms","remote":"127.0.0.1:46514","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-18T05:19:40.556361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.990049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:19:40.556372Z","caller":"traceutil/trace.go:171","msg":"trace[554297404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"289.04616ms","start":"2026-02-18T05:19:40.267323Z","end":"2026-02-18T05:19:40.556369Z","steps":["trace[554297404] 'agreement among raft nodes before linearized reading' (duration: 289.02163ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:44.773477Z","caller":"traceutil/trace.go:171","msg":"trace[248241230] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"166.893654ms","start":"2026-02-18T05:19:44.606554Z","end":"2026-02-18T05:19:44.773448Z","steps":["trace[248241230] 'process raft request' (duration: 84.720062ms)","trace[248241230] 'compare' (duration: 81.979907ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:45.97266Z","caller":"traceutil/trace.go:171","msg":"trace[792244106] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"169.451645ms","start":"2026-02-18T05:19:45.803186Z","end":"2026-02-18T05:19:45.972638Z","steps":["trace[792244106] 'process raft request' (duration: 169.281351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:46.726725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.273382ms","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-18T05:19:46.726841Z","caller":"traceutil/trace.go:171","msg":"trace[2090111209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"142.416845ms","start":"2026-02-18T05:19:46.584387Z","end":"2026-02-18T05:19:46.726803Z","steps":["trace[2090111209] 'range keys from in-memory index tree' (duration: 142.131908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:46.727065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.302856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:19:46.727094Z","caller":"traceutil/trace.go:171","msg":"trace[1933097421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"567.332437ms","start":"2026-02-18T05:19:46.159752Z","end":"2026-02-18T05:19:46.727085Z","steps":["trace[1933097421] 'range keys from in-memory index tree' (duration: 567.224554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:46.72712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:46.159696Z","time spent":"567.416639ms","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:46.726734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.79849ms","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-18T05:19:46.727391Z","caller":"traceutil/trace.go:171","msg":"trace[1267404606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"120.484317ms","start":"2026-02-18T05:19:46.606897Z","end":"2026-02-18T05:19:46.727381Z","steps":["trace[1267404606] 'range keys from in-memory index tree' (duration: 119.735189ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:53.024517Z","caller":"traceutil/trace.go:171","msg":"trace[824468343] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:464; }","duration":"212.651464ms","start":"2026-02-18T05:19:52.811851Z","end":"2026-02-18T05:19:53.024502Z","steps":["trace[824468343] 'read index received' (duration: 210.089134ms)","trace[824468343] 'applied index is now lower than readState.Index' (duration: 2.56149ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:53.025167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.299659ms","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-18T05:19:53.025406Z","caller":"traceutil/trace.go:171","msg":"trace[1535726730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"213.566476ms","start":"2026-02-18T05:19:52.811824Z","end":"2026-02-18T05:19:53.025391Z","steps":["trace[1535726730] 'agreement among raft nodes before linearized reading' (duration: 213.192528ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:53.026006Z","caller":"traceutil/trace.go:171","msg":"trace[675003451] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"254.461842ms","start":"2026-02-18T05:19:52.771518Z","end":"2026-02-18T05:19:53.02598Z","steps":["trace[675003451] 'process raft request' (duration: 252.905286ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:53.023411Z","caller":"traceutil/trace.go:171","msg":"trace[901412605] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"254.914863ms","start":"2026-02-18T05:19:52.768255Z","end":"2026-02-18T05:19:53.02317Z","steps":["trace[901412605] 'process raft request' (duration: 253.632023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:03.411789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.190732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:03.411883Z","caller":"traceutil/trace.go:171","msg":"trace[832326755] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:490; }","duration":"142.19617ms","start":"2026-02-18T05:20:03.269678Z","end":"2026-02-18T05:20:03.411874Z","steps":["trace[832326755] 'read index received' (duration: 138.625795ms)","trace[832326755] 'applied index is now lower than readState.Index' (duration: 3.569895ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:03.411999Z","caller":"traceutil/trace.go:171","msg":"trace[577880691] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"218.565385ms","start":"2026-02-18T05:20:03.193428Z","end":"2026-02-18T05:20:03.411993Z","steps":["trace[577880691] 'process raft request' (duration: 56.114296ms)","trace[577880691] 'compare' (duration: 162.135221ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:03.412081Z","caller":"traceutil/trace.go:171","msg":"trace[93182282] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"218.260747ms","start":"2026-02-18T05:20:03.193816Z","end":"2026-02-18T05:20:03.412077Z","steps":["trace[93182282] 'process raft request' (duration: 218.036781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:03.412147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.479406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T05:20:03.412161Z","caller":"traceutil/trace.go:171","msg":"trace[2097409024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"142.529308ms","start":"2026-02-18T05:20:03.269627Z","end":"2026-02-18T05:20:03.412156Z","steps":["trace[2097409024] 'agreement among raft nodes before linearized reading' (duration: 142.499937ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:07.055825Z","caller":"traceutil/trace.go:171","msg":"trace[1019687712] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"100.735872ms","start":"2026-02-18T05:20:06.955061Z","end":"2026-02-18T05:20:07.055797Z","steps":["trace[1019687712] 'process raft request' (duration: 100.513457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:07.688527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909670811,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:20:07.989528Z","caller":"traceutil/trace.go:171","msg":"trace[690358175] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"827.452322ms","start":"2026-02-18T05:20:07.162052Z","end":"2026-02-18T05:20:07.989504Z","steps":["trace[690358175] 'process raft request' (duration: 827.321179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:07.989691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.162034Z","time spent":"827.577685ms","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:20:09.233758Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.142813383s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:20:09.234641Z","caller":"traceutil/trace.go:171","msg":"trace[2015298454] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.878890617s","start":"2026-02-18T05:20:07.355638Z","end":"2026-02-18T05:20:09.234529Z","steps":["trace[2015298454] 'process raft request' (duration: 1.878440106s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:09.23482Z","caller":"traceutil/trace.go:171","msg":"trace[610906998] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:557; }","duration":"2.047255936s","start":"2026-02-18T05:20:07.187529Z","end":"2026-02-18T05:20:09.234785Z","steps":["trace[610906998] 'read index received' (duration: 801.934319ms)","trace[610906998] 'applied index is now lower than readState.Index' (duration: 1.245320497s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:09.234845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.355617Z","time spent":"1.879153113s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:09.234942Z","caller":"traceutil/trace.go:171","msg":"trace[480783984] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.678553663s","start":"2026-02-18T05:20:07.55637Z","end":"2026-02-18T05:20:09.234923Z","steps":["trace[480783984] 'process raft request' (duration: 1.677820866s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:09.235029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.55635Z","time spent":"1.678617814s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:09.235615Z","caller":"traceutil/trace.go:171","msg":"trace[476419719] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.477114203s","start":"2026-02-18T05:20:07.758339Z","end":"2026-02-18T05:20:09.235454Z","steps":["trace[476419719] 'process raft request' (duration: 1.475908765s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:09.235653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.75832Z","time spent":"1.477314608s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:09.236397Z","caller":"traceutil/trace.go:171","msg":"trace[729628281] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.27766957s","start":"2026-02-18T05:20:07.958709Z","end":"2026-02-18T05:20:09.236379Z","steps":["trace[729628281] 'process raft request' (duration: 1.275811056s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:09.23646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.958684Z","time spent":"1.277735742s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:20:09.237942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.05040692s","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-18T05:20:09.238Z","caller":"traceutil/trace.go:171","msg":"trace[636949821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"2.050494282s","start":"2026-02-18T05:20:07.187494Z","end":"2026-02-18T05:20:09.237988Z","steps":["trace[636949821] 'agreement among raft nodes before linearized reading' (duration: 2.049283504s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:09.238048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.187481Z","time spent":"2.050536453s","remote":"127.0.0.1:46514","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-18T05:20:09.403736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000186603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T05:20:09.403787Z","caller":"traceutil/trace.go:171","msg":"trace[1292302794] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000288556s","start":"2026-02-18T05:20:07.403485Z","end":"2026-02-18T05:20:09.403774Z","steps":["trace[1292302794] 'agreement among raft nodes before linearized reading' (duration: 2.000210014s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:09.403823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.403468Z","time spent":"2.000349067s","remote":"127.0.0.1:46286","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 05:20:09 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T05:20:09.735715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909670825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:20:10.215369Z","caller":"traceutil/trace.go:171","msg":"trace[920068086] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"2.188578184s","start":"2026-02-18T05:20:08.026758Z","end":"2026-02-18T05:20:10.215336Z","steps":["trace[920068086] 'process raft request' (duration: 2.187912758s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.215527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.026736Z","time spent":"2.188713978s","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:10.220887Z","caller":"traceutil/trace.go:171","msg":"trace[1242889123] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"1.369171542s","start":"2026-02-18T05:20:08.851677Z","end":"2026-02-18T05:20:10.220849Z","steps":["trace[1242889123] 'process raft request' (duration: 1.369129881s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.220973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.851651Z","time spent":"1.369266045s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:10.221018Z","caller":"traceutil/trace.go:171","msg":"trace[1261082952] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.633091318s","start":"2026-02-18T05:20:08.587902Z","end":"2026-02-18T05:20:10.220993Z","steps":["trace[1261082952] 'process raft request' (duration: 1.632576536s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.221128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.587882Z","time spent":"1.63318614s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:10.221527Z","caller":"traceutil/trace.go:171","msg":"trace[171804066] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"2.185547712s","start":"2026-02-18T05:20:08.03597Z","end":"2026-02-18T05:20:10.221517Z","steps":["trace[171804066] 'process raft request' (duration: 2.179053999s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.221586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.03596Z","time spent":"2.185590183s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:10.221944Z","caller":"traceutil/trace.go:171","msg":"trace[663043941] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"1.52185761s","start":"2026-02-18T05:20:08.70008Z","end":"2026-02-18T05:20:10.221938Z","steps":["trace[663043941] 'process raft request' (duration: 1.520654481s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.221983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.700061Z","time spent":"1.521903101s","remote":"127.0.0.1:46442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4407,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:10.245626Z","caller":"traceutil/trace.go:171","msg":"trace[561929316] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:562; }","duration":"1.010761043s","start":"2026-02-18T05:20:09.234849Z","end":"2026-02-18T05:20:10.24561Z","steps":["trace[561929316] 'read index received' (duration: 979.931345ms)","trace[561929316] 'applied index is now lower than readState.Index' (duration: 30.827308ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:10.245856Z","caller":"traceutil/trace.go:171","msg":"trace[951113633] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"897.474535ms","start":"2026-02-18T05:20:09.348374Z","end":"2026-02-18T05:20:10.245849Z","steps":["trace[951113633] 'process raft request' (duration: 897.139937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.245931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.348357Z","time spent":"897.520786ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:10.246052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092054415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-18T05:20:10.246076Z","caller":"traceutil/trace.go:171","msg":"trace[268837259] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"1.092075465s","start":"2026-02-18T05:20:09.153993Z","end":"2026-02-18T05:20:10.246069Z","steps":["trace[268837259] 'agreement among raft nodes before linearized reading' (duration: 1.092035614s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.246097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.153964Z","time spent":"1.092126446s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.246214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291876245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-18T05:20:10.246234Z","caller":"traceutil/trace.go:171","msg":"trace[379131026] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"1.291919037s","start":"2026-02-18T05:20:08.95431Z","end":"2026-02-18T05:20:10.246229Z","steps":["trace[379131026] 'agreement among raft nodes before linearized reading' (duration: 1.291880486s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.246247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.954293Z","time spent":"1.291951287s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.246346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490865177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-18T05:20:10.246364Z","caller":"traceutil/trace.go:171","msg":"trace[164283316] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"1.490908378s","start":"2026-02-18T05:20:08.755451Z","end":"2026-02-18T05:20:10.246359Z","steps":["trace[164283316] 'agreement among raft nodes before linearized reading' (duration: 1.490843047s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.24638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.755435Z","time spent":"1.490942298s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.246462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89176439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-18T05:20:10.24648Z","caller":"traceutil/trace.go:171","msg":"trace[1304225675] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:552; }","duration":"1.891813052s","start":"2026-02-18T05:20:08.354662Z","end":"2026-02-18T05:20:10.246475Z","steps":["trace[1304225675] 'agreement among raft nodes before linearized reading' (duration: 1.891768761s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.246501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.354646Z","time spent":"1.891850822s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.246609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.063722744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:20:10.246628Z","caller":"traceutil/trace.go:171","msg":"trace[1190655638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:552; }","duration":"2.063768335s","start":"2026-02-18T05:20:08.182855Z","end":"2026-02-18T05:20:10.246623Z","steps":["trace[1190655638] 'agreement among raft nodes before linearized reading' (duration: 2.063716584s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.246641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.182841Z","time spent":"2.063797016s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:10.246728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.092559476s","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-18T05:20:10.246749Z","caller":"traceutil/trace.go:171","msg":"trace[591000517] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"2.092604096s","start":"2026-02-18T05:20:08.15414Z","end":"2026-02-18T05:20:10.246744Z","steps":["trace[591000517] 'agreement among raft nodes before linearized reading' (duration: 2.092573185s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.246765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:08.154123Z","time spent":"2.092639408s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.246834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.799549631s","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-18T05:20:10.246851Z","caller":"traceutil/trace.go:171","msg":"trace[1228063545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"2.799577032s","start":"2026-02-18T05:20:07.44727Z","end":"2026-02-18T05:20:10.246847Z","steps":["trace[1228063545] 'agreement among raft nodes before linearized reading' (duration: 2.799546101s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.246864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.44726Z","time spent":"2.799601322s","remote":"127.0.0.1:46514","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-18T05:20:10.246942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.806396973s","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-18T05:20:10.246963Z","caller":"traceutil/trace.go:171","msg":"trace[1875775265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"2.806424423s","start":"2026-02-18T05:20:07.440534Z","end":"2026-02-18T05:20:10.246959Z","steps":["trace[1875775265] 'agreement among raft nodes before linearized reading' (duration: 2.806387912s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.246976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.440532Z","time spent":"2.806440894s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:10.247073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.806547006s","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-18T05:20:10.247093Z","caller":"traceutil/trace.go:171","msg":"trace[939961981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"2.806592087s","start":"2026-02-18T05:20:07.440496Z","end":"2026-02-18T05:20:10.247088Z","steps":["trace[939961981] 'agreement among raft nodes before linearized reading' (duration: 2.806558666s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.247109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:07.440481Z","time spent":"2.806625748s","remote":"127.0.0.1:46514","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-18T05:20:10.423559Z","caller":"traceutil/trace.go:171","msg":"trace[1412578137] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"177.863712ms","start":"2026-02-18T05:20:10.245671Z","end":"2026-02-18T05:20:10.423535Z","steps":["trace[1412578137] 'read index received' (duration: 177.522714ms)","trace[1412578137] 'applied index is now lower than readState.Index' (duration: 339.198µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:10.423735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07039892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-02-18T05:20:10.423992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.444669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:23292"} {"level":"info","ts":"2026-02-18T05:20:10.424059Z","caller":"traceutil/trace.go:171","msg":"trace[856337479] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:553; }","duration":"468.517551ms","start":"2026-02-18T05:20:09.955505Z","end":"2026-02-18T05:20:10.424023Z","steps":["trace[856337479] 'agreement among raft nodes before linearized reading' (duration: 468.295046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.424099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.955483Z","time spent":"468.607723ms","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":23316,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-18T05:20:10.424452Z","caller":"traceutil/trace.go:171","msg":"trace[1420480829] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"183.471495ms","start":"2026-02-18T05:20:10.240885Z","end":"2026-02-18T05:20:10.424356Z","steps":["trace[1420480829] 'process raft request' (duration: 182.356338ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.425118Z","caller":"traceutil/trace.go:171","msg":"trace[71702847] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:553; }","duration":"1.070468133s","start":"2026-02-18T05:20:09.353291Z","end":"2026-02-18T05:20:10.423759Z","steps":["trace[71702847] 'agreement among raft nodes before linearized reading' (duration: 1.07035538s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.425887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.353282Z","time spent":"1.072590293s","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.425318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.267522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:8285"} {"level":"info","ts":"2026-02-18T05:20:10.426251Z","caller":"traceutil/trace.go:171","msg":"trace[897939309] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:553; }","duration":"573.243305ms","start":"2026-02-18T05:20:09.852993Z","end":"2026-02-18T05:20:10.426237Z","steps":["trace[897939309] 'agreement among raft nodes before linearized reading' (duration: 572.15682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.426316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.852967Z","time spent":"573.335728ms","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":8309,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.425437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.653475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:3368"} {"level":"info","ts":"2026-02-18T05:20:10.426632Z","caller":"traceutil/trace.go:171","msg":"trace[2083671693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:553; }","duration":"574.902974ms","start":"2026-02-18T05:20:09.851717Z","end":"2026-02-18T05:20:10.42662Z","steps":["trace[2083671693] 'agreement among raft nodes before linearized reading' (duration: 573.642925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.426778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.851695Z","time spent":"575.069029ms","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":3392,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:10.425473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021480896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:10.427164Z","caller":"traceutil/trace.go:171","msg":"trace[581126443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"1.023177867s","start":"2026-02-18T05:20:09.403973Z","end":"2026-02-18T05:20:10.427151Z","steps":["trace[581126443] 'agreement among raft nodes before linearized reading' (duration: 1.021481117s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.427297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.403966Z","time spent":"1.023233808s","remote":"127.0.0.1:46298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:20:10.437816Z","caller":"traceutil/trace.go:171","msg":"trace[2118956165] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"185.847841ms","start":"2026-02-18T05:20:10.251954Z","end":"2026-02-18T05:20:10.437801Z","steps":["trace[2118956165] 'process raft request' (duration: 185.303629ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.438714Z","caller":"traceutil/trace.go:171","msg":"trace[618924176] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"176.095001ms","start":"2026-02-18T05:20:10.262611Z","end":"2026-02-18T05:20:10.438706Z","steps":["trace[618924176] 'process raft request' (duration: 174.734759ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.443133Z","caller":"traceutil/trace.go:171","msg":"trace[385755317] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"180.431543ms","start":"2026-02-18T05:20:10.262677Z","end":"2026-02-18T05:20:10.443108Z","steps":["trace[385755317] 'process raft request' (duration: 174.700087ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.443404Z","caller":"traceutil/trace.go:171","msg":"trace[2003456266] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"178.153159ms","start":"2026-02-18T05:20:10.265173Z","end":"2026-02-18T05:20:10.443326Z","steps":["trace[2003456266] 'process raft request' (duration: 172.221209ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.443516Z","caller":"traceutil/trace.go:171","msg":"trace[1333438540] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"178.275743ms","start":"2026-02-18T05:20:10.265233Z","end":"2026-02-18T05:20:10.443509Z","steps":["trace[1333438540] 'process raft request' (duration: 172.178588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.445962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.99771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:4595"} {"level":"info","ts":"2026-02-18T05:20:10.446001Z","caller":"traceutil/trace.go:171","msg":"trace[1050817082] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:559; }","duration":"292.071941ms","start":"2026-02-18T05:20:10.153918Z","end":"2026-02-18T05:20:10.44599Z","steps":["trace[1050817082] 'agreement among raft nodes before linearized reading' (duration: 279.568126ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.715025Z","caller":"traceutil/trace.go:171","msg":"trace[938318324] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"105.921243ms","start":"2026-02-18T05:20:10.609087Z","end":"2026-02-18T05:20:10.715008Z","steps":["trace[938318324] 'process raft request' (duration: 105.79961ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.744591Z","caller":"traceutil/trace.go:171","msg":"trace[2001501297] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"130.156445ms","start":"2026-02-18T05:20:10.61442Z","end":"2026-02-18T05:20:10.744577Z","steps":["trace[2001501297] 'process raft request' (duration: 130.059223ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:11.016562Z","caller":"traceutil/trace.go:171","msg":"trace[566336728] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"187.939721ms","start":"2026-02-18T05:20:10.8286Z","end":"2026-02-18T05:20:11.01654Z","steps":["trace[566336728] 'read index received' (duration: 187.340266ms)","trace[566336728] 'applied index is now lower than readState.Index' (duration: 598.665µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:11.016655Z","caller":"traceutil/trace.go:171","msg":"trace[1035203712] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"194.649519ms","start":"2026-02-18T05:20:10.821995Z","end":"2026-02-18T05:20:11.016645Z","steps":["trace[1035203712] 'process raft request' (duration: 194.004164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.017122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.502954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-02-18T05:20:11.017158Z","caller":"traceutil/trace.go:171","msg":"trace[1834466952] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"188.572916ms","start":"2026-02-18T05:20:10.828574Z","end":"2026-02-18T05:20:11.017147Z","steps":["trace[1834466952] 'agreement among raft nodes before linearized reading' (duration: 188.30611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.028168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.135466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-18T05:20:11.028278Z","caller":"traceutil/trace.go:171","msg":"trace[1341959401] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"177.263949ms","start":"2026-02-18T05:20:10.851001Z","end":"2026-02-18T05:20:11.028265Z","steps":["trace[1341959401] 'agreement among raft nodes before linearized reading' (duration: 175.881866ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:11.599711Z","caller":"traceutil/trace.go:171","msg":"trace[1637060621] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"242.942391ms","start":"2026-02-18T05:20:11.356751Z","end":"2026-02-18T05:20:11.599693Z","steps":["trace[1637060621] 'read index received' (duration: 230.817714ms)","trace[1637060621] 'applied index is now lower than readState.Index' (duration: 12.123727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:11.599948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.176727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-02-18T05:20:11.599996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.530031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.600008Z","caller":"traceutil/trace.go:171","msg":"trace[1433661618] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"243.271639ms","start":"2026-02-18T05:20:11.356723Z","end":"2026-02-18T05:20:11.599995Z","steps":["trace[1433661618] 'agreement among raft nodes before linearized reading' (duration: 243.109065ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:11.600023Z","caller":"traceutil/trace.go:171","msg":"trace[1445038925] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:570; }","duration":"231.604173ms","start":"2026-02-18T05:20:11.368411Z","end":"2026-02-18T05:20:11.600015Z","steps":["trace[1445038925] 'agreement among raft nodes before linearized reading' (duration: 231.545872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.600321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.225902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.600341Z","caller":"traceutil/trace.go:171","msg":"trace[634809220] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:570; }","duration":"134.261053ms","start":"2026-02-18T05:20:11.466075Z","end":"2026-02-18T05:20:11.600336Z","steps":["trace[634809220] 'agreement among raft nodes before linearized reading' (duration: 134.231922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.600434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.411616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.60045Z","caller":"traceutil/trace.go:171","msg":"trace[1452232982] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:570; }","duration":"134.441498ms","start":"2026-02-18T05:20:11.466004Z","end":"2026-02-18T05:20:11.600445Z","steps":["trace[1452232982] 'agreement among raft nodes before linearized reading' (duration: 134.415577ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:11.600516Z","caller":"traceutil/trace.go:171","msg":"trace[682022849] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"305.085969ms","start":"2026-02-18T05:20:11.295422Z","end":"2026-02-18T05:20:11.600508Z","steps":["trace[682022849] 'process raft request' (duration: 292.212505ms)","trace[682022849] 'compare' (duration: 11.699826ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:11.600543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.55475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.600564Z","caller":"traceutil/trace.go:171","msg":"trace[658514013] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:570; }","duration":"134.600881ms","start":"2026-02-18T05:20:11.465955Z","end":"2026-02-18T05:20:11.600556Z","steps":["trace[658514013] 'agreement among raft nodes before linearized reading' (duration: 134.56988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.600574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:11.295408Z","time spent":"305.125951ms","remote":"127.0.0.1:46316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:11.60064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.754088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.600656Z","caller":"traceutil/trace.go:171","msg":"trace[378662475] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"146.800029ms","start":"2026-02-18T05:20:11.453852Z","end":"2026-02-18T05:20:11.600652Z","steps":["trace[378662475] 'agreement among raft nodes before linearized reading' (duration: 146.772569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.600738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.177538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.600754Z","caller":"traceutil/trace.go:171","msg":"trace[1492604214] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"147.281851ms","start":"2026-02-18T05:20:11.453468Z","end":"2026-02-18T05:20:11.60075Z","steps":["trace[1492604214] 'agreement among raft nodes before linearized reading' (duration: 147.25581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.600759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.359949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.60078Z","caller":"traceutil/trace.go:171","msg":"trace[1493856103] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"201.393489ms","start":"2026-02-18T05:20:11.399379Z","end":"2026-02-18T05:20:11.600773Z","steps":["trace[1493856103] 'agreement among raft nodes before linearized reading' (duration: 201.361298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.600825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.154014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.600844Z","caller":"traceutil/trace.go:171","msg":"trace[1973996878] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:570; }","duration":"215.171145ms","start":"2026-02-18T05:20:11.385666Z","end":"2026-02-18T05:20:11.600837Z","steps":["trace[1973996878] 'agreement among raft nodes before linearized reading' (duration: 215.144214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.600927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.279698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.600943Z","caller":"traceutil/trace.go:171","msg":"trace[291491421] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:570; }","duration":"215.305608ms","start":"2026-02-18T05:20:11.385632Z","end":"2026-02-18T05:20:11.600938Z","steps":["trace[291491421] 'agreement among raft nodes before linearized reading' (duration: 215.281468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.601012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.39078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.601027Z","caller":"traceutil/trace.go:171","msg":"trace[146960359] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:570; }","duration":"215.40688ms","start":"2026-02-18T05:20:11.385615Z","end":"2026-02-18T05:20:11.601022Z","steps":["trace[146960359] 'agreement among raft nodes before linearized reading' (duration: 215.38363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.601095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.609237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.601111Z","caller":"traceutil/trace.go:171","msg":"trace[733136479] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:570; }","duration":"232.632237ms","start":"2026-02-18T05:20:11.368475Z","end":"2026-02-18T05:20:11.601107Z","steps":["trace[733136479] 'agreement among raft nodes before linearized reading' (duration: 232.605987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.602489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.93294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.602521Z","caller":"traceutil/trace.go:171","msg":"trace[759879962] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"148.97225ms","start":"2026-02-18T05:20:11.453538Z","end":"2026-02-18T05:20:11.60251Z","steps":["trace[759879962] 'agreement among raft nodes before linearized reading' (duration: 148.929689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.602599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.152217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.602612Z","caller":"traceutil/trace.go:171","msg":"trace[1131442269] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"169.179629ms","start":"2026-02-18T05:20:11.433428Z","end":"2026-02-18T05:20:11.602608Z","steps":["trace[1131442269] 'agreement among raft nodes before linearized reading' (duration: 169.157198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.602696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.298581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.602709Z","caller":"traceutil/trace.go:171","msg":"trace[1505836689] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"169.341902ms","start":"2026-02-18T05:20:11.433363Z","end":"2026-02-18T05:20:11.602705Z","steps":["trace[1505836689] 'agreement among raft nodes before linearized reading' (duration: 169.310351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.602761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.840294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.602772Z","caller":"traceutil/trace.go:171","msg":"trace[2136255302] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"169.874154ms","start":"2026-02-18T05:20:11.432895Z","end":"2026-02-18T05:20:11.602769Z","steps":["trace[2136255302] 'agreement among raft nodes before linearized reading' (duration: 169.856004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.602839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.695103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.602861Z","caller":"traceutil/trace.go:171","msg":"trace[1987495558] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"189.733603ms","start":"2026-02-18T05:20:11.413124Z","end":"2026-02-18T05:20:11.602857Z","steps":["trace[1987495558] 'agreement among raft nodes before linearized reading' (duration: 189.704933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.602927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.806106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.602938Z","caller":"traceutil/trace.go:171","msg":"trace[1853399397] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"189.825906ms","start":"2026-02-18T05:20:11.413109Z","end":"2026-02-18T05:20:11.602935Z","steps":["trace[1853399397] 'agreement among raft nodes before linearized reading' (duration: 189.806926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.603034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.98858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.603048Z","caller":"traceutil/trace.go:171","msg":"trace[748531582] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"190.016111ms","start":"2026-02-18T05:20:11.413027Z","end":"2026-02-18T05:20:11.603043Z","steps":["trace[748531582] 'agreement among raft nodes before linearized reading' (duration: 189.99116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.603101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.806229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.603113Z","caller":"traceutil/trace.go:171","msg":"trace[613445177] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"201.818359ms","start":"2026-02-18T05:20:11.401291Z","end":"2026-02-18T05:20:11.603109Z","steps":["trace[613445177] 'agreement among raft nodes before linearized reading' (duration: 201.799599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.603163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.937552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.603174Z","caller":"traceutil/trace.go:171","msg":"trace[525334209] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"203.653683ms","start":"2026-02-18T05:20:11.399517Z","end":"2026-02-18T05:20:11.603171Z","steps":["trace[525334209] 'agreement among raft nodes before linearized reading' (duration: 203.636292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.604001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.526865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:11.604018Z","caller":"traceutil/trace.go:171","msg":"trace[1943349955] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:570; }","duration":"235.551357ms","start":"2026-02-18T05:20:11.36846Z","end":"2026-02-18T05:20:11.604012Z","steps":["trace[1943349955] 'agreement among raft nodes before linearized reading' (duration: 235.525386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:14.699787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.226071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:14.69991Z","caller":"traceutil/trace.go:171","msg":"trace[335109169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"298.362975ms","start":"2026-02-18T05:20:14.401507Z","end":"2026-02-18T05:20:14.69987Z","steps":["trace[335109169] 'range keys from in-memory index tree' (duration: 298.15281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:14.701273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.652282ms","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-18T05:20:14.701711Z","caller":"traceutil/trace.go:171","msg":"trace[128591075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:579; }","duration":"203.067591ms","start":"2026-02-18T05:20:14.498581Z","end":"2026-02-18T05:20:14.701649Z","steps":["trace[128591075] 'range keys from in-memory index tree' (duration: 201.611797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:14.701446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.041302ms","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-18T05:20:14.702136Z","caller":"traceutil/trace.go:171","msg":"trace[1144778045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"209.741208ms","start":"2026-02-18T05:20:14.492382Z","end":"2026-02-18T05:20:14.702123Z","steps":["trace[1144778045] 'range keys from in-memory index tree' (duration: 208.991851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:14.701615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.49334ms","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-18T05:20:14.702693Z","caller":"traceutil/trace.go:171","msg":"trace[584781030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"215.623187ms","start":"2026-02-18T05:20:14.487054Z","end":"2026-02-18T05:20:14.702677Z","steps":["trace[584781030] 'range keys from in-memory index tree' (duration: 214.349627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:14.702767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.25806ms","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-18T05:20:14.702844Z","caller":"traceutil/trace.go:171","msg":"trace[70158655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"210.363973ms","start":"2026-02-18T05:20:14.492461Z","end":"2026-02-18T05:20:14.702825Z","steps":["trace[70158655] 'range keys from in-memory index tree' (duration: 210.169658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:18.366961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.064215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wxl6s\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-18T05:20:18.36703Z","caller":"traceutil/trace.go:171","msg":"trace[523127530] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wxl6s; range_end:; response_count:1; response_revision:592; }","duration":"108.176497ms","start":"2026-02-18T05:20:18.258838Z","end":"2026-02-18T05:20:18.367014Z","steps":["trace[523127530] 'range keys from in-memory index tree' (duration: 107.892961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:19.227954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909670920,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:20:19.523259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.510573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:19.523349Z","caller":"traceutil/trace.go:171","msg":"trace[729438454] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"795.986782ms","start":"2026-02-18T05:20:18.727346Z","end":"2026-02-18T05:20:19.523333Z","steps":["trace[729438454] 'read index received' (duration: 535.258543ms)","trace[729438454] 'applied index is now lower than readState.Index' (duration: 260.727099ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:19.52463Z","caller":"traceutil/trace.go:171","msg":"trace[1112829041] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.116638301s","start":"2026-02-18T05:20:18.407973Z","end":"2026-02-18T05:20:19.524611Z","steps":["trace[1112829041] 'process raft request' (duration: 854.696884ms)","trace[1112829041] 'compare' (duration: 260.299069ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:19.524777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:18.407958Z","time spent":"1.116711274s","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:19.525026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.675242ms","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-18T05:20:19.525058Z","caller":"traceutil/trace.go:171","msg":"trace[1234099826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"797.737923ms","start":"2026-02-18T05:20:18.727308Z","end":"2026-02-18T05:20:19.525046Z","steps":["trace[1234099826] 'agreement among raft nodes before linearized reading' (duration: 797.624231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:19.52509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:18.72729Z","time spent":"797.792714ms","remote":"127.0.0.1:46514","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-18T05:20:19.52513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.3117ms","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-18T05:20:19.525218Z","caller":"traceutil/trace.go:171","msg":"trace[418194998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"796.429982ms","start":"2026-02-18T05:20:18.728772Z","end":"2026-02-18T05:20:19.525202Z","steps":["trace[418194998] 'agreement among raft nodes before linearized reading' (duration: 796.205807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:19.525259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:18.728763Z","time spent":"796.487154ms","remote":"127.0.0.1:46514","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-18T05:20:19.525483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.330985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:19.525518Z","caller":"traceutil/trace.go:171","msg":"trace[1929360539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"126.57739ms","start":"2026-02-18T05:20:19.398929Z","end":"2026-02-18T05:20:19.525507Z","steps":["trace[1929360539] 'agreement among raft nodes before linearized reading' (duration: 126.525749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:19.525556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.951817ms","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-18T05:20:19.525607Z","caller":"traceutil/trace.go:171","msg":"trace[323713229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:594; }","duration":"795.019399ms","start":"2026-02-18T05:20:18.730578Z","end":"2026-02-18T05:20:19.525597Z","steps":["trace[323713229] 'agreement among raft nodes before linearized reading' (duration: 794.906527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:19.525636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:18.73057Z","time spent":"795.05991ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:19.528635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.285902ms","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-18T05:20:19.528699Z","caller":"traceutil/trace.go:171","msg":"trace[1857748591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"794.369774ms","start":"2026-02-18T05:20:18.734317Z","end":"2026-02-18T05:20:19.528686Z","steps":["trace[1857748591] 'agreement among raft nodes before linearized reading' (duration: 791.439775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:19.528733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:18.734307Z","time spent":"794.417165ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:20.576393Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.029777687s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:20:20.621425Z","caller":"traceutil/trace.go:171","msg":"trace[1712476425] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.074893s","start":"2026-02-18T05:20:19.546518Z","end":"2026-02-18T05:20:20.621411Z","steps":["trace[1712476425] 'process raft request' (duration: 1.030110675s)","trace[1712476425] 'compare' (duration: 44.632411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:20.621528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:19.546488Z","time spent":"1.074992031s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:20.624595Z","caller":"traceutil/trace.go:171","msg":"trace[1920613546] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:612; }","duration":"223.421411ms","start":"2026-02-18T05:20:20.401165Z","end":"2026-02-18T05:20:20.624586Z","steps":["trace[1920613546] 'read index received' (duration: 175.471502ms)","trace[1920613546] 'applied index is now lower than readState.Index' (duration: 47.949459ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:20.624708Z","caller":"traceutil/trace.go:171","msg":"trace[558399680] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.074154652s","start":"2026-02-18T05:20:19.550549Z","end":"2026-02-18T05:20:20.624703Z","steps":["trace[558399680] 'process raft request' (duration: 1.073964447s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.624742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:19.550538Z","time spent":"1.074181552s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:20.624815Z","caller":"traceutil/trace.go:171","msg":"trace[487503449] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.074217233s","start":"2026-02-18T05:20:19.55059Z","end":"2026-02-18T05:20:20.624808Z","steps":["trace[487503449] 'process raft request' (duration: 1.073957517s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.624845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:19.550587Z","time spent":"1.074241814s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:20.624901Z","caller":"traceutil/trace.go:171","msg":"trace[1033470923] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.071302554s","start":"2026-02-18T05:20:19.553593Z","end":"2026-02-18T05:20:20.624896Z","steps":["trace[1033470923] 'process raft request' (duration: 1.070970907s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.624926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:19.553573Z","time spent":"1.071337686s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:20.625237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.070196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:20.625256Z","caller":"traceutil/trace.go:171","msg":"trace[1093456152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"224.120988ms","start":"2026-02-18T05:20:20.401129Z","end":"2026-02-18T05:20:20.62525Z","steps":["trace[1093456152] 'agreement among raft nodes before linearized reading' (duration: 224.089317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.625626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.325007ms","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-18T05:20:20.625642Z","caller":"traceutil/trace.go:171","msg":"trace[1396986479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"155.368949ms","start":"2026-02-18T05:20:20.470268Z","end":"2026-02-18T05:20:20.625637Z","steps":["trace[1396986479] 'agreement among raft nodes before linearized reading' (duration: 155.299767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:25.879513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.427436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T05:20:25.879593Z","caller":"traceutil/trace.go:171","msg":"trace[806987597] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:631; }","duration":"219.533839ms","start":"2026-02-18T05:20:25.660027Z","end":"2026-02-18T05:20:25.879561Z","steps":["trace[806987597] 'range keys from in-memory index tree' (duration: 219.232512ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:31.429858Z","caller":"traceutil/trace.go:171","msg":"trace[684242588] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"394.054879ms","start":"2026-02-18T05:20:31.035787Z","end":"2026-02-18T05:20:31.429841Z","steps":["trace[684242588] 'process raft request' (duration: 393.913996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:31.429987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:31.03577Z","time spent":"394.154992ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:32.024569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.261394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:32.024663Z","caller":"traceutil/trace.go:171","msg":"trace[1717172970] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"946.336942ms","start":"2026-02-18T05:20:31.078314Z","end":"2026-02-18T05:20:32.024651Z","steps":["trace[1717172970] 'process raft request' (duration: 390.968535ms)","trace[1717172970] 'compare' (duration: 554.907266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:32.024693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:31.078298Z","time spent":"946.383804ms","remote":"127.0.0.1:53424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:32.024748Z","caller":"traceutil/trace.go:171","msg":"trace[16906691] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"755.467238ms","start":"2026-02-18T05:20:31.269276Z","end":"2026-02-18T05:20:32.024743Z","steps":["trace[16906691] 'read index received' (duration: 160.381907ms)","trace[16906691] 'applied index is now lower than readState.Index' (duration: 595.084561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:32.024797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.52135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:32.024809Z","caller":"traceutil/trace.go:171","msg":"trace[1197849639] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:676; }","duration":"755.580401ms","start":"2026-02-18T05:20:31.269224Z","end":"2026-02-18T05:20:32.024804Z","steps":["trace[1197849639] 'agreement among raft nodes before linearized reading' (duration: 755.53295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:32.024822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:31.269211Z","time spent":"755.607152ms","remote":"127.0.0.1:46544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-02-18T05:20:32.259432Z","caller":"traceutil/trace.go:171","msg":"trace[1165898269] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"221.964866ms","start":"2026-02-18T05:20:32.037454Z","end":"2026-02-18T05:20:32.259419Z","steps":["trace[1165898269] 'process raft request' (duration: 158.470411ms)","trace[1165898269] 'compare' (duration: 63.099876ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:32.259529Z","caller":"traceutil/trace.go:171","msg":"trace[515015510] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"222.040978ms","start":"2026-02-18T05:20:32.037484Z","end":"2026-02-18T05:20:32.259525Z","steps":["trace[515015510] 'process raft request' (duration: 221.749911ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:32.874997Z","caller":"traceutil/trace.go:171","msg":"trace[1003152484] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"197.04866ms","start":"2026-02-18T05:20:32.677935Z","end":"2026-02-18T05:20:32.874984Z","steps":["trace[1003152484] 'read index received' (duration: 196.843545ms)","trace[1003152484] 'applied index is now lower than readState.Index' (duration: 204.535µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:32.875349Z","caller":"traceutil/trace.go:171","msg":"trace[1260508344] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"198.092934ms","start":"2026-02-18T05:20:32.677248Z","end":"2026-02-18T05:20:32.875341Z","steps":["trace[1260508344] 'process raft request' (duration: 197.585132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:32.875588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.641114ms","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-18T05:20:32.875653Z","caller":"traceutil/trace.go:171","msg":"trace[412892174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"197.741536ms","start":"2026-02-18T05:20:32.677906Z","end":"2026-02-18T05:20:32.875647Z","steps":["trace[412892174] 'agreement among raft nodes before linearized reading' (duration: 197.605153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:32.875818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.307429ms","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-18T05:20:32.875865Z","caller":"traceutil/trace.go:171","msg":"trace[117069345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"164.38214ms","start":"2026-02-18T05:20:32.711478Z","end":"2026-02-18T05:20:32.87586Z","steps":["trace[117069345] 'agreement among raft nodes before linearized reading' (duration: 164.294608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:32.876147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.980855ms","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-18T05:20:32.876203Z","caller":"traceutil/trace.go:171","msg":"trace[1892046308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"151.140229ms","start":"2026-02-18T05:20:32.725057Z","end":"2026-02-18T05:20:32.876197Z","steps":["trace[1892046308] 'agreement among raft nodes before linearized reading' (duration: 150.953735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:32.876408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.871632ms","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-18T05:20:32.876469Z","caller":"traceutil/trace.go:171","msg":"trace[489546994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:680; }","duration":"164.946783ms","start":"2026-02-18T05:20:32.711517Z","end":"2026-02-18T05:20:32.876464Z","steps":["trace[489546994] 'agreement among raft nodes before linearized reading' (duration: 164.834761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:33.503349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.615029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:33.503396Z","caller":"traceutil/trace.go:171","msg":"trace[1892518837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"100.704362ms","start":"2026-02-18T05:20:33.40268Z","end":"2026-02-18T05:20:33.503384Z","steps":["trace[1892518837] 'range keys from in-memory index tree' (duration: 100.571728ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:39.040254Z","caller":"traceutil/trace.go:171","msg":"trace[47990798] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"139.760981ms","start":"2026-02-18T05:20:38.900473Z","end":"2026-02-18T05:20:39.040234Z","steps":["trace[47990798] 'read index received' (duration: 139.612087ms)","trace[47990798] 'applied index is now lower than readState.Index' (duration: 148.034µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:39.040564Z","caller":"traceutil/trace.go:171","msg":"trace[1600847792] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"141.696456ms","start":"2026-02-18T05:20:38.898855Z","end":"2026-02-18T05:20:39.040551Z","steps":["trace[1600847792] 'process raft request' (duration: 141.279476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.040804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.311384ms","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-18T05:20:39.040844Z","caller":"traceutil/trace.go:171","msg":"trace[1959103325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:734; }","duration":"140.376376ms","start":"2026-02-18T05:20:38.900456Z","end":"2026-02-18T05:20:39.040833Z","steps":["trace[1959103325] 'agreement among raft nodes before linearized reading' (duration: 140.256953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.041005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.258887ms","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-18T05:20:39.041037Z","caller":"traceutil/trace.go:171","msg":"trace[97717285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:734; }","duration":"130.304788ms","start":"2026-02-18T05:20:38.910724Z","end":"2026-02-18T05:20:39.041029Z","steps":["trace[97717285] 'agreement among raft nodes before linearized reading' (duration: 130.228637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.041407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.074671ms","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-18T05:20:39.041723Z","caller":"traceutil/trace.go:171","msg":"trace[1370239445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"124.339928ms","start":"2026-02-18T05:20:38.917307Z","end":"2026-02-18T05:20:39.041647Z","steps":["trace[1370239445] 'agreement among raft nodes before linearized reading' (duration: 123.99896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.041914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.975541ms","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-18T05:20:39.042244Z","caller":"traceutil/trace.go:171","msg":"trace[979716402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:734; }","duration":"130.318269ms","start":"2026-02-18T05:20:38.911913Z","end":"2026-02-18T05:20:39.042232Z","steps":["trace[979716402] 'agreement among raft nodes before linearized reading' (duration: 129.924209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.899496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909671185,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:20:40.150348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:39.72639Z","time spent":"423.950642ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T05:20:40.150818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.317704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:40.150881Z","caller":"traceutil/trace.go:171","msg":"trace[74451553] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"1.102435917s","start":"2026-02-18T05:20:39.048428Z","end":"2026-02-18T05:20:40.150864Z","steps":["trace[74451553] 'process raft request' (duration: 253.99714ms)","trace[74451553] 'compare' (duration: 848.032018ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:40.150931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:39.048409Z","time spent":"1.102497809s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:40.20743Z","caller":"traceutil/trace.go:171","msg":"trace[1004621468] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:760; }","duration":"808.421905ms","start":"2026-02-18T05:20:39.398995Z","end":"2026-02-18T05:20:40.207417Z","steps":["trace[1004621468] 'read index received' (duration: 808.274251ms)","trace[1004621468] 'applied index is now lower than readState.Index' (duration: 147.044µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:40.207525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.714902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:40.207551Z","caller":"traceutil/trace.go:171","msg":"trace[1085892618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"808.771004ms","start":"2026-02-18T05:20:39.398772Z","end":"2026-02-18T05:20:40.207543Z","steps":["trace[1085892618] 'agreement among raft nodes before linearized reading' (duration: 808.707572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:40.207575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:39.398758Z","time spent":"808.812545ms","remote":"127.0.0.1:46286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:20:40.207757Z","caller":"traceutil/trace.go:171","msg":"trace[1720794506] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"1.157775791s","start":"2026-02-18T05:20:39.049973Z","end":"2026-02-18T05:20:40.207749Z","steps":["trace[1720794506] 'process raft request' (duration: 1.15733123s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:40.207812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:39.049959Z","time spent":"1.157819802s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:40.207914Z","caller":"traceutil/trace.go:171","msg":"trace[2125306402] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.157349281s","start":"2026-02-18T05:20:39.050547Z","end":"2026-02-18T05:20:40.207897Z","steps":["trace[2125306402] 'process raft request' (duration: 1.156826089s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:40.207953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:39.050534Z","time spent":"1.157400342s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:40.208042Z","caller":"traceutil/trace.go:171","msg":"trace[1637439938] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.156582312s","start":"2026-02-18T05:20:39.051449Z","end":"2026-02-18T05:20:40.208031Z","steps":["trace[1637439938] 'process raft request' (duration: 1.155948948s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:40.208079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:39.051415Z","time spent":"1.156646354s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:40.900621Z","caller":"traceutil/trace.go:171","msg":"trace[1715577215] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"139.740531ms","start":"2026-02-18T05:20:40.760858Z","end":"2026-02-18T05:20:40.900599Z","steps":["trace[1715577215] 'process raft request' (duration: 139.594657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:41.548235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909671193,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:20:41.669724Z","caller":"traceutil/trace.go:171","msg":"trace[868753887] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"766.299703ms","start":"2026-02-18T05:20:40.903398Z","end":"2026-02-18T05:20:41.669698Z","steps":["trace[868753887] 'process raft request' (duration: 766.214961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:41.669831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:40.903386Z","time spent":"766.381085ms","remote":"127.0.0.1:46442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4768,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:43.029361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.084433ms","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-18T05:20:43.02945Z","caller":"traceutil/trace.go:171","msg":"trace[384822871] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:740; }","duration":"336.183715ms","start":"2026-02-18T05:20:42.693253Z","end":"2026-02-18T05:20:43.029437Z","steps":["trace[384822871] 'range keys from in-memory index tree' (duration: 336.073473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:43.259898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.808816ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T05:20:43.260038Z","caller":"traceutil/trace.go:171","msg":"trace[1632997513] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"2.21271261s","start":"2026-02-18T05:20:41.047315Z","end":"2026-02-18T05:20:43.260028Z","steps":["trace[1632997513] 'read index received' (duration: 622.782864ms)","trace[1632997513] 'applied index is now lower than readState.Index' (duration: 1.589929136s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.260149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.212828663s","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-18T05:20:43.260164Z","caller":"traceutil/trace.go:171","msg":"trace[1228294895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"2.212869543s","start":"2026-02-18T05:20:41.047288Z","end":"2026-02-18T05:20:43.260158Z","steps":["trace[1228294895] 'agreement among raft nodes before linearized reading' (duration: 2.212799372s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:43.260199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.047277Z","time spent":"2.212915255s","remote":"127.0.0.1:46514","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-18T05:20:43.260327Z","caller":"traceutil/trace.go:171","msg":"trace[500035038] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"1.505394807s","start":"2026-02-18T05:20:41.754916Z","end":"2026-02-18T05:20:43.26031Z","steps":["trace[500035038] 'process raft request' (duration: 1.505054959s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:43.260423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.754892Z","time spent":"1.505481448s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:43.482573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.083217191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:43.482644Z","caller":"traceutil/trace.go:171","msg":"trace[712616821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"2.083340153s","start":"2026-02-18T05:20:41.399285Z","end":"2026-02-18T05:20:43.482625Z","steps":["trace[712616821] 'agreement among raft nodes before linearized reading' (duration: 1.86107753s)","trace[712616821] 'range keys from in-memory index tree' (duration: 222.149731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.482683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.39927Z","time spent":"2.083404946s","remote":"127.0.0.1:46286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:43.482769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.984748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:20:43.482796Z","caller":"traceutil/trace.go:171","msg":"trace[323908247] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:741; }","duration":"744.06175ms","start":"2026-02-18T05:20:42.738724Z","end":"2026-02-18T05:20:43.482785Z","steps":["trace[323908247] 'agreement among raft nodes before linearized reading' (duration: 521.671724ms)","trace[323908247] 'count revisions from in-memory index tree' (duration: 222.357465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.482826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.738704Z","time spent":"744.112351ms","remote":"127.0.0.1:46430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:20:43.483158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269993804s","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-18T05:20:43.483226Z","caller":"traceutil/trace.go:171","msg":"trace[1755734812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"1.270083815s","start":"2026-02-18T05:20:42.213131Z","end":"2026-02-18T05:20:43.483215Z","steps":["trace[1755734812] 'agreement among raft nodes before linearized reading' (duration: 1.047268879s)","trace[1755734812] 'range keys from in-memory index tree' (duration: 222.674013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.483256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.21312Z","time spent":"1.270129276s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:43.483507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271166291s","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-18T05:20:43.483542Z","caller":"traceutil/trace.go:171","msg":"trace[209330986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"1.271216972s","start":"2026-02-18T05:20:42.212315Z","end":"2026-02-18T05:20:43.483532Z","steps":["trace[209330986] 'agreement among raft nodes before linearized reading' (duration: 1.048088928s)","trace[209330986] 'range keys from in-memory index tree' (duration: 223.044402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.483573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.212307Z","time spent":"1.271256633s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:20:43.483812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271512409s","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-18T05:20:43.483843Z","caller":"traceutil/trace.go:171","msg":"trace[1843597929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"1.271596561s","start":"2026-02-18T05:20:42.212236Z","end":"2026-02-18T05:20:43.483833Z","steps":["trace[1843597929] 'agreement among raft nodes before linearized reading' (duration: 1.04817167s)","trace[1843597929] 'range keys from in-memory index tree' (duration: 223.350049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.483913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.212217Z","time spent":"1.271687214s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:43.484298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328688786s","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-18T05:20:43.484333Z","caller":"traceutil/trace.go:171","msg":"trace[581224358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"1.328749757s","start":"2026-02-18T05:20:42.155572Z","end":"2026-02-18T05:20:43.484322Z","steps":["trace[581224358] 'agreement among raft nodes before linearized reading' (duration: 1.104839185s)","trace[581224358] 'range keys from in-memory index tree' (duration: 223.82703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.484362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.155535Z","time spent":"1.328820829s","remote":"127.0.0.1:46514","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-18T05:20:43.484709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475708307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:43.484749Z","caller":"traceutil/trace.go:171","msg":"trace[2114466494] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:741; }","duration":"1.475779289s","start":"2026-02-18T05:20:42.008956Z","end":"2026-02-18T05:20:43.484735Z","steps":["trace[2114466494] 'agreement among raft nodes before linearized reading' (duration: 1.251459987s)","trace[2114466494] 'count revisions from in-memory index tree' (duration: 224.271831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:43.484781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.008938Z","time spent":"1.47583443s","remote":"127.0.0.1:46476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:20:43.486158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.722566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:43.487001Z","caller":"traceutil/trace.go:171","msg":"trace[564403573] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"223.586354ms","start":"2026-02-18T05:20:43.263401Z","end":"2026-02-18T05:20:43.486988Z","steps":["trace[564403573] 'process raft request' (duration: 97.980497ms)","trace[564403573] 'compare' (duration: 124.307357ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:57.89084Z","caller":"traceutil/trace.go:171","msg":"trace[991764632] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"351.300902ms","start":"2026-02-18T05:20:57.539525Z","end":"2026-02-18T05:20:57.890826Z","steps":["trace[991764632] 'read index received' (duration: 351.18929ms)","trace[991764632] 'applied index is now lower than readState.Index' (duration: 111.012µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:57.89104Z","caller":"traceutil/trace.go:171","msg":"trace[1883641504] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"355.293296ms","start":"2026-02-18T05:20:57.535739Z","end":"2026-02-18T05:20:57.891032Z","steps":["trace[1883641504] 'process raft request' (duration: 355.011849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:57.891122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:57.535723Z","time spent":"355.350977ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:57.891275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.752133ms","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-18T05:20:57.891299Z","caller":"traceutil/trace.go:171","msg":"trace[16124453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"351.798893ms","start":"2026-02-18T05:20:57.539495Z","end":"2026-02-18T05:20:57.891293Z","steps":["trace[16124453] 'agreement among raft nodes before linearized reading' (duration: 351.726261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:57.891321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:57.539489Z","time spent":"351.828014ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:20:57.891457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.659544ms","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-18T05:20:57.89148Z","caller":"traceutil/trace.go:171","msg":"trace[1632096800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"259.693614ms","start":"2026-02-18T05:20:57.631781Z","end":"2026-02-18T05:20:57.891474Z","steps":["trace[1632096800] 'agreement among raft nodes before linearized reading' (duration: 259.610513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:57.891615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.41368ms","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-18T05:20:57.891636Z","caller":"traceutil/trace.go:171","msg":"trace[1334038448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"200.45715ms","start":"2026-02-18T05:20:57.691175Z","end":"2026-02-18T05:20:57.891632Z","steps":["trace[1334038448] 'agreement among raft nodes before linearized reading' (duration: 200.407849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:57.892771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.242237ms","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-18T05:20:57.892813Z","caller":"traceutil/trace.go:171","msg":"trace[2079184999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"254.287778ms","start":"2026-02-18T05:20:57.638509Z","end":"2026-02-18T05:20:57.892797Z","steps":["trace[2079184999] 'agreement among raft nodes before linearized reading' (duration: 254.202566ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:00.034964Z","caller":"traceutil/trace.go:171","msg":"trace[914175001] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"123.888257ms","start":"2026-02-18T05:20:59.911056Z","end":"2026-02-18T05:21:00.034944Z","steps":["trace[914175001] 'read index received' (duration: 123.662092ms)","trace[914175001] 'applied index is now lower than readState.Index' (duration: 224.915µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:00.035479Z","caller":"traceutil/trace.go:171","msg":"trace[2019250692] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"129.823617ms","start":"2026-02-18T05:20:59.905642Z","end":"2026-02-18T05:21:00.035466Z","steps":["trace[2019250692] 'process raft request' (duration: 129.194662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.036874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.803402ms","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-18T05:21:00.037061Z","caller":"traceutil/trace.go:171","msg":"trace[539579481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"126.027988ms","start":"2026-02-18T05:20:59.911018Z","end":"2026-02-18T05:21:00.037046Z","steps":["trace[539579481] 'agreement among raft nodes before linearized reading' (duration: 125.532206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.038168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.2931ms","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-18T05:21:00.039181Z","caller":"traceutil/trace.go:171","msg":"trace[268123721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"123.327064ms","start":"2026-02-18T05:20:59.915834Z","end":"2026-02-18T05:21:00.039161Z","steps":["trace[268123721] 'agreement among raft nodes before linearized reading' (duration: 122.249958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.039485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.637384ms","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-18T05:21:00.039792Z","caller":"traceutil/trace.go:171","msg":"trace[1512306296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"121.953161ms","start":"2026-02-18T05:20:59.917827Z","end":"2026-02-18T05:21:00.03978Z","steps":["trace[1512306296] 'agreement among raft nodes before linearized reading' (duration: 121.572592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.03967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.246898ms","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-18T05:21:00.04015Z","caller":"traceutil/trace.go:171","msg":"trace[1104405592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"122.73987ms","start":"2026-02-18T05:20:59.917402Z","end":"2026-02-18T05:21:00.040141Z","steps":["trace[1104405592] 'agreement among raft nodes before linearized reading' (duration: 122.223637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.357898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.02713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:00.358027Z","caller":"traceutil/trace.go:171","msg":"trace[380969428] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"313.420199ms","start":"2026-02-18T05:21:00.044595Z","end":"2026-02-18T05:21:00.358015Z","steps":["trace[380969428] 'process raft request' (duration: 203.223325ms)","trace[380969428] 'compare' (duration: 109.952029ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:00.358046Z","caller":"traceutil/trace.go:171","msg":"trace[646068746] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"310.745297ms","start":"2026-02-18T05:21:00.047293Z","end":"2026-02-18T05:21:00.358038Z","steps":["trace[646068746] 'process raft request' (duration: 310.724497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.358067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.044582Z","time spent":"313.467061ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:00.358084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.047286Z","time spent":"310.778358ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:00.358172Z","caller":"traceutil/trace.go:171","msg":"trace[508697491] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"313.564613ms","start":"2026-02-18T05:21:00.044602Z","end":"2026-02-18T05:21:00.358166Z","steps":["trace[508697491] 'process raft request' (duration: 313.364068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.358205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.044582Z","time spent":"313.604484ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:00.358264Z","caller":"traceutil/trace.go:171","msg":"trace[86169185] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"312.016037ms","start":"2026-02-18T05:21:00.046243Z","end":"2026-02-18T05:21:00.358259Z","steps":["trace[86169185] 'process raft request' (duration: 311.755641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.358295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.046235Z","time spent":"312.045618ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:01.23091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.615253ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T05:21:01.231216Z","caller":"traceutil/trace.go:171","msg":"trace[1886711803] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"223.926783ms","start":"2026-02-18T05:21:01.00727Z","end":"2026-02-18T05:21:01.231197Z","steps":["trace[1886711803] 'read index received' (duration: 29.007143ms)","trace[1886711803] 'applied index is now lower than readState.Index' (duration: 194.91765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:01.231506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.2133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:21:01.231689Z","caller":"traceutil/trace.go:171","msg":"trace[2035535926] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:797; }","duration":"224.434355ms","start":"2026-02-18T05:21:01.007241Z","end":"2026-02-18T05:21:01.231675Z","steps":["trace[2035535926] 'agreement among raft nodes before linearized reading' (duration: 224.183309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:01.232176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.028044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:01.232421Z","caller":"traceutil/trace.go:171","msg":"trace[1080588914] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:797; }","duration":"108.30947ms","start":"2026-02-18T05:21:01.124099Z","end":"2026-02-18T05:21:01.232409Z","steps":["trace[1080588914] 'agreement among raft nodes before linearized reading' (duration: 108.037784ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:02.822365Z","caller":"traceutil/trace.go:171","msg":"trace[918828149] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"459.95458ms","start":"2026-02-18T05:21:02.362388Z","end":"2026-02-18T05:21:02.822343Z","steps":["trace[918828149] 'read index received' (duration: 459.798547ms)","trace[918828149] 'applied index is now lower than readState.Index' (duration: 155.003µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:02.822496Z","caller":"traceutil/trace.go:171","msg":"trace[1648175723] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"775.392378ms","start":"2026-02-18T05:21:02.047094Z","end":"2026-02-18T05:21:02.822486Z","steps":["trace[1648175723] 'process raft request' (duration: 775.109061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.822608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.047079Z","time spent":"775.442148ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:02.822818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.714234ms","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-18T05:21:02.822881Z","caller":"traceutil/trace.go:171","msg":"trace[1170220543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"456.809846ms","start":"2026-02-18T05:21:02.366054Z","end":"2026-02-18T05:21:02.822863Z","steps":["trace[1170220543] 'agreement among raft nodes before linearized reading' (duration: 456.640332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.822917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.366043Z","time spent":"456.865558ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:21:02.823023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.941065ms","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-18T05:21:02.823067Z","caller":"traceutil/trace.go:171","msg":"trace[764833859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"456.003277ms","start":"2026-02-18T05:21:02.367051Z","end":"2026-02-18T05:21:02.823055Z","steps":["trace[764833859] 'agreement among raft nodes before linearized reading' (duration: 455.868474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.823104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.367043Z","time spent":"456.051918ms","remote":"127.0.0.1:46514","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-18T05:21:02.823136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.283714ms","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-18T05:21:02.823171Z","caller":"traceutil/trace.go:171","msg":"trace[1807679656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"456.335935ms","start":"2026-02-18T05:21:02.366826Z","end":"2026-02-18T05:21:02.823162Z","steps":["trace[1807679656] 'agreement among raft nodes before linearized reading' (duration: 456.245052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.823198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.366817Z","time spent":"456.375765ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:02.82329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.900022ms","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-18T05:21:02.823516Z","caller":"traceutil/trace.go:171","msg":"trace[1139458698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"461.265351ms","start":"2026-02-18T05:21:02.362241Z","end":"2026-02-18T05:21:02.823507Z","steps":["trace[1139458698] 'agreement among raft nodes before linearized reading' (duration: 460.988074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.823546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.362221Z","time spent":"461.317612ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:03.59771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.972604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:03.597953Z","caller":"traceutil/trace.go:171","msg":"trace[305189322] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"764.412798ms","start":"2026-02-18T05:21:02.833527Z","end":"2026-02-18T05:21:03.59794Z","steps":["trace[305189322] 'process raft request' (duration: 585.157537ms)","trace[305189322] 'compare' (duration: 178.878052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:03.598594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.83351Z","time spent":"765.043383ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:03.90653Z","caller":"traceutil/trace.go:171","msg":"trace[1137603431] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:829; }","duration":"504.998761ms","start":"2026-02-18T05:21:03.40151Z","end":"2026-02-18T05:21:03.906509Z","steps":["trace[1137603431] 'read index received' (duration: 15.853613ms)","trace[1137603431] 'applied index is now lower than readState.Index' (duration: 489.144198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:03.907101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.583965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:03.907774Z","caller":"traceutil/trace.go:171","msg":"trace[217218100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"506.313042ms","start":"2026-02-18T05:21:03.401452Z","end":"2026-02-18T05:21:03.907765Z","steps":["trace[217218100] 'agreement among raft nodes before linearized reading' (duration: 505.568324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.908006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:03.401426Z","time spent":"506.565398ms","remote":"127.0.0.1:46286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:21:03.907561Z","caller":"traceutil/trace.go:171","msg":"trace[284677598] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.073817174s","start":"2026-02-18T05:21:02.833727Z","end":"2026-02-18T05:21:03.907544Z","steps":["trace[284677598] 'process raft request' (duration: 975.15657ms)","trace[284677598] 'compare' (duration: 97.388474ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:03.9076Z","caller":"traceutil/trace.go:171","msg":"trace[666936502] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.072699117s","start":"2026-02-18T05:21:02.834893Z","end":"2026-02-18T05:21:03.907592Z","steps":["trace[666936502] 'process raft request' (duration: 1.071481918s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:03.907626Z","caller":"traceutil/trace.go:171","msg":"trace[1158203988] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.072478151s","start":"2026-02-18T05:21:02.835142Z","end":"2026-02-18T05:21:03.90762Z","steps":["trace[1158203988] 'process raft request' (duration: 1.071323794s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:03.909231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.835135Z","time spent":"1.074024058s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:03.909643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.833718Z","time spent":"1.075898712s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:03.910001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:02.834885Z","time spent":"1.075048653s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:14.608546Z","caller":"traceutil/trace.go:171","msg":"trace[534715839] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"285.900172ms","start":"2026-02-18T05:21:14.32263Z","end":"2026-02-18T05:21:14.60853Z","steps":["trace[534715839] 'process raft request' (duration: 285.764509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:15.125998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.384236ms","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-18T05:21:15.126046Z","caller":"traceutil/trace.go:171","msg":"trace[742693740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"222.470388ms","start":"2026-02-18T05:21:14.903564Z","end":"2026-02-18T05:21:15.126035Z","steps":["trace[742693740] 'range keys from in-memory index tree' (duration: 222.269133ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:16.159501Z","caller":"traceutil/trace.go:171","msg":"trace[1370405798] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"137.974129ms","start":"2026-02-18T05:21:16.021496Z","end":"2026-02-18T05:21:16.15947Z","steps":["trace[1370405798] 'read index received' (duration: 137.497367ms)","trace[1370405798] 'applied index is now lower than readState.Index' (duration: 475.772µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:16.159603Z","caller":"traceutil/trace.go:171","msg":"trace[709065381] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"141.524473ms","start":"2026-02-18T05:21:16.01807Z","end":"2026-02-18T05:21:16.159594Z","steps":["trace[709065381] 'process raft request' (duration: 140.972749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:16.159787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.313963ms","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-18T05:21:16.15987Z","caller":"traceutil/trace.go:171","msg":"trace[372824532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"137.435286ms","start":"2026-02-18T05:21:16.022421Z","end":"2026-02-18T05:21:16.159857Z","steps":["trace[372824532] 'agreement among raft nodes before linearized reading' (duration: 137.2023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:16.160016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.504861ms","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-18T05:21:16.160039Z","caller":"traceutil/trace.go:171","msg":"trace[1783464162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:887; }","duration":"138.549872ms","start":"2026-02-18T05:21:16.021483Z","end":"2026-02-18T05:21:16.160033Z","steps":["trace[1783464162] 'agreement among raft nodes before linearized reading' (duration: 138.448409ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:17.413501Z","caller":"traceutil/trace.go:171","msg":"trace[883017018] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"263.708109ms","start":"2026-02-18T05:21:17.149776Z","end":"2026-02-18T05:21:17.413484Z","steps":["trace[883017018] 'process raft request' (duration: 263.29648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:18.309015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.389659ms","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-18T05:21:18.309109Z","caller":"traceutil/trace.go:171","msg":"trace[1332459812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:891; }","duration":"144.490283ms","start":"2026-02-18T05:21:18.164603Z","end":"2026-02-18T05:21:18.309094Z","steps":["trace[1332459812] 'range keys from in-memory index tree' (duration: 144.294817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:18.309256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.478637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T05:21:18.309301Z","caller":"traceutil/trace.go:171","msg":"trace[1598451511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"103.559048ms","start":"2026-02-18T05:21:18.205731Z","end":"2026-02-18T05:21:18.30929Z","steps":["trace[1598451511] 'range keys from in-memory index tree' (duration: 103.341353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:18.860454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909671573,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:21:19.361152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909671573,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:21:19.653485Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.337945453s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:21:19.654267Z","caller":"traceutil/trace.go:171","msg":"trace[639993366] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"1.338777703s","start":"2026-02-18T05:21:18.315443Z","end":"2026-02-18T05:21:19.65422Z","steps":["trace[639993366] 'process raft request' (duration: 1.338250201s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:19.654408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:18.315423Z","time spent":"1.338906116s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:19.71025Z","caller":"traceutil/trace.go:171","msg":"trace[430816469] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.389766475s","start":"2026-02-18T05:21:18.320462Z","end":"2026-02-18T05:21:19.710228Z","steps":["trace[430816469] 'process raft request' (duration: 1.388902105s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:19.710375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:18.320451Z","time spent":"1.389861497s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:19.710937Z","caller":"traceutil/trace.go:171","msg":"trace[1226886347] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.3904066s","start":"2026-02-18T05:21:18.320511Z","end":"2026-02-18T05:21:19.710918Z","steps":["trace[1226886347] 'process raft request' (duration: 1.389363596s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:19.711044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:18.320507Z","time spent":"1.390494372s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:19.711296Z","caller":"traceutil/trace.go:171","msg":"trace[1834413995] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:927; }","duration":"1.350960021s","start":"2026-02-18T05:21:18.359878Z","end":"2026-02-18T05:21:19.710838Z","steps":["trace[1834413995] 'read index received' (duration: 1.294820178s)","trace[1834413995] 'applied index is now lower than readState.Index' (duration: 56.137213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:19.711394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351505854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:19.711437Z","caller":"traceutil/trace.go:171","msg":"trace[304434500] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:894; }","duration":"1.351596206s","start":"2026-02-18T05:21:18.359826Z","end":"2026-02-18T05:21:19.711422Z","steps":["trace[304434500] 'agreement among raft nodes before linearized reading' (duration: 1.351524065s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:19.711469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:18.35981Z","time spent":"1.351650467s","remote":"127.0.0.1:53472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:21:19.711789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313848568s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:19.71182Z","caller":"traceutil/trace.go:171","msg":"trace[1317127320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"1.313909378s","start":"2026-02-18T05:21:18.397902Z","end":"2026-02-18T05:21:19.711812Z","steps":["trace[1317127320] 'agreement among raft nodes before linearized reading' (duration: 1.313862227s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:19.711849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:18.397885Z","time spent":"1.31395888s","remote":"127.0.0.1:46286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:21:19.712075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.285552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T05:21:19.712115Z","caller":"traceutil/trace.go:171","msg":"trace[2110968844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"285.349324ms","start":"2026-02-18T05:21:19.426754Z","end":"2026-02-18T05:21:19.712103Z","steps":["trace[2110968844] 'agreement among raft nodes before linearized reading' (duration: 285.222721ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:19.837566Z","caller":"traceutil/trace.go:171","msg":"trace[205946648] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"115.931772ms","start":"2026-02-18T05:21:19.721611Z","end":"2026-02-18T05:21:19.837543Z","steps":["trace[205946648] 'read index received' (duration: 115.029831ms)","trace[205946648] 'applied index is now lower than readState.Index' (duration: 901.031µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:19.837911Z","caller":"traceutil/trace.go:171","msg":"trace[607235424] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"120.913939ms","start":"2026-02-18T05:21:19.716962Z","end":"2026-02-18T05:21:19.837876Z","steps":["trace[607235424] 'process raft request' (duration: 119.738551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:19.838143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.515696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:19.838177Z","caller":"traceutil/trace.go:171","msg":"trace[2073371103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"116.586757ms","start":"2026-02-18T05:21:19.721581Z","end":"2026-02-18T05:21:19.838167Z","steps":["trace[2073371103] 'agreement among raft nodes before linearized reading' (duration: 116.522585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:22.546648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.794969ms","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-18T05:21:22.546695Z","caller":"traceutil/trace.go:171","msg":"trace[1710037028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"701.86952ms","start":"2026-02-18T05:21:21.844812Z","end":"2026-02-18T05:21:22.546681Z","steps":["trace[1710037028] 'range keys from in-memory index tree' (duration: 701.688187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:22.546715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:21.844797Z","time spent":"701.913872ms","remote":"127.0.0.1:46514","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-18T05:21:22.546811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.038776ms","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-18T05:21:22.546824Z","caller":"traceutil/trace.go:171","msg":"trace[868723603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"697.063947ms","start":"2026-02-18T05:21:21.849755Z","end":"2026-02-18T05:21:22.546819Z","steps":["trace[868723603] 'range keys from in-memory index tree' (duration: 696.972655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:22.546836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:21.849749Z","time spent":"697.084098ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T05:21:22.814167Z","caller":"traceutil/trace.go:171","msg":"trace[1527933808] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"263.034918ms","start":"2026-02-18T05:21:22.551117Z","end":"2026-02-18T05:21:22.814152Z","steps":["trace[1527933808] 'process raft request' (duration: 262.933776ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:22.819124Z","caller":"traceutil/trace.go:171","msg":"trace[1150236771] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"267.986895ms","start":"2026-02-18T05:21:22.55112Z","end":"2026-02-18T05:21:22.819107Z","steps":["trace[1150236771] 'process raft request' (duration: 267.884163ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:25.176593Z","caller":"traceutil/trace.go:171","msg":"trace[1305826733] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"355.855106ms","start":"2026-02-18T05:21:24.820723Z","end":"2026-02-18T05:21:25.176578Z","steps":["trace[1305826733] 'read index received' (duration: 355.730613ms)","trace[1305826733] 'applied index is now lower than readState.Index' (duration: 123.993µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:25.176733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.981179ms","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-18T05:21:25.17675Z","caller":"traceutil/trace.go:171","msg":"trace[1170505581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:905; }","duration":"356.03509ms","start":"2026-02-18T05:21:24.820709Z","end":"2026-02-18T05:21:25.176744Z","steps":["trace[1170505581] 'agreement among raft nodes before linearized reading' (duration: 355.923197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:25.176767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:24.820704Z","time spent":"356.059522ms","remote":"127.0.0.1:46514","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-18T05:21:25.176975Z","caller":"traceutil/trace.go:171","msg":"trace[174678565] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"357.541075ms","start":"2026-02-18T05:21:24.819429Z","end":"2026-02-18T05:21:25.17697Z","steps":["trace[174678565] 'process raft request' (duration: 357.060644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:25.177019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:24.81942Z","time spent":"357.567507ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:25.257617Z","caller":"traceutil/trace.go:171","msg":"trace[1503669150] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"378.389788ms","start":"2026-02-18T05:21:24.879211Z","end":"2026-02-18T05:21:25.257601Z","steps":["trace[1503669150] 'process raft request' (duration: 378.304076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:25.257711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:24.879196Z","time spent":"378.467819ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:25.351941Z","caller":"traceutil/trace.go:171","msg":"trace[911676674] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"172.28653ms","start":"2026-02-18T05:21:25.179638Z","end":"2026-02-18T05:21:25.351924Z","steps":["trace[911676674] 'process raft request' (duration: 100.461318ms)","trace[911676674] 'compare' (duration: 71.756831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:31.20768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.709284ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T05:21:31.207802Z","caller":"traceutil/trace.go:171","msg":"trace[1741018521] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"149.857352ms","start":"2026-02-18T05:21:31.057934Z","end":"2026-02-18T05:21:31.207792Z","steps":["trace[1741018521] 'read index received' (duration: 36.121µs)","trace[1741018521] 'applied index is now lower than readState.Index' (duration: 149.818911ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:31.207871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.933533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:21:31.207887Z","caller":"traceutil/trace.go:171","msg":"trace[57051742] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:922; }","duration":"149.982185ms","start":"2026-02-18T05:21:31.0579Z","end":"2026-02-18T05:21:31.207882Z","steps":["trace[57051742] 'agreement among raft nodes before linearized reading' (duration: 149.941154ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:31.555148Z","caller":"traceutil/trace.go:171","msg":"trace[862316934] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"189.763592ms","start":"2026-02-18T05:21:31.365363Z","end":"2026-02-18T05:21:31.555126Z","steps":["trace[862316934] 'read index received' (duration: 189.572538ms)","trace[862316934] 'applied index is now lower than readState.Index' (duration: 190.124µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:31.555392Z","caller":"traceutil/trace.go:171","msg":"trace[686076280] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"343.755571ms","start":"2026-02-18T05:21:31.211626Z","end":"2026-02-18T05:21:31.555381Z","steps":["trace[686076280] 'process raft request' (duration: 343.363952ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:31.555496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:31.211616Z","time spent":"343.816212ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:31.555764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.396767ms","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-18T05:21:31.555796Z","caller":"traceutil/trace.go:171","msg":"trace[725077841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"190.462439ms","start":"2026-02-18T05:21:31.365324Z","end":"2026-02-18T05:21:31.555787Z","steps":["trace[725077841] 'agreement among raft nodes before linearized reading' (duration: 190.337106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:31.555946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.399955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:31.555972Z","caller":"traceutil/trace.go:171","msg":"trace[1097283808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"156.446997ms","start":"2026-02-18T05:21:31.399517Z","end":"2026-02-18T05:21:31.555964Z","steps":["trace[1097283808] 'agreement among raft nodes before linearized reading' (duration: 156.407386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.287216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897346909671666,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:21:32.564107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.838007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:32.564232Z","caller":"traceutil/trace.go:171","msg":"trace[691425251] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"777.44753ms","start":"2026-02-18T05:21:31.786762Z","end":"2026-02-18T05:21:32.56421Z","steps":["trace[691425251] 'read index received' (duration: 631.335317ms)","trace[691425251] 'applied index is now lower than readState.Index' (duration: 146.110753ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:32.564509Z","caller":"traceutil/trace.go:171","msg":"trace[1169608201] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.004277776s","start":"2026-02-18T05:21:31.56021Z","end":"2026-02-18T05:21:32.564488Z","steps":["trace[1169608201] 'process raft request' (duration: 857.943107ms)","trace[1169608201] 'compare' (duration: 145.687963ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:32.564637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:31.560203Z","time spent":"1.004356767s","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:32.564971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.200948ms","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-18T05:21:32.565005Z","caller":"traceutil/trace.go:171","msg":"trace[1086342518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"778.25792ms","start":"2026-02-18T05:21:31.786736Z","end":"2026-02-18T05:21:32.564994Z","steps":["trace[1086342518] 'agreement among raft nodes before linearized reading' (duration: 778.117116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.565038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:31.786723Z","time spent":"778.30728ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:21:32.565642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.234619ms","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-18T05:21:32.565684Z","caller":"traceutil/trace.go:171","msg":"trace[1035769017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"753.304632ms","start":"2026-02-18T05:21:31.812368Z","end":"2026-02-18T05:21:32.565673Z","steps":["trace[1035769017] 'agreement among raft nodes before linearized reading' (duration: 752.80351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.565715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:31.812355Z","time spent":"753.352632ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:32.565956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.911486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:32.566221Z","caller":"traceutil/trace.go:171","msg":"trace[1815347368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"165.222394ms","start":"2026-02-18T05:21:32.400964Z","end":"2026-02-18T05:21:32.566187Z","steps":["trace[1815347368] 'agreement among raft nodes before linearized reading' (duration: 164.959527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.566985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.785346ms","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-18T05:21:32.567023Z","caller":"traceutil/trace.go:171","msg":"trace[230378194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"751.864047ms","start":"2026-02-18T05:21:31.815148Z","end":"2026-02-18T05:21:32.567012Z","steps":["trace[230378194] 'agreement among raft nodes before linearized reading' (duration: 751.649202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.567055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:31.815138Z","time spent":"751.906799ms","remote":"127.0.0.1:46514","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-18T05:21:34.147358Z","caller":"traceutil/trace.go:171","msg":"trace[1897843103] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"392.650623ms","start":"2026-02-18T05:21:33.75469Z","end":"2026-02-18T05:21:34.147341Z","steps":["trace[1897843103] 'read index received' (duration: 392.5118ms)","trace[1897843103] 'applied index is now lower than readState.Index' (duration: 138.253µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:34.147447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.740636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:34.147462Z","caller":"traceutil/trace.go:171","msg":"trace[1265033579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"392.796386ms","start":"2026-02-18T05:21:33.754661Z","end":"2026-02-18T05:21:34.147458Z","steps":["trace[1265033579] 'agreement among raft nodes before linearized reading' (duration: 392.740785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:34.14748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:33.754651Z","time spent":"392.825588ms","remote":"127.0.0.1:46286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:21:34.147677Z","caller":"traceutil/trace.go:171","msg":"trace[1440106183] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"580.849448ms","start":"2026-02-18T05:21:33.566819Z","end":"2026-02-18T05:21:34.147668Z","steps":["trace[1440106183] 'process raft request' (duration: 580.430678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:34.147724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:33.566807Z","time spent":"580.882459ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:34.822772Z","caller":"traceutil/trace.go:171","msg":"trace[1411136786] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"202.525283ms","start":"2026-02-18T05:21:34.62023Z","end":"2026-02-18T05:21:34.822755Z","steps":["trace[1411136786] 'process raft request' (duration: 202.502572ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:34.822976Z","caller":"traceutil/trace.go:171","msg":"trace[1049815484] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"208.571226ms","start":"2026-02-18T05:21:34.6144Z","end":"2026-02-18T05:21:34.822971Z","steps":["trace[1049815484] 'process raft request' (duration: 204.299725ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:34.823035Z","caller":"traceutil/trace.go:171","msg":"trace[146425969] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"206.756983ms","start":"2026-02-18T05:21:34.616274Z","end":"2026-02-18T05:21:34.823031Z","steps":["trace[146425969] 'process raft request' (duration: 206.437175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:38.484931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.041958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:21:38.484978Z","caller":"traceutil/trace.go:171","msg":"trace[897165807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"319.120721ms","start":"2026-02-18T05:21:38.165846Z","end":"2026-02-18T05:21:38.484967Z","steps":["trace[897165807] 'range keys from in-memory index tree' (duration: 318.934696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:38.485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.165831Z","time spent":"319.163351ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:38.485328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.294284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4782"} {"level":"info","ts":"2026-02-18T05:21:38.485372Z","caller":"traceutil/trace.go:171","msg":"trace[108776641] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:954; }","duration":"142.367755ms","start":"2026-02-18T05:21:38.342993Z","end":"2026-02-18T05:21:38.485361Z","steps":["trace[108776641] 'range keys from in-memory index tree' (duration: 142.185661ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:38.799304Z","caller":"traceutil/trace.go:171","msg":"trace[1194601851] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"310.644201ms","start":"2026-02-18T05:21:38.488642Z","end":"2026-02-18T05:21:38.799286Z","steps":["trace[1194601851] 'process raft request' (duration: 310.551049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:38.799406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.488628Z","time spent":"310.726703ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:39.165011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.138802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:39.16507Z","caller":"traceutil/trace.go:171","msg":"trace[2000582993] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"544.303957ms","start":"2026-02-18T05:21:38.620756Z","end":"2026-02-18T05:21:39.16506Z","steps":["trace[2000582993] 'read index received' (duration: 178.61872ms)","trace[2000582993] 'applied index is now lower than readState.Index' (duration: 365.684557ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:39.165214Z","caller":"traceutil/trace.go:171","msg":"trace[2021041725] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"672.722332ms","start":"2026-02-18T05:21:38.492486Z","end":"2026-02-18T05:21:39.165208Z","steps":["trace[2021041725] 'process raft request' (duration: 428.334694ms)","trace[2021041725] 'compare' (duration: 244.058831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:39.165249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.492474Z","time spent":"672.759043ms","remote":"127.0.0.1:46442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4908,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:39.165376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.628144ms","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-18T05:21:39.165395Z","caller":"traceutil/trace.go:171","msg":"trace[999293778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"544.670406ms","start":"2026-02-18T05:21:38.620719Z","end":"2026-02-18T05:21:39.165389Z","steps":["trace[999293778] 'agreement among raft nodes before linearized reading' (duration: 544.609484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.165431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.620704Z","time spent":"544.722306ms","remote":"127.0.0.1:46514","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-18T05:21:39.165523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.32062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7zdj8\" ","response":"range_response_count:1 size:10924"} {"level":"info","ts":"2026-02-18T05:21:39.165535Z","caller":"traceutil/trace.go:171","msg":"trace[850648474] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7zdj8; range_end:; response_count:1; response_revision:956; }","duration":"399.36429ms","start":"2026-02-18T05:21:38.766167Z","end":"2026-02-18T05:21:39.165532Z","steps":["trace[850648474] 'agreement among raft nodes before linearized reading' (duration: 399.325449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.165547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.766154Z","time spent":"399.390941ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":10948,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7zdj8\" "} {"level":"warn","ts":"2026-02-18T05:21:39.165663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.545594ms","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-18T05:21:39.165681Z","caller":"traceutil/trace.go:171","msg":"trace[838478709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"323.575394ms","start":"2026-02-18T05:21:38.842101Z","end":"2026-02-18T05:21:39.165677Z","steps":["trace[838478709] 'agreement among raft nodes before linearized reading' (duration: 323.534283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.165693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.842095Z","time spent":"323.594855ms","remote":"127.0.0.1:46514","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-18T05:21:39.165772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.405993ms","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-18T05:21:39.165787Z","caller":"traceutil/trace.go:171","msg":"trace[540780618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"324.446535ms","start":"2026-02-18T05:21:38.841337Z","end":"2026-02-18T05:21:39.165784Z","steps":["trace[540780618] 'agreement among raft nodes before linearized reading' (duration: 324.399954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.1658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.841326Z","time spent":"324.470265ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:39.165877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.447846ms","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-18T05:21:39.165892Z","caller":"traceutil/trace.go:171","msg":"trace[1744472091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"330.497867ms","start":"2026-02-18T05:21:38.835391Z","end":"2026-02-18T05:21:39.165889Z","steps":["trace[1744472091] 'agreement among raft nodes before linearized reading' (duration: 330.459886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.165904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:38.835374Z","time spent":"330.527209ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T05:21:39.583944Z","caller":"traceutil/trace.go:171","msg":"trace[425006073] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"402.171017ms","start":"2026-02-18T05:21:39.181748Z","end":"2026-02-18T05:21:39.583919Z","steps":["trace[425006073] 'process raft request' (duration: 366.898796ms)","trace[425006073] 'compare' (duration: 34.930823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:39.584071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:39.181736Z","time spent":"402.267589ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11172,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:39.642142Z","caller":"traceutil/trace.go:171","msg":"trace[627783595] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"172.679279ms","start":"2026-02-18T05:21:39.469445Z","end":"2026-02-18T05:21:39.642124Z","steps":["trace[627783595] 'read index received' (duration: 79.213296ms)","trace[627783595] 'applied index is now lower than readState.Index' (duration: 93.465233ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:39.642425Z","caller":"traceutil/trace.go:171","msg":"trace[622831563] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"460.522173ms","start":"2026-02-18T05:21:39.181891Z","end":"2026-02-18T05:21:39.642413Z","steps":["trace[622831563] 'process raft request' (duration: 460.045472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.642519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:39.181887Z","time spent":"460.572854ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:39.642675Z","caller":"traceutil/trace.go:171","msg":"trace[315406835] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"173.13661ms","start":"2026-02-18T05:21:39.469529Z","end":"2026-02-18T05:21:39.642666Z","steps":["trace[315406835] 'process raft request' (duration: 172.503715ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:39.642839Z","caller":"traceutil/trace.go:171","msg":"trace[1333324254] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"173.069509ms","start":"2026-02-18T05:21:39.469759Z","end":"2026-02-18T05:21:39.642829Z","steps":["trace[1333324254] 'process raft request' (duration: 172.304011ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:39.642961Z","caller":"traceutil/trace.go:171","msg":"trace[1833716456] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"154.298786ms","start":"2026-02-18T05:21:39.488653Z","end":"2026-02-18T05:21:39.642952Z","steps":["trace[1833716456] 'process raft request' (duration: 153.438815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.643094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.642982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-18T05:21:39.64313Z","caller":"traceutil/trace.go:171","msg":"trace[1638884321] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:961; }","duration":"173.704234ms","start":"2026-02-18T05:21:39.469417Z","end":"2026-02-18T05:21:39.643121Z","steps":["trace[1638884321] 'agreement among raft nodes before linearized reading' (duration: 173.639812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.671876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.245587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:39.671932Z","caller":"traceutil/trace.go:171","msg":"trace[388626927] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:964; }","duration":"202.352989ms","start":"2026-02-18T05:21:39.469565Z","end":"2026-02-18T05:21:39.671918Z","steps":["trace[388626927] 'agreement among raft nodes before linearized reading' (duration: 202.236417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.67196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.892818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:39.672253Z","caller":"traceutil/trace.go:171","msg":"trace[1541207415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"202.197165ms","start":"2026-02-18T05:21:39.470044Z","end":"2026-02-18T05:21:39.672241Z","steps":["trace[1541207415] 'agreement among raft nodes before linearized reading' (duration: 201.885878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.899914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.196418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:39.899982Z","caller":"traceutil/trace.go:171","msg":"trace[2108624973] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"224.116692ms","start":"2026-02-18T05:21:39.675853Z","end":"2026-02-18T05:21:39.89997Z","steps":["trace[2108624973] 'read index received' (duration: 96.769391ms)","trace[2108624973] 'applied index is now lower than readState.Index' (duration: 127.346501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:39.900049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.194963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:39.900065Z","caller":"traceutil/trace.go:171","msg":"trace[1373268148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"224.226264ms","start":"2026-02-18T05:21:39.675833Z","end":"2026-02-18T05:21:39.900059Z","steps":["trace[1373268148] 'agreement among raft nodes before linearized reading' (duration: 224.175102ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:39.900272Z","caller":"traceutil/trace.go:171","msg":"trace[1625565719] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"244.493972ms","start":"2026-02-18T05:21:39.65577Z","end":"2026-02-18T05:21:39.900264Z","steps":["trace[1625565719] 'process raft request' (duration: 116.894665ms)","trace[1625565719] 'compare' (duration: 126.995752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:40.960242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.369929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:40.960296Z","caller":"traceutil/trace.go:171","msg":"trace[1678888145] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:965; }","duration":"345.438571ms","start":"2026-02-18T05:21:40.614845Z","end":"2026-02-18T05:21:40.960283Z","steps":["trace[1678888145] 'count revisions from in-memory index tree' (duration: 345.292617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:40.960319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:40.614767Z","time spent":"345.547163ms","remote":"127.0.0.1:53454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:21:40.960504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.731742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-18T05:21:40.960518Z","caller":"traceutil/trace.go:171","msg":"trace[1660260666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"150.783753ms","start":"2026-02-18T05:21:40.80973Z","end":"2026-02-18T05:21:40.960513Z","steps":["trace[1660260666] 'range keys from in-memory index tree' (duration: 150.611219ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:46.885944Z","caller":"traceutil/trace.go:171","msg":"trace[1169247511] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"485.987043ms","start":"2026-02-18T05:21:46.39994Z","end":"2026-02-18T05:21:46.885927Z","steps":["trace[1169247511] 'read index received' (duration: 485.819089ms)","trace[1169247511] 'applied index is now lower than readState.Index' (duration: 167.174µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:46.88601Z","caller":"traceutil/trace.go:171","msg":"trace[1598190125] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"517.936356ms","start":"2026-02-18T05:21:46.368068Z","end":"2026-02-18T05:21:46.886004Z","steps":["trace[1598190125] 'process raft request' (duration: 517.743961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:46.886097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:46.368046Z","time spent":"517.992718ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:46.892536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.578918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:46.892597Z","caller":"traceutil/trace.go:171","msg":"trace[1283570843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"492.677531ms","start":"2026-02-18T05:21:46.399906Z","end":"2026-02-18T05:21:46.892584Z","steps":["trace[1283570843] 'agreement among raft nodes before linearized reading' (duration: 486.215089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:46.892624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:46.399891Z","time spent":"492.727402ms","remote":"127.0.0.1:46286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:21:48.023384Z","caller":"traceutil/trace.go:171","msg":"trace[589929641] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"340.088335ms","start":"2026-02-18T05:21:47.683274Z","end":"2026-02-18T05:21:48.023362Z","steps":["trace[589929641] 'read index received' (duration: 339.916691ms)","trace[589929641] 'applied index is now lower than readState.Index' (duration: 170.794µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:48.023684Z","caller":"traceutil/trace.go:171","msg":"trace[579488891] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"343.29803ms","start":"2026-02-18T05:21:47.680375Z","end":"2026-02-18T05:21:48.023673Z","steps":["trace[579488891] 'process raft request' (duration: 342.87615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:48.023772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:47.680365Z","time spent":"343.346222ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:48.023994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.71443ms","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-18T05:21:48.02534Z","caller":"traceutil/trace.go:171","msg":"trace[1834885146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"340.75468ms","start":"2026-02-18T05:21:47.683259Z","end":"2026-02-18T05:21:48.024014Z","steps":["trace[1834885146] 'agreement among raft nodes before linearized reading' (duration: 340.652338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:48.025398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:47.683253Z","time spent":"342.129203ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:21:48.025747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.599347ms","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-18T05:21:48.025782Z","caller":"traceutil/trace.go:171","msg":"trace[1293794187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"340.646818ms","start":"2026-02-18T05:21:47.685125Z","end":"2026-02-18T05:21:48.025772Z","steps":["trace[1293794187] 'agreement among raft nodes before linearized reading' (duration: 340.549305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:48.02581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:47.685119Z","time spent":"340.685258ms","remote":"127.0.0.1:46514","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-18T05:21:48.026526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.036314ms","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-18T05:21:48.026566Z","caller":"traceutil/trace.go:171","msg":"trace[1972801492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"329.109626ms","start":"2026-02-18T05:21:47.697446Z","end":"2026-02-18T05:21:48.026556Z","steps":["trace[1972801492] 'agreement among raft nodes before linearized reading' (duration: 328.998334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:48.026596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:47.69743Z","time spent":"329.159367ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:50.883542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:50.883647Z","caller":"traceutil/trace.go:171","msg":"trace[1861796809] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"187.711313ms","start":"2026-02-18T05:21:50.695921Z","end":"2026-02-18T05:21:50.883632Z","steps":["trace[1861796809] 'process raft request' (duration: 54.295529ms)","trace[1861796809] 'compare' (duration: 133.065656ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:53.154447Z","caller":"traceutil/trace.go:171","msg":"trace[437012810] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"263.025119ms","start":"2026-02-18T05:21:52.8914Z","end":"2026-02-18T05:21:53.154425Z","steps":["trace[437012810] 'process raft request' (duration: 262.505887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:22:43.537377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.613661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:22:43.537453Z","caller":"traceutil/trace.go:171","msg":"trace[1753427111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"135.722534ms","start":"2026-02-18T05:22:43.401714Z","end":"2026-02-18T05:22:43.537436Z","steps":["trace[1753427111] 'range keys from in-memory index tree' (duration: 135.54167ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:22:48.478506Z","caller":"traceutil/trace.go:171","msg":"trace[1467050377] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"107.509885ms","start":"2026-02-18T05:22:48.370971Z","end":"2026-02-18T05:22:48.47848Z","steps":["trace[1467050377] 'process raft request' (duration: 82.964371ms)","trace[1467050377] 'compare' (duration: 24.466462ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:24:06.321304Z","caller":"traceutil/trace.go:171","msg":"trace[296794655] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"101.809838ms","start":"2026-02-18T05:24:06.219475Z","end":"2026-02-18T05:24:06.321285Z","steps":["trace[296794655] 'process raft request' (duration: 101.482601ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:24:59.399802Z","caller":"traceutil/trace.go:171","msg":"trace[2142863286] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"208.170578ms","start":"2026-02-18T05:24:59.191615Z","end":"2026-02-18T05:24:59.399786Z","steps":["trace[2142863286] 'read index received' (duration: 208.043205ms)","trace[2142863286] 'applied index is now lower than readState.Index' (duration: 126.673µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:24:59.400005Z","caller":"traceutil/trace.go:171","msg":"trace[254262592] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"241.83065ms","start":"2026-02-18T05:24:59.158166Z","end":"2026-02-18T05:24:59.399997Z","steps":["trace[254262592] 'process raft request' (duration: 241.531063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:24:59.400135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.504556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:24:59.400157Z","caller":"traceutil/trace.go:171","msg":"trace[2143217507] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1828; }","duration":"208.558988ms","start":"2026-02-18T05:24:59.191591Z","end":"2026-02-18T05:24:59.40015Z","steps":["trace[2143217507] 'agreement among raft nodes before linearized reading' (duration: 208.510187ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:24:59.521843Z","caller":"traceutil/trace.go:171","msg":"trace[1664387126] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"117.125725ms","start":"2026-02-18T05:24:59.404693Z","end":"2026-02-18T05:24:59.521819Z","steps":["trace[1664387126] 'process raft request' (duration: 115.760463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:00.78495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.849454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-18T05:25:00.785026Z","caller":"traceutil/trace.go:171","msg":"trace[662862354] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1834; }","duration":"106.944516ms","start":"2026-02-18T05:25:00.67806Z","end":"2026-02-18T05:25:00.785004Z","steps":["trace[662862354] 'range keys from in-memory index tree' (duration: 106.730481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:00.785066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.840054ms","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-18T05:25:00.785136Z","caller":"traceutil/trace.go:171","msg":"trace[1797792662] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"140.961367ms","start":"2026-02-18T05:25:00.64416Z","end":"2026-02-18T05:25:00.785121Z","steps":["trace[1797792662] 'range keys from in-memory index tree' (duration: 140.67174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:01.062702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.91256ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T05:25:01.063531Z","caller":"traceutil/trace.go:171","msg":"trace[1620888403] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"274.749185ms","start":"2026-02-18T05:25:00.788761Z","end":"2026-02-18T05:25:01.06351Z","steps":["trace[1620888403] 'process raft request' (duration: 274.136561ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:25:01.063778Z","caller":"traceutil/trace.go:171","msg":"trace[1797531133] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"273.66818ms","start":"2026-02-18T05:25:00.790098Z","end":"2026-02-18T05:25:01.063766Z","steps":["trace[1797531133] 'process raft request' (duration: 272.940053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:01.308588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.456436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:25:01.308929Z","caller":"traceutil/trace.go:171","msg":"trace[1966931831] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"240.471878ms","start":"2026-02-18T05:25:01.068427Z","end":"2026-02-18T05:25:01.308899Z","steps":["trace[1966931831] 'process raft request' (duration: 96.645804ms)","trace[1966931831] 'compare' (duration: 143.22031ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:25:05.625948Z","caller":"traceutil/trace.go:171","msg":"trace[605112025] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"224.478292ms","start":"2026-02-18T05:25:05.401455Z","end":"2026-02-18T05:25:05.625933Z","steps":["trace[605112025] 'read index received' (duration: 224.37861ms)","trace[605112025] 'applied index is now lower than readState.Index' (duration: 99.002µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:25:05.62606Z","caller":"traceutil/trace.go:171","msg":"trace[2030654573] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"342.94433ms","start":"2026-02-18T05:25:05.283079Z","end":"2026-02-18T05:25:05.626023Z","steps":["trace[2030654573] 'process raft request' (duration: 342.760245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:05.626143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:25:05.283068Z","time spent":"343.010232ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2815,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:25:05.626317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.860831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:25:05.626339Z","caller":"traceutil/trace.go:171","msg":"trace[23530581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1856; }","duration":"224.913683ms","start":"2026-02-18T05:25:05.401419Z","end":"2026-02-18T05:25:05.626333Z","steps":["trace[23530581] 'agreement among raft nodes before linearized reading' (duration: 224.875762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:05.626504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.902867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T05:25:05.626533Z","caller":"traceutil/trace.go:171","msg":"trace[1368802473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1856; }","duration":"211.954247ms","start":"2026-02-18T05:25:05.414574Z","end":"2026-02-18T05:25:05.626529Z","steps":["trace[1368802473] 'agreement among raft nodes before linearized reading' (duration: 211.873676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:05.62834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.74748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-18T05:25:05.628365Z","caller":"traceutil/trace.go:171","msg":"trace[728330296] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1856; }","duration":"165.873644ms","start":"2026-02-18T05:25:05.462486Z","end":"2026-02-18T05:25:05.628359Z","steps":["trace[728330296] 'agreement among raft nodes before linearized reading' (duration: 165.787152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:05.893273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.386141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:25:05.893367Z","caller":"traceutil/trace.go:171","msg":"trace[1688277722] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"256.328782ms","start":"2026-02-18T05:25:05.63702Z","end":"2026-02-18T05:25:05.893349Z","steps":["trace[1688277722] 'process raft request' (duration: 79.761577ms)","trace[1688277722] 'compare' (duration: 176.170896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:25:06.224774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.730575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:25:06.224903Z","caller":"traceutil/trace.go:171","msg":"trace[321195539] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"587.666759ms","start":"2026-02-18T05:25:05.637222Z","end":"2026-02-18T05:25:06.224889Z","steps":["trace[321195539] 'process raft request' (duration: 448.638337ms)","trace[321195539] 'compare' (duration: 138.493869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:25:06.224941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:25:05.637207Z","time spent":"587.718919ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:25:06.225212Z","caller":"traceutil/trace.go:171","msg":"trace[1901568387] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"584.670528ms","start":"2026-02-18T05:25:05.640536Z","end":"2026-02-18T05:25:06.225206Z","steps":["trace[1901568387] 'process raft request' (duration: 584.30586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:06.225244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:25:05.640528Z","time spent":"584.701139ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:25:06.225471Z","caller":"traceutil/trace.go:171","msg":"trace[512593665] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"584.854712ms","start":"2026-02-18T05:25:05.640612Z","end":"2026-02-18T05:25:06.225466Z","steps":["trace[512593665] 'process raft request' (duration: 584.256438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:06.2255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:25:05.640608Z","time spent":"584.879123ms","remote":"127.0.0.1:46342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":706,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:25:06.225618Z","caller":"traceutil/trace.go:171","msg":"trace[1233827635] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"583.328677ms","start":"2026-02-18T05:25:05.642285Z","end":"2026-02-18T05:25:06.225613Z","steps":["trace[1233827635] 'process raft request' (duration: 583.086131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:06.225649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:25:05.642278Z","time spent":"583.355667ms","remote":"127.0.0.1:46514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:25:06.225403Z","caller":"traceutil/trace.go:171","msg":"trace[1822419750] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1938; }","duration":"587.976626ms","start":"2026-02-18T05:25:05.637421Z","end":"2026-02-18T05:25:06.225398Z","steps":["trace[1822419750] 'read index received' (duration: 79.432839ms)","trace[1822419750] 'applied index is now lower than readState.Index' (duration: 508.543297ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:25:06.225856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.21675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T05:25:06.225874Z","caller":"traceutil/trace.go:171","msg":"trace[846294107] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:1861; }","duration":"565.272912ms","start":"2026-02-18T05:25:05.660597Z","end":"2026-02-18T05:25:06.225869Z","steps":["trace[846294107] 'agreement among raft nodes before linearized reading' (duration: 565.21229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:06.225888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:25:05.66058Z","time spent":"565.304992ms","remote":"127.0.0.1:46308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-02-18T05:25:06.225982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.736511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-18T05:25:06.225998Z","caller":"traceutil/trace.go:171","msg":"trace[1169294695] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1861; }","duration":"157.775893ms","start":"2026-02-18T05:25:06.068217Z","end":"2026-02-18T05:25:06.225993Z","steps":["trace[1169294695] 'agreement among raft nodes before linearized reading' (duration: 157.736912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:06.226696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.304777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2830"} {"level":"info","ts":"2026-02-18T05:25:06.226718Z","caller":"traceutil/trace.go:171","msg":"trace[677121252] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1861; }","duration":"589.338528ms","start":"2026-02-18T05:25:05.637373Z","end":"2026-02-18T05:25:06.226712Z","steps":["trace[677121252] 'agreement among raft nodes before linearized reading' (duration: 588.415397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:25:06.226739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:25:05.637367Z","time spent":"589.368029ms","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2854,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "}