{"level":"warn","ts":"2026-02-18T05:19:11.117478Z","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:11.117794Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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:11.117998Z","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:11.118103Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-02-18T05:19:11.118179Z","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:11.119016Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-02-18T05:19:11.120264Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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:11.13011Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.411248ms"} {"level":"info","ts":"2026-02-18T05:19:11.158431Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-02-18T05:19:11.158656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T05:19:11.158729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-02-18T05:19:11.158785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T05:19:11.158841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-02-18T05:19:11.158917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-02-18T05:19:11.166761Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T05:19:11.175207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T05:19:11.18399Z","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:11.188431Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T05:19:11.188824Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T05:19:11.188994Z","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:11.189247Z","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:11.18928Z","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:11.191558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-02-18T05:19:11.192168Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-02-18T05:19:11.206953Z","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:11.208537Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T05:19:11.208586Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T05:19:11.208662Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-02-18T05:19:11.208672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-02-18T05:19:11.759261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T05:19:11.759405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T05:19:11.759471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-02-18T05:19:11.759485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-02-18T05:19:11.759491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-02-18T05:19:11.759499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-02-18T05:19:11.759505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-02-18T05:19:11.761715Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T05:19:11.76189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:19:11.762183Z","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:11.762297Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T05:19:11.762459Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T05:19:11.762489Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T05:19:11.770943Z","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:11.771122Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:11.771856Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:11.771914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T05:19:11.772406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"warn","ts":"2026-02-18T05:19:35.890005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.846307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-18T05:19:35.890126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.982567ms","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:19:35.890136Z","caller":"traceutil/trace.go:171","msg":"trace[739480599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"336.0212ms","start":"2026-02-18T05:19:35.554093Z","end":"2026-02-18T05:19:35.890115Z","steps":["trace[739480599] 'range keys from in-memory index tree' (duration: 335.760824ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:35.890158Z","caller":"traceutil/trace.go:171","msg":"trace[2115728367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"626.036019ms","start":"2026-02-18T05:19:35.264112Z","end":"2026-02-18T05:19:35.890148Z","steps":["trace[2115728367] 'range keys from in-memory index tree' (duration: 625.913496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:35.890191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.554075Z","time spent":"336.106603ms","remote":"127.0.0.1:43190","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:19:35.89019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.264096Z","time spent":"626.08498ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:19:35.890459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.780865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:19:35.890492Z","caller":"traceutil/trace.go:171","msg":"trace[1071177070] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:416; }","duration":"971.834346ms","start":"2026-02-18T05:19:34.918645Z","end":"2026-02-18T05:19:35.890479Z","steps":["trace[1071177070] 'range keys from in-memory index tree' (duration: 971.710363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:35.890517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:34.918627Z","time spent":"971.882287ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-02-18T05:19:35.890049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.409579ms","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:35.890628Z","caller":"traceutil/trace.go:171","msg":"trace[584286423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"743.011115ms","start":"2026-02-18T05:19:35.147605Z","end":"2026-02-18T05:19:35.890616Z","steps":["trace[584286423] 'range keys from in-memory index tree' (duration: 742.347328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:35.890667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.147586Z","time spent":"743.069646ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T05:19:35.890004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.942222ms","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:35.890718Z","caller":"traceutil/trace.go:171","msg":"trace[1599259616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"963.701202ms","start":"2026-02-18T05:19:34.927008Z","end":"2026-02-18T05:19:35.890709Z","steps":["trace[1599259616] 'range keys from in-memory index tree' (duration: 962.837009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:35.890752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:34.926998Z","time spent":"963.744324ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:36.427978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:19:36.930306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:19:37.13331Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.039964258s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T05:19:37.133587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.895331Z","time spent":"1.238252639s","remote":"127.0.0.1:42972","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T05:19:37.617917Z","caller":"traceutil/trace.go:171","msg":"trace[2066587514] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:431; }","duration":"1.689965343s","start":"2026-02-18T05:19:35.927932Z","end":"2026-02-18T05:19:37.617897Z","steps":["trace[2066587514] 'read index received' (duration: 1.205989624s)","trace[2066587514] 'applied index is now lower than readState.Index' (duration: 483.974709ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:37.618029Z","caller":"traceutil/trace.go:171","msg":"trace[116841966] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.721408617s","start":"2026-02-18T05:19:35.896611Z","end":"2026-02-18T05:19:37.618019Z","steps":["trace[116841966] 'process raft request' (duration: 1.720976967s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:37.618102Z","caller":"traceutil/trace.go:171","msg":"trace[1920374987] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.718100944s","start":"2026-02-18T05:19:35.899973Z","end":"2026-02-18T05:19:37.618074Z","steps":["trace[1920374987] 'process raft request' (duration: 1.717859758s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:37.618171Z","caller":"traceutil/trace.go:171","msg":"trace[1305707598] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.719432868s","start":"2026-02-18T05:19:35.89873Z","end":"2026-02-18T05:19:37.618163Z","steps":["trace[1305707598] 'process raft request' (duration: 1.719028818s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:37.618197Z","caller":"traceutil/trace.go:171","msg":"trace[1332481737] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"494.131856ms","start":"2026-02-18T05:19:37.12406Z","end":"2026-02-18T05:19:37.618192Z","steps":["trace[1332481737] 'process raft request' (duration: 493.805477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:37.618258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.690315632s","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:37.618276Z","caller":"traceutil/trace.go:171","msg":"trace[1545956915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"1.690366723s","start":"2026-02-18T05:19:35.927904Z","end":"2026-02-18T05:19:37.61827Z","steps":["trace[1545956915] 'agreement among raft nodes before linearized reading' (duration: 1.690321041s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:37.618291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.927892Z","time spent":"1.690394934s","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:37.618447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.896596Z","time spent":"1.721461388s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:37.618532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.899962Z","time spent":"1.718185785s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:37.61859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:35.898695Z","time spent":"1.719484349s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:37.618654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:37.124035Z","time spent":"494.181186ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:38.118877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176133,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:19:38.935434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.110657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:19:38.935576Z","caller":"traceutil/trace.go:171","msg":"trace[539820742] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"1.317569173s","start":"2026-02-18T05:19:37.617989Z","end":"2026-02-18T05:19:38.935558Z","steps":["trace[539820742] 'read index received' (duration: 779.465375ms)","trace[539820742] 'applied index is now lower than readState.Index' (duration: 538.101938ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:38.9356Z","caller":"traceutil/trace.go:171","msg":"trace[698905985] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.801257235s","start":"2026-02-18T05:19:37.134317Z","end":"2026-02-18T05:19:38.935575Z","steps":["trace[698905985] 'process raft request' (duration: 1.263118377s)","trace[698905985] 'compare' (duration: 535.781989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:38.935723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:37.134304Z","time spent":"1.801350338s","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:38.93574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.648147735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:19:38.935769Z","caller":"traceutil/trace.go:171","msg":"trace[954034299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"2.648202257s","start":"2026-02-18T05:19:36.287557Z","end":"2026-02-18T05:19:38.93576Z","steps":["trace[954034299] 'agreement among raft nodes before linearized reading' (duration: 2.648093434s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:38.935889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:36.287541Z","time spent":"2.648336331s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:19:38.935994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313283325s","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:38.936023Z","caller":"traceutil/trace.go:171","msg":"trace[706276812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"1.313335577s","start":"2026-02-18T05:19:37.622679Z","end":"2026-02-18T05:19:38.936015Z","steps":["trace[706276812] 'agreement among raft nodes before linearized reading' (duration: 1.313283796s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:38.936042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:37.622667Z","time spent":"1.313371988s","remote":"127.0.0.1:42952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:19:39.095937Z","caller":"traceutil/trace.go:171","msg":"trace[640482831] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"148.39351ms","start":"2026-02-18T05:19:38.947522Z","end":"2026-02-18T05:19:39.095915Z","steps":["trace[640482831] 'read index received' (duration: 114.028651ms)","trace[640482831] 'applied index is now lower than readState.Index' (duration: 34.363769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:39.096039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.494583ms","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:39.096043Z","caller":"traceutil/trace.go:171","msg":"trace[1545307936] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"150.980575ms","start":"2026-02-18T05:19:38.94504Z","end":"2026-02-18T05:19:39.096021Z","steps":["trace[1545307936] 'process raft request' (duration: 116.473972ms)","trace[1545307936] 'compare' (duration: 34.254517ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:39.096074Z","caller":"traceutil/trace.go:171","msg":"trace[566278735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"148.557944ms","start":"2026-02-18T05:19:38.947508Z","end":"2026-02-18T05:19:39.096066Z","steps":["trace[566278735] 'agreement among raft nodes before linearized reading' (duration: 148.483572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:43.42896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176218,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:19:43.929714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176218,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:19:44.4305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176218,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:19:44.480711Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.746167454s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:19:44.481039Z","caller":"traceutil/trace.go:171","msg":"trace[137973169] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"1.552991453s","start":"2026-02-18T05:19:42.928008Z","end":"2026-02-18T05:19:44.480999Z","steps":["trace[137973169] 'read index received' (duration: 1.552817588s)","trace[137973169] 'applied index is now lower than readState.Index' (duration: 172.485µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:44.481265Z","caller":"traceutil/trace.go:171","msg":"trace[1088983906] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"1.746816971s","start":"2026-02-18T05:19:42.734434Z","end":"2026-02-18T05:19:44.481251Z","steps":["trace[1088983906] 'process raft request' (duration: 1.746448662s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:44.481361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:42.734422Z","time spent":"1.746872012s","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:44.481587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553578367s","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:44.481652Z","caller":"traceutil/trace.go:171","msg":"trace[855733781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"1.553671999s","start":"2026-02-18T05:19:42.927968Z","end":"2026-02-18T05:19:44.48164Z","steps":["trace[855733781] 'agreement among raft nodes before linearized reading' (duration: 1.553582907s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:44.481681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:42.927948Z","time spent":"1.55372666s","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:44.481835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37362838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:19:44.481862Z","caller":"traceutil/trace.go:171","msg":"trace[1284503490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"1.373692382s","start":"2026-02-18T05:19:43.10816Z","end":"2026-02-18T05:19:44.481852Z","steps":["trace[1284503490] 'agreement among raft nodes before linearized reading' (duration: 1.37364369s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:44.481903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:43.108142Z","time spent":"1.373753963s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:19:44.482166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.538663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:19:44.482199Z","caller":"traceutil/trace.go:171","msg":"trace[296485491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:465; }","duration":"824.585925ms","start":"2026-02-18T05:19:43.657604Z","end":"2026-02-18T05:19:44.482189Z","steps":["trace[296485491] 'agreement among raft nodes before linearized reading' (duration: 824.526813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:44.482224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:43.657597Z","time spent":"824.621216ms","remote":"127.0.0.1:43190","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:19:44.482348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.297712ms","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:44.482373Z","caller":"traceutil/trace.go:171","msg":"trace[1303137955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"831.335123ms","start":"2026-02-18T05:19:43.65103Z","end":"2026-02-18T05:19:44.482365Z","steps":["trace[1303137955] 'agreement among raft nodes before linearized reading' (duration: 831.292142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:44.482396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:43.651024Z","time spent":"831.367454ms","remote":"127.0.0.1:43190","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:44.482512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.77157ms","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:19:44.482536Z","caller":"traceutil/trace.go:171","msg":"trace[403722159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"832.827522ms","start":"2026-02-18T05:19:43.649701Z","end":"2026-02-18T05:19:44.482529Z","steps":["trace[403722159] 'agreement among raft nodes before linearized reading' (duration: 832.78684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:44.482559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:43.649687Z","time spent":"832.866873ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:19:45.000264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:19:45.17131Z","caller":"traceutil/trace.go:171","msg":"trace[1675555473] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"676.660344ms","start":"2026-02-18T05:19:44.494453Z","end":"2026-02-18T05:19:45.171113Z","steps":["trace[1675555473] 'process raft request' (duration: 676.336106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:45.171441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:44.494429Z","time spent":"676.944571ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:45.237698Z","caller":"traceutil/trace.go:171","msg":"trace[1382926797] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:481; }","duration":"738.329726ms","start":"2026-02-18T05:19:44.499353Z","end":"2026-02-18T05:19:45.237683Z","steps":["trace[1382926797] 'read index received' (duration: 671.70465ms)","trace[1382926797] 'applied index is now lower than readState.Index' (duration: 66.624316ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:45.237892Z","caller":"traceutil/trace.go:171","msg":"trace[120979985] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"743.282011ms","start":"2026-02-18T05:19:44.494605Z","end":"2026-02-18T05:19:45.237887Z","steps":["trace[120979985] 'process raft request' (duration: 742.935572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:45.23797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:44.494598Z","time spent":"743.313271ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:45.238309Z","caller":"traceutil/trace.go:171","msg":"trace[1956378385] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"743.589908ms","start":"2026-02-18T05:19:44.494701Z","end":"2026-02-18T05:19:45.238291Z","steps":["trace[1956378385] 'process raft request' (duration: 742.923192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:45.23836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:44.494695Z","time spent":"743.64719ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:45.238553Z","caller":"traceutil/trace.go:171","msg":"trace[1942387498] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"742.398379ms","start":"2026-02-18T05:19:44.496145Z","end":"2026-02-18T05:19:45.238543Z","steps":["trace[1942387498] 'process raft request' (duration: 741.497646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:45.238598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:44.496136Z","time spent":"742.44174ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:45.238722Z","caller":"traceutil/trace.go:171","msg":"trace[1379134906] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"395.912302ms","start":"2026-02-18T05:19:44.842802Z","end":"2026-02-18T05:19:45.238714Z","steps":["trace[1379134906] 'process raft request' (duration: 394.858286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:45.238783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:44.842779Z","time spent":"395.983124ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:45.239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.876126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:19:45.23902Z","caller":"traceutil/trace.go:171","msg":"trace[707870689] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:470; }","duration":"314.949698ms","start":"2026-02-18T05:19:44.924065Z","end":"2026-02-18T05:19:45.239015Z","steps":["trace[707870689] 'agreement among raft nodes before linearized reading' (duration: 314.919068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:45.239045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:44.924044Z","time spent":"314.996429ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-02-18T05:19:45.238942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.586158ms","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:45.239586Z","caller":"traceutil/trace.go:171","msg":"trace[927666620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"740.246335ms","start":"2026-02-18T05:19:44.499333Z","end":"2026-02-18T05:19:45.239579Z","steps":["trace[927666620] 'agreement among raft nodes before linearized reading' (duration: 739.570889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:45.239609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:44.499326Z","time spent":"740.277567ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:19:48.008524Z","caller":"traceutil/trace.go:171","msg":"trace[1475434989] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"275.876069ms","start":"2026-02-18T05:19:47.73263Z","end":"2026-02-18T05:19:48.008506Z","steps":["trace[1475434989] 'process raft request' (duration: 275.763357ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:53.318305Z","caller":"traceutil/trace.go:171","msg":"trace[50483382] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"390.283483ms","start":"2026-02-18T05:19:52.92801Z","end":"2026-02-18T05:19:53.318294Z","steps":["trace[50483382] 'read index received' (duration: 390.13965ms)","trace[50483382] 'applied index is now lower than readState.Index' (duration: 143.263µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:53.318486Z","caller":"traceutil/trace.go:171","msg":"trace[435585193] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"582.320175ms","start":"2026-02-18T05:19:52.73616Z","end":"2026-02-18T05:19:53.31848Z","steps":["trace[435585193] 'process raft request' (duration: 582.033328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:53.318547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:52.736141Z","time spent":"582.359416ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:53.318642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.633282ms","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:53.318663Z","caller":"traceutil/trace.go:171","msg":"trace[1118316645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"390.653332ms","start":"2026-02-18T05:19:52.928003Z","end":"2026-02-18T05:19:53.318656Z","steps":["trace[1118316645] 'agreement among raft nodes before linearized reading' (duration: 390.623731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:53.318675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:52.927951Z","time spent":"390.720673ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:54.894399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566702487s","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:54.894479Z","caller":"traceutil/trace.go:171","msg":"trace[22815678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"1.56683245s","start":"2026-02-18T05:19:53.327623Z","end":"2026-02-18T05:19:54.894455Z","steps":["trace[22815678] 'range keys from in-memory index tree' (duration: 1.566619254s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:54.894519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:53.327603Z","time spent":"1.566905941s","remote":"127.0.0.1:42952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:54.895148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:54.153987Z","time spent":"741.157908ms","remote":"127.0.0.1:53906","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:19:55.189893Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.854984537s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T05:19:55.190498Z","caller":"traceutil/trace.go:171","msg":"trace[607932158] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.855673446s","start":"2026-02-18T05:19:53.334802Z","end":"2026-02-18T05:19:55.190476Z","steps":["trace[607932158] 'process raft request' (duration: 1.85542853s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:55.191243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:53.334794Z","time spent":"1.855765588s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:55.209196Z","caller":"traceutil/trace.go:171","msg":"trace[589586757] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.874374374s","start":"2026-02-18T05:19:53.334803Z","end":"2026-02-18T05:19:55.209177Z","steps":["trace[589586757] 'process raft request' (duration: 1.869272826s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:55.209311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:53.334779Z","time spent":"1.874469826s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:55.210688Z","caller":"traceutil/trace.go:171","msg":"trace[621888796] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.875815979s","start":"2026-02-18T05:19:53.334856Z","end":"2026-02-18T05:19:55.210672Z","steps":["trace[621888796] 'process raft request' (duration: 1.874117616s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:55.210774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:53.334849Z","time spent":"1.875884561s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:55.211194Z","caller":"traceutil/trace.go:171","msg":"trace[650930965] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:506; }","duration":"306.26004ms","start":"2026-02-18T05:19:54.904922Z","end":"2026-02-18T05:19:55.211182Z","steps":["trace[650930965] 'read index received' (duration: 285.291186ms)","trace[650930965] 'applied index is now lower than readState.Index' (duration: 20.967884ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:19:55.211351Z","caller":"traceutil/trace.go:171","msg":"trace[492633944] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.876476725s","start":"2026-02-18T05:19:53.334864Z","end":"2026-02-18T05:19:55.211341Z","steps":["trace[492633944] 'process raft request' (duration: 1.876243639s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:55.211476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:53.334861Z","time spent":"1.876574337s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:19:55.211617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.692771ms","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:55.211647Z","caller":"traceutil/trace.go:171","msg":"trace[265580067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"306.754012ms","start":"2026-02-18T05:19:54.904884Z","end":"2026-02-18T05:19:55.211638Z","steps":["trace[265580067] 'agreement among raft nodes before linearized reading' (duration: 306.703091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:55.211674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:54.904874Z","time spent":"306.794713ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:19:55.212065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.745987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:19:55.212103Z","caller":"traceutil/trace.go:171","msg":"trace[1735445339] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:492; }","duration":"287.82782ms","start":"2026-02-18T05:19:54.924265Z","end":"2026-02-18T05:19:55.212093Z","steps":["trace[1735445339] 'agreement among raft nodes before linearized reading' (duration: 287.745447ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:55.943912Z","caller":"traceutil/trace.go:171","msg":"trace[355717748] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"185.561601ms","start":"2026-02-18T05:19:55.758331Z","end":"2026-02-18T05:19:55.943893Z","steps":["trace[355717748] 'process raft request' (duration: 185.334746ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:56.022583Z","caller":"traceutil/trace.go:171","msg":"trace[1154454157] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"257.161203ms","start":"2026-02-18T05:19:55.765399Z","end":"2026-02-18T05:19:56.02256Z","steps":["trace[1154454157] 'process raft request' (duration: 256.65023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:59.184723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.954737ms","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:19:59.18485Z","caller":"traceutil/trace.go:171","msg":"trace[659368160] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:535; }","duration":"181.10049ms","start":"2026-02-18T05:19:59.003732Z","end":"2026-02-18T05:19:59.184832Z","steps":["trace[659368160] 'range keys from in-memory index tree' (duration: 180.940075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:59.185433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.628315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:19:59.185631Z","caller":"traceutil/trace.go:171","msg":"trace[1574219378] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"257.573232ms","start":"2026-02-18T05:19:58.928035Z","end":"2026-02-18T05:19:59.185609Z","steps":["trace[1574219378] 'read index received' (duration: 24.961µs)","trace[1574219378] 'applied index is now lower than readState.Index' (duration: 257.546211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:19:59.185722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.676615ms","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:59.185753Z","caller":"traceutil/trace.go:171","msg":"trace[2118652079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"257.732947ms","start":"2026-02-18T05:19:58.928006Z","end":"2026-02-18T05:19:59.185739Z","steps":["trace[2118652079] 'agreement among raft nodes before linearized reading' (duration: 257.662895ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:19:59.18583Z","caller":"traceutil/trace.go:171","msg":"trace[1950889808] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"300.881016ms","start":"2026-02-18T05:19:58.884932Z","end":"2026-02-18T05:19:59.185813Z","steps":["trace[1950889808] 'compare' (duration: 294.892027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:19:59.186024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:19:58.884924Z","time spent":"300.963997ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:19:59.214247Z","caller":"traceutil/trace.go:171","msg":"trace[2106449422] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"139.837117ms","start":"2026-02-18T05:19:59.074391Z","end":"2026-02-18T05:19:59.214228Z","steps":["trace[2106449422] 'process raft request' (duration: 139.725254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:00.084725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.051144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:00.08483Z","caller":"traceutil/trace.go:171","msg":"trace[975035921] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"155.865628ms","start":"2026-02-18T05:19:59.928944Z","end":"2026-02-18T05:20:00.084809Z","steps":["trace[975035921] 'read index received' (duration: 26.96µs)","trace[975035921] 'applied index is now lower than readState.Index' (duration: 155.837698ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:00.084975Z","caller":"traceutil/trace.go:171","msg":"trace[266801715] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"192.355211ms","start":"2026-02-18T05:19:59.892611Z","end":"2026-02-18T05:20:00.084966Z","steps":["trace[266801715] 'process raft request' (duration: 21.977079ms)","trace[266801715] 'compare' (duration: 169.912341ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:00.085118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.171587ms","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:00.085141Z","caller":"traceutil/trace.go:171","msg":"trace[1980864609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"156.224047ms","start":"2026-02-18T05:19:59.92891Z","end":"2026-02-18T05:20:00.085134Z","steps":["trace[1980864609] 'agreement among raft nodes before linearized reading' (duration: 156.181496ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:00.163015Z","caller":"traceutil/trace.go:171","msg":"trace[801269143] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"173.607652ms","start":"2026-02-18T05:19:59.989378Z","end":"2026-02-18T05:20:00.162985Z","steps":["trace[801269143] 'process raft request' (duration: 173.473308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:00.449766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.219078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:00.449878Z","caller":"traceutil/trace.go:171","msg":"trace[965613273] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:554; }","duration":"176.409013ms","start":"2026-02-18T05:20:00.273443Z","end":"2026-02-18T05:20:00.449852Z","steps":["trace[965613273] 'range keys from in-memory index tree' (duration: 176.072804ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:00.972205Z","caller":"traceutil/trace.go:171","msg":"trace[311586344] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"155.620363ms","start":"2026-02-18T05:20:00.816562Z","end":"2026-02-18T05:20:00.972182Z","steps":["trace[311586344] 'process raft request' (duration: 155.459819ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:01.450402Z","caller":"traceutil/trace.go:171","msg":"trace[1963967778] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"577.470643ms","start":"2026-02-18T05:20:00.872895Z","end":"2026-02-18T05:20:01.450365Z","steps":["trace[1963967778] 'read index received' (duration: 99.083118ms)","trace[1963967778] 'applied index is now lower than readState.Index' (duration: 478.386255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:01.451337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.393037ms","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:01.451492Z","caller":"traceutil/trace.go:171","msg":"trace[757479867] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:557; }","duration":"578.777006ms","start":"2026-02-18T05:20:00.872684Z","end":"2026-02-18T05:20:01.451462Z","steps":["trace[757479867] 'agreement among raft nodes before linearized reading' (duration: 578.375896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.451552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:00.87259Z","time spent":"578.949391ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-18T05:20:01.451861Z","caller":"traceutil/trace.go:171","msg":"trace[1929652714] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"626.996032ms","start":"2026-02-18T05:20:00.824841Z","end":"2026-02-18T05:20:01.451837Z","steps":["trace[1929652714] 'process raft request' (duration: 625.380362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.452024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:00.824833Z","time spent":"627.094165ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18060,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:01.666599Z","caller":"traceutil/trace.go:171","msg":"trace[280658365] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"216.076945ms","start":"2026-02-18T05:20:01.45047Z","end":"2026-02-18T05:20:01.666547Z","steps":["trace[280658365] 'read index received' (duration: 215.807329ms)","trace[280658365] 'applied index is now lower than readState.Index' (duration: 268.066µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:01.667036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.043461ms","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:01.667096Z","caller":"traceutil/trace.go:171","msg":"trace[782297455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"417.175594ms","start":"2026-02-18T05:20:01.249905Z","end":"2026-02-18T05:20:01.667081Z","steps":["trace[782297455] 'agreement among raft nodes before linearized reading' (duration: 416.837406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.667146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.249883Z","time spent":"417.252857ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T05:20:01.667489Z","caller":"traceutil/trace.go:171","msg":"trace[1537346766] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"593.292019ms","start":"2026-02-18T05:20:01.074184Z","end":"2026-02-18T05:20:01.667476Z","steps":["trace[1537346766] 'process raft request' (duration: 592.026248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.667574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.074154Z","time spent":"593.369761ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:20:01.66769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.933683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-18T05:20:01.667716Z","caller":"traceutil/trace.go:171","msg":"trace[956886756] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:558; }","duration":"395.976214ms","start":"2026-02-18T05:20:01.27173Z","end":"2026-02-18T05:20:01.667706Z","steps":["trace[956886756] 'agreement among raft nodes before linearized reading' (duration: 395.907332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.667739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.27172Z","time spent":"396.013835ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:01.668214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.299012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:20:01.668242Z","caller":"traceutil/trace.go:171","msg":"trace[305377510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"418.342403ms","start":"2026-02-18T05:20:01.249893Z","end":"2026-02-18T05:20:01.668235Z","steps":["trace[305377510] 'agreement among raft nodes before linearized reading' (duration: 418.276301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.668259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.249884Z","time spent":"418.371014ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:01.668425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.556228ms","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:01.668443Z","caller":"traceutil/trace.go:171","msg":"trace[1581943262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"418.60392ms","start":"2026-02-18T05:20:01.249833Z","end":"2026-02-18T05:20:01.668437Z","steps":["trace[1581943262] 'agreement among raft nodes before linearized reading' (duration: 418.548399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.668459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.249818Z","time spent":"418.63725ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:20:01.671524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.533178ms","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:01.671607Z","caller":"traceutil/trace.go:171","msg":"trace[917839685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"432.681892ms","start":"2026-02-18T05:20:01.238902Z","end":"2026-02-18T05:20:01.671584Z","steps":["trace[917839685] 'agreement among raft nodes before linearized reading' (duration: 427.80353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.671652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.238874Z","time spent":"432.770124ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T05:20:01.883019Z","caller":"traceutil/trace.go:171","msg":"trace[1230449115] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"406.275742ms","start":"2026-02-18T05:20:01.476663Z","end":"2026-02-18T05:20:01.882939Z","steps":["trace[1230449115] 'process raft request' (duration: 405.503582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:01.883214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.476637Z","time spent":"406.486067ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:02.247989Z","caller":"traceutil/trace.go:171","msg":"trace[1564100111] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"570.791507ms","start":"2026-02-18T05:20:01.677162Z","end":"2026-02-18T05:20:02.247954Z","steps":["trace[1564100111] 'process raft request' (duration: 503.691159ms)","trace[1564100111] 'compare' (duration: 66.78169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:02.248213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.677141Z","time spent":"570.95102ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:02.248334Z","caller":"traceutil/trace.go:171","msg":"trace[1346571462] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"567.983806ms","start":"2026-02-18T05:20:01.68032Z","end":"2026-02-18T05:20:02.248304Z","steps":["trace[1346571462] 'process raft request' (duration: 567.543795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.248519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.680285Z","time spent":"568.118859ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:02.251972Z","caller":"traceutil/trace.go:171","msg":"trace[1784565311] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:579; }","duration":"571.530255ms","start":"2026-02-18T05:20:01.680428Z","end":"2026-02-18T05:20:02.251959Z","steps":["trace[1784565311] 'read index received' (duration: 202.111445ms)","trace[1784565311] 'applied index is now lower than readState.Index' (duration: 369.41691ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:02.252682Z","caller":"traceutil/trace.go:171","msg":"trace[1623938031] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"571.913794ms","start":"2026-02-18T05:20:01.68076Z","end":"2026-02-18T05:20:02.252673Z","steps":["trace[1623938031] 'process raft request' (duration: 567.475543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.252783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.680751Z","time spent":"571.965005ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:02.252903Z","caller":"traceutil/trace.go:171","msg":"trace[1952583858] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"571.860863ms","start":"2026-02-18T05:20:01.681035Z","end":"2026-02-18T05:20:02.252896Z","steps":["trace[1952583858] 'process raft request' (duration: 570.840338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.252935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.681025Z","time spent":"571.893044ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:02.253016Z","caller":"traceutil/trace.go:171","msg":"trace[1657124542] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"571.867444ms","start":"2026-02-18T05:20:01.68114Z","end":"2026-02-18T05:20:02.253007Z","steps":["trace[1657124542] 'process raft request' (duration: 570.784987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.253051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.681132Z","time spent":"571.897694ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:02.253213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.782826ms","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:02.253236Z","caller":"traceutil/trace.go:171","msg":"trace[923020904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:564; }","duration":"572.826747ms","start":"2026-02-18T05:20:01.680402Z","end":"2026-02-18T05:20:02.253229Z","steps":["trace[923020904] 'agreement among raft nodes before linearized reading' (duration: 572.719234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.253262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.680395Z","time spent":"572.857808ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-18T05:20:02.506487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.453524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:02.506856Z","caller":"traceutil/trace.go:171","msg":"trace[2105765735] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"244.740513ms","start":"2026-02-18T05:20:02.262098Z","end":"2026-02-18T05:20:02.506839Z","steps":["trace[2105765735] 'process raft request' (duration: 244.575589ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:02.506994Z","caller":"traceutil/trace.go:171","msg":"trace[286497348] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"240.301031ms","start":"2026-02-18T05:20:02.266664Z","end":"2026-02-18T05:20:02.506965Z","steps":["trace[286497348] 'process raft request' (duration: 240.135507ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:02.507095Z","caller":"traceutil/trace.go:171","msg":"trace[838263722] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"601.770282ms","start":"2026-02-18T05:20:01.905316Z","end":"2026-02-18T05:20:02.507086Z","steps":["trace[838263722] 'process raft request' (duration: 492.653751ms)","trace[838263722] 'compare' (duration: 108.192587ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:02.507169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.905293Z","time spent":"601.831953ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:02.507988Z","caller":"traceutil/trace.go:171","msg":"trace[1415666401] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"255.972942ms","start":"2026-02-18T05:20:02.252006Z","end":"2026-02-18T05:20:02.507979Z","steps":["trace[1415666401] 'read index received' (duration: 145.977969ms)","trace[1415666401] 'applied index is now lower than readState.Index' (duration: 109.993723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:02.508825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.916535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-18T05:20:02.508867Z","caller":"traceutil/trace.go:171","msg":"trace[991212834] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"236.969426ms","start":"2026-02-18T05:20:02.271886Z","end":"2026-02-18T05:20:02.508855Z","steps":["trace[991212834] 'agreement among raft nodes before linearized reading' (duration: 236.817393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.508963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.645923ms","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:02.508985Z","caller":"traceutil/trace.go:171","msg":"trace[536055813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"580.690723ms","start":"2026-02-18T05:20:01.928288Z","end":"2026-02-18T05:20:02.508979Z","steps":["trace[536055813] 'agreement among raft nodes before linearized reading' (duration: 580.655883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.509008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:01.928279Z","time spent":"580.724505ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:02.509048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.770324ms","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:02.509098Z","caller":"traceutil/trace.go:171","msg":"trace[1921317771] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"434.886787ms","start":"2026-02-18T05:20:02.074203Z","end":"2026-02-18T05:20:02.50909Z","steps":["trace[1921317771] 'agreement among raft nodes before linearized reading' (duration: 433.930514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:02.509131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:02.074174Z","time spent":"434.950029ms","remote":"127.0.0.1:43002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-18T05:20:02.696399Z","caller":"traceutil/trace.go:171","msg":"trace[1090099325] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"172.247857ms","start":"2026-02-18T05:20:02.524123Z","end":"2026-02-18T05:20:02.696371Z","steps":["trace[1090099325] 'process raft request' (duration: 162.299249ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:07.775994Z","caller":"traceutil/trace.go:171","msg":"trace[1655427576] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"258.31599ms","start":"2026-02-18T05:20:07.517666Z","end":"2026-02-18T05:20:07.775982Z","steps":["trace[1655427576] 'process raft request' (duration: 258.227698ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.360826Z","caller":"traceutil/trace.go:171","msg":"trace[23310793] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"446.165559ms","start":"2026-02-18T05:20:09.914635Z","end":"2026-02-18T05:20:10.360801Z","steps":["trace[23310793] 'process raft request' (duration: 386.317312ms)","trace[23310793] 'compare' (duration: 59.533949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:10.360996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.914606Z","time spent":"446.303253ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4827,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:10.420622Z","caller":"traceutil/trace.go:171","msg":"trace[345815342] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"504.966771ms","start":"2026-02-18T05:20:09.915632Z","end":"2026-02-18T05:20:10.420599Z","steps":["trace[345815342] 'process raft request' (duration: 504.768475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.420725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:09.915617Z","time spent":"505.054553ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:10.649817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.302214ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T05:20:10.649882Z","caller":"traceutil/trace.go:171","msg":"trace[1833406021] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"302.186038ms","start":"2026-02-18T05:20:10.347684Z","end":"2026-02-18T05:20:10.64987Z","steps":["trace[1833406021] 'read index received' (duration: 72.676577ms)","trace[1833406021] 'applied index is now lower than readState.Index' (duration: 229.508571ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:10.650253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.561728ms","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:10.650269Z","caller":"traceutil/trace.go:171","msg":"trace[1957311380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"302.607349ms","start":"2026-02-18T05:20:10.347657Z","end":"2026-02-18T05:20:10.650264Z","steps":["trace[1957311380] 'agreement among raft nodes before linearized reading' (duration: 302.531386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.650286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.347644Z","time spent":"302.638451ms","remote":"127.0.0.1:43190","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:10.650741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.889061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:20:10.65076Z","caller":"traceutil/trace.go:171","msg":"trace[562618565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"289.922432ms","start":"2026-02-18T05:20:10.360831Z","end":"2026-02-18T05:20:10.650754Z","steps":["trace[562618565] 'agreement among raft nodes before linearized reading' (duration: 289.86623ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.650877Z","caller":"traceutil/trace.go:171","msg":"trace[592014201] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"279.574753ms","start":"2026-02-18T05:20:10.371296Z","end":"2026-02-18T05:20:10.650871Z","steps":["trace[592014201] 'process raft request' (duration: 279.266865ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.650994Z","caller":"traceutil/trace.go:171","msg":"trace[205530457] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"277.492231ms","start":"2026-02-18T05:20:10.373497Z","end":"2026-02-18T05:20:10.650989Z","steps":["trace[205530457] 'process raft request' (duration: 277.157862ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:10.651091Z","caller":"traceutil/trace.go:171","msg":"trace[1160013967] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"276.960888ms","start":"2026-02-18T05:20:10.374125Z","end":"2026-02-18T05:20:10.651086Z","steps":["trace[1160013967] 'process raft request' (duration: 276.550088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.651182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.186048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T05:20:10.651197Z","caller":"traceutil/trace.go:171","msg":"trace[1255446385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"274.202199ms","start":"2026-02-18T05:20:10.376991Z","end":"2026-02-18T05:20:10.651193Z","steps":["trace[1255446385] 'agreement among raft nodes before linearized reading' (duration: 274.161268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.651273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.383694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-bqddg\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-18T05:20:10.651287Z","caller":"traceutil/trace.go:171","msg":"trace[717724104] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-bqddg; range_end:; response_count:1; response_revision:643; }","duration":"282.407134ms","start":"2026-02-18T05:20:10.368874Z","end":"2026-02-18T05:20:10.651281Z","steps":["trace[717724104] 'agreement among raft nodes before linearized reading' (duration: 282.373053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:10.651376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.678646ms","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.651389Z","caller":"traceutil/trace.go:171","msg":"trace[308761946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"287.716516ms","start":"2026-02-18T05:20:10.363668Z","end":"2026-02-18T05:20:10.651385Z","steps":["trace[308761946] 'agreement among raft nodes before linearized reading' (duration: 287.669655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:11.922061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.961407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:11.922172Z","caller":"traceutil/trace.go:171","msg":"trace[2129095379] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.260275612s","start":"2026-02-18T05:20:10.661877Z","end":"2026-02-18T05:20:11.922153Z","steps":["trace[2129095379] 'process raft request' (duration: 638.13486ms)","trace[2129095379] 'compare' (duration: 621.618329ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:11.92223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.661853Z","time spent":"1.260349134s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.010313Z","caller":"traceutil/trace.go:171","msg":"trace[1159152933] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.348265903s","start":"2026-02-18T05:20:10.662032Z","end":"2026-02-18T05:20:12.010298Z","steps":["trace[1159152933] 'process raft request' (duration: 1.348124499s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.010427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.662025Z","time spent":"1.348346794s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.010609Z","caller":"traceutil/trace.go:171","msg":"trace[1374379688] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.345913484s","start":"2026-02-18T05:20:10.664677Z","end":"2026-02-18T05:20:12.010591Z","steps":["trace[1374379688] 'process raft request' (duration: 1.345567285s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.010713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.664668Z","time spent":"1.345990476s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.010929Z","caller":"traceutil/trace.go:171","msg":"trace[565022184] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.34617411s","start":"2026-02-18T05:20:10.664748Z","end":"2026-02-18T05:20:12.010922Z","steps":["trace[565022184] 'process raft request' (duration: 1.34578683s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.010968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.66473Z","time spent":"1.346214271s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.01109Z","caller":"traceutil/trace.go:171","msg":"trace[827560730] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.341889864s","start":"2026-02-18T05:20:10.669191Z","end":"2026-02-18T05:20:12.011081Z","steps":["trace[827560730] 'process raft request' (duration: 1.341623287s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.011125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.669184Z","time spent":"1.341922685s","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.011163Z","caller":"traceutil/trace.go:171","msg":"trace[1132236766] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:667; }","duration":"1.100480496s","start":"2026-02-18T05:20:10.910675Z","end":"2026-02-18T05:20:12.011155Z","steps":["trace[1132236766] 'read index received' (duration: 389.346419ms)","trace[1132236766] 'applied index is now lower than readState.Index' (duration: 711.133437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:12.011292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085377177s","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:12.011306Z","caller":"traceutil/trace.go:171","msg":"trace[211891041] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.338930839s","start":"2026-02-18T05:20:10.672368Z","end":"2026-02-18T05:20:12.011299Z","steps":["trace[211891041] 'process raft request' (duration: 1.33856825s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:12.011314Z","caller":"traceutil/trace.go:171","msg":"trace[378406080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"1.085414839s","start":"2026-02-18T05:20:10.925892Z","end":"2026-02-18T05:20:12.011307Z","steps":["trace[378406080] 'agreement among raft nodes before linearized reading' (duration: 1.085382448s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.011333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.925881Z","time spent":"1.085448879s","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:12.011343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.672362Z","time spent":"1.338960591s","remote":"127.0.0.1:43442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:12.01146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100785443s","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:20:12.011475Z","caller":"traceutil/trace.go:171","msg":"trace[1087160100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:649; }","duration":"1.100825344s","start":"2026-02-18T05:20:10.910646Z","end":"2026-02-18T05:20:12.011471Z","steps":["trace[1087160100] 'agreement among raft nodes before linearized reading' (duration: 1.100760582s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.011488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:10.910624Z","time spent":"1.100860645s","remote":"127.0.0.1:43190","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:12.54739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.1346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:12.547459Z","caller":"traceutil/trace.go:171","msg":"trace[221461153] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"522.967651ms","start":"2026-02-18T05:20:12.024478Z","end":"2026-02-18T05:20:12.547445Z","steps":["trace[221461153] 'read index received' (duration: 64.680718ms)","trace[221461153] 'applied index is now lower than readState.Index' (duration: 458.286203ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:12.547502Z","caller":"traceutil/trace.go:171","msg":"trace[556614734] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"523.263598ms","start":"2026-02-18T05:20:12.024232Z","end":"2026-02-18T05:20:12.547496Z","steps":["trace[556614734] 'process raft request' (duration: 64.978945ms)","trace[556614734] 'compare' (duration: 457.900144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:12.547531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.02422Z","time spent":"523.296899ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:20:12.547729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.245768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8hzjq\" ","response":"range_response_count:1 size:4812"} {"level":"info","ts":"2026-02-18T05:20:12.547748Z","caller":"traceutil/trace.go:171","msg":"trace[710179045] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-8hzjq; range_end:; response_count:1; response_revision:650; }","duration":"523.285909ms","start":"2026-02-18T05:20:12.024456Z","end":"2026-02-18T05:20:12.547742Z","steps":["trace[710179045] 'agreement among raft nodes before linearized reading' (duration: 523.190656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.547767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.02445Z","time spent":"523.312519ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4835,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8hzjq\" "} {"level":"info","ts":"2026-02-18T05:20:12.569968Z","caller":"traceutil/trace.go:171","msg":"trace[44738496] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"537.868763ms","start":"2026-02-18T05:20:12.032088Z","end":"2026-02-18T05:20:12.569957Z","steps":["trace[44738496] 'process raft request' (duration: 537.841563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.570078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.032083Z","time spent":"537.922844ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.570337Z","caller":"traceutil/trace.go:171","msg":"trace[1658003397] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"545.816512ms","start":"2026-02-18T05:20:12.024514Z","end":"2026-02-18T05:20:12.57033Z","steps":["trace[1658003397] 'process raft request' (duration: 545.271449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.570373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.02451Z","time spent":"545.843973ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.570512Z","caller":"traceutil/trace.go:171","msg":"trace[1682881293] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"545.861393ms","start":"2026-02-18T05:20:12.024645Z","end":"2026-02-18T05:20:12.570506Z","steps":["trace[1682881293] 'process raft request' (duration: 545.199046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.570544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.024641Z","time spent":"545.885674ms","remote":"127.0.0.1:43442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.570934Z","caller":"traceutil/trace.go:171","msg":"trace[1852318111] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"545.889524ms","start":"2026-02-18T05:20:12.025037Z","end":"2026-02-18T05:20:12.570927Z","steps":["trace[1852318111] 'process raft request' (duration: 544.848848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.570974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.025031Z","time spent":"545.921955ms","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1016,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:12.573556Z","caller":"traceutil/trace.go:171","msg":"trace[2083259264] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"548.442388ms","start":"2026-02-18T05:20:12.025087Z","end":"2026-02-18T05:20:12.57353Z","steps":["trace[2083259264] 'process raft request' (duration: 544.823788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.573673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.025084Z","time spent":"548.52425ms","remote":"127.0.0.1:43204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1611,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:12.574418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.517665ms","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:12.574439Z","caller":"traceutil/trace.go:171","msg":"trace[157930838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"543.548886ms","start":"2026-02-18T05:20:12.030884Z","end":"2026-02-18T05:20:12.574433Z","steps":["trace[157930838] 'agreement among raft nodes before linearized reading' (duration: 543.511495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.574454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.030879Z","time spent":"543.571696ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:12.574541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.597987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:12.574554Z","caller":"traceutil/trace.go:171","msg":"trace[1159240642] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:655; }","duration":"176.640809ms","start":"2026-02-18T05:20:12.397909Z","end":"2026-02-18T05:20:12.57455Z","steps":["trace[1159240642] 'agreement among raft nodes before linearized reading' (duration: 176.616708ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:12.970727Z","caller":"traceutil/trace.go:171","msg":"trace[85190051] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"420.780134ms","start":"2026-02-18T05:20:12.549928Z","end":"2026-02-18T05:20:12.970708Z","steps":["trace[85190051] 'process raft request' (duration: 420.649061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:12.970844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.549915Z","time spent":"420.865936ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4809,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:13.356624Z","caller":"traceutil/trace.go:171","msg":"trace[776709599] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"774.453331ms","start":"2026-02-18T05:20:12.582152Z","end":"2026-02-18T05:20:13.356605Z","steps":["trace[776709599] 'process raft request' (duration: 774.338618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:13.356763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.582137Z","time spent":"774.544574ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:13.503987Z","caller":"traceutil/trace.go:171","msg":"trace[1314555084] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"526.026137ms","start":"2026-02-18T05:20:12.977943Z","end":"2026-02-18T05:20:13.503969Z","steps":["trace[1314555084] 'process raft request' (duration: 455.856242ms)","trace[1314555084] 'compare' (duration: 70.009551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:13.504076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.977927Z","time spent":"526.10619ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:13.505165Z","caller":"traceutil/trace.go:171","msg":"trace[2042205239] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"527.105594ms","start":"2026-02-18T05:20:12.978052Z","end":"2026-02-18T05:20:13.505158Z","steps":["trace[2042205239] 'process raft request' (duration: 525.873933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:13.505209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.978048Z","time spent":"527.137916ms","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":743,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:13.505611Z","caller":"traceutil/trace.go:171","msg":"trace[690877763] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"526.55299ms","start":"2026-02-18T05:20:12.979004Z","end":"2026-02-18T05:20:13.505557Z","steps":["trace[690877763] 'process raft request' (duration: 526.090789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:13.505756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.978983Z","time spent":"526.690603ms","remote":"127.0.0.1:43204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:13.506141Z","caller":"traceutil/trace.go:171","msg":"trace[811378365] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:681; }","duration":"524.96109ms","start":"2026-02-18T05:20:12.981142Z","end":"2026-02-18T05:20:13.506103Z","steps":["trace[811378365] 'read index received' (duration: 376.047105ms)","trace[811378365] 'applied index is now lower than readState.Index' (duration: 148.862723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:13.506677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.529974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8hzjq\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-18T05:20:13.5067Z","caller":"traceutil/trace.go:171","msg":"trace[1026567318] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-8hzjq; range_end:; response_count:1; response_revision:662; }","duration":"525.564015ms","start":"2026-02-18T05:20:12.981125Z","end":"2026-02-18T05:20:13.506689Z","steps":["trace[1026567318] 'agreement among raft nodes before linearized reading' (duration: 525.134224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:13.506715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.981118Z","time spent":"525.593816ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4847,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-8hzjq\" "} {"level":"info","ts":"2026-02-18T05:20:13.507274Z","caller":"traceutil/trace.go:171","msg":"trace[1968188524] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"525.624376ms","start":"2026-02-18T05:20:12.981637Z","end":"2026-02-18T05:20:13.507262Z","steps":["trace[1968188524] 'process raft request' (duration: 524.377995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:13.507328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.981628Z","time spent":"525.667187ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:20:13.507746Z","caller":"traceutil/trace.go:171","msg":"trace[1990319533] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"528.593432ms","start":"2026-02-18T05:20:12.979138Z","end":"2026-02-18T05:20:13.507731Z","steps":["trace[1990319533] 'process raft request' (duration: 525.982796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:13.510434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:12.979132Z","time spent":"528.630213ms","remote":"127.0.0.1:43442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:19.429887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176649,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:20:20.341517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337525674s","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:20.341643Z","caller":"traceutil/trace.go:171","msg":"trace[2113912644] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:683; }","duration":"1.337662517s","start":"2026-02-18T05:20:19.003959Z","end":"2026-02-18T05:20:20.341622Z","steps":["trace[2113912644] 'range keys from in-memory index tree' (duration: 1.337508373s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.342847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.911597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:20.342913Z","caller":"traceutil/trace.go:171","msg":"trace[953309664] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"1.413928784s","start":"2026-02-18T05:20:18.928973Z","end":"2026-02-18T05:20:20.342902Z","steps":["trace[953309664] 'read index received' (duration: 667.846883ms)","trace[953309664] 'applied index is now lower than readState.Index' (duration: 746.080901ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:20.343097Z","caller":"traceutil/trace.go:171","msg":"trace[597292781] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"1.469984317s","start":"2026-02-18T05:20:18.8731Z","end":"2026-02-18T05:20:20.343084Z","steps":["trace[597292781] 'process raft request' (duration: 723.772023ms)","trace[597292781] 'compare' (duration: 745.301141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:20.343165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:18.873056Z","time spent":"1.470074889s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:20.343347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414378026s","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.343455Z","caller":"traceutil/trace.go:171","msg":"trace[14601529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"1.414440508s","start":"2026-02-18T05:20:18.928927Z","end":"2026-02-18T05:20:20.343367Z","steps":["trace[14601529] 'agreement among raft nodes before linearized reading' (duration: 1.414388367s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:20.34351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:18.928904Z","time spent":"1.414596641s","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:21.247678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.253302ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T05:20:21.247753Z","caller":"traceutil/trace.go:171","msg":"trace[506456968] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"904.80624ms","start":"2026-02-18T05:20:20.342934Z","end":"2026-02-18T05:20:21.24774Z","steps":["trace[506456968] 'read index received' (duration: 328.421695ms)","trace[506456968] 'applied index is now lower than readState.Index' (duration: 576.383415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:21.247905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203126783s","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:21.247921Z","caller":"traceutil/trace.go:171","msg":"trace[128298545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"1.203162394s","start":"2026-02-18T05:20:20.044752Z","end":"2026-02-18T05:20:21.247915Z","steps":["trace[128298545] 'agreement among raft nodes before linearized reading' (duration: 1.203073032s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.24794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.044744Z","time spent":"1.203192284s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:21.247944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196003234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:20:21.247995Z","caller":"traceutil/trace.go:171","msg":"trace[1783396856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"1.196075916s","start":"2026-02-18T05:20:20.051906Z","end":"2026-02-18T05:20:21.247982Z","steps":["trace[1783396856] 'agreement among raft nodes before linearized reading' (duration: 1.195918523s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.0519Z","time spent":"1.196131538s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:20:21.248104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240128478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:21.248122Z","caller":"traceutil/trace.go:171","msg":"trace[1348535499] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:684; }","duration":"1.24017062s","start":"2026-02-18T05:20:20.007945Z","end":"2026-02-18T05:20:21.248116Z","steps":["trace[1348535499] 'agreement among raft nodes before linearized reading' (duration: 1.240144749s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.007932Z","time spent":"1.24019949s","remote":"127.0.0.1:43474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:20:21.248176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072262769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:20:21.248197Z","caller":"traceutil/trace.go:171","msg":"trace[97830380] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:684; }","duration":"1.072310681s","start":"2026-02-18T05:20:20.17588Z","end":"2026-02-18T05:20:21.24819Z","steps":["trace[97830380] 'agreement among raft nodes before linearized reading' (duration: 1.07227564s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.175861Z","time spent":"1.072348781s","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:20:21.248237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300567671s","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:21.248252Z","caller":"traceutil/trace.go:171","msg":"trace[1570123230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:684; }","duration":"1.300608791s","start":"2026-02-18T05:20:19.947639Z","end":"2026-02-18T05:20:21.248248Z","steps":["trace[1570123230] 'agreement among raft nodes before linearized reading' (duration: 1.30056679s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:19.947624Z","time spent":"1.300636522s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:20:21.248325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194575029s","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:21.248343Z","caller":"traceutil/trace.go:171","msg":"trace[222666945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"1.19460523s","start":"2026-02-18T05:20:20.053732Z","end":"2026-02-18T05:20:21.248337Z","steps":["trace[222666945] 'agreement among raft nodes before linearized reading' (duration: 1.194551489s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.301283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T05:20:21.24836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.053726Z","time spent":"1.194629941s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T05:20:21.248366Z","caller":"traceutil/trace.go:171","msg":"trace[356168403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"903.346155ms","start":"2026-02-18T05:20:20.345016Z","end":"2026-02-18T05:20:21.248362Z","steps":["trace[356168403] 'agreement among raft nodes before linearized reading' (duration: 903.322534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.345001Z","time spent":"903.377836ms","remote":"127.0.0.1:42952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:21.248557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.56242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:20:21.248582Z","caller":"traceutil/trace.go:171","msg":"trace[1562666477] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:684; }","duration":"520.610441ms","start":"2026-02-18T05:20:20.727961Z","end":"2026-02-18T05:20:21.248571Z","steps":["trace[1562666477] 'agreement among raft nodes before linearized reading' (duration: 520.57549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.388457ms","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:20:21.248618Z","caller":"traceutil/trace.go:171","msg":"trace[397026181] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:684; }","duration":"594.448008ms","start":"2026-02-18T05:20:20.654165Z","end":"2026-02-18T05:20:21.248613Z","steps":["trace[397026181] 'agreement among raft nodes before linearized reading' (duration: 594.398937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:21.248631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.654144Z","time spent":"594.483398ms","remote":"127.0.0.1:43190","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:21.248602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:20.727945Z","time spent":"520.651853ms","remote":"127.0.0.1:43106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:20:37.928873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:20:38.429804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:20:38.930255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T05:20:39.057805Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.646856999s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T05:20:39.337727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.654255ms","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:39.337836Z","caller":"traceutil/trace.go:171","msg":"trace[1858536751] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:736; }","duration":"333.776018ms","start":"2026-02-18T05:20:39.004043Z","end":"2026-02-18T05:20:39.337819Z","steps":["trace[1858536751] 'range keys from in-memory index tree' (duration: 333.638884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.338401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.428304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:39.338456Z","caller":"traceutil/trace.go:171","msg":"trace[146887445] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"1.910550315s","start":"2026-02-18T05:20:37.427895Z","end":"2026-02-18T05:20:39.338445Z","steps":["trace[146887445] 'read index received' (duration: 1.629977607s)","trace[146887445] 'applied index is now lower than readState.Index' (duration: 280.571798ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:39.338744Z","caller":"traceutil/trace.go:171","msg":"trace[2106544416] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.927834097s","start":"2026-02-18T05:20:37.410897Z","end":"2026-02-18T05:20:39.338731Z","steps":["trace[2106544416] 'process raft request' (duration: 1.647011143s)","trace[2106544416] 'compare' (duration: 280.199519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:39.338809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:37.410885Z","time spent":"1.927891108s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:39.339027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911133269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:20:39.339053Z","caller":"traceutil/trace.go:171","msg":"trace[1990170060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"1.911173931s","start":"2026-02-18T05:20:37.427871Z","end":"2026-02-18T05:20:39.339044Z","steps":["trace[1990170060] 'agreement among raft nodes before linearized reading' (duration: 1.911075948s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.33908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:37.42785Z","time spent":"1.911224102s","remote":"127.0.0.1:43190","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:39.33925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852089094s","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:20:39.339279Z","caller":"traceutil/trace.go:171","msg":"trace[1071125572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:737; }","duration":"1.852137545s","start":"2026-02-18T05:20:37.487133Z","end":"2026-02-18T05:20:39.339271Z","steps":["trace[1071125572] 'agreement among raft nodes before linearized reading' (duration: 1.852055093s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.339303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:37.487122Z","time spent":"1.852175306s","remote":"127.0.0.1:43190","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:39.339475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.133993ms","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:39.3395Z","caller":"traceutil/trace.go:171","msg":"trace[1777752566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"411.190485ms","start":"2026-02-18T05:20:38.928302Z","end":"2026-02-18T05:20:39.339493Z","steps":["trace[1777752566] 'agreement among raft nodes before linearized reading' (duration: 411.149694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.339526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:38.928284Z","time spent":"411.237046ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:39.339688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.433195516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:20:39.339713Z","caller":"traceutil/trace.go:171","msg":"trace[446040683] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:737; }","duration":"1.433258687s","start":"2026-02-18T05:20:37.906447Z","end":"2026-02-18T05:20:39.339705Z","steps":["trace[446040683] 'agreement among raft nodes before linearized reading' (duration: 1.433186855s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.339738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:37.906426Z","time spent":"1.433305339s","remote":"127.0.0.1:43294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-18T05:20:39.571602Z","caller":"traceutil/trace.go:171","msg":"trace[2127066386] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"196.881023ms","start":"2026-02-18T05:20:39.374697Z","end":"2026-02-18T05:20:39.571578Z","steps":["trace[2127066386] 'read index received' (duration: 7.839646ms)","trace[2127066386] 'applied index is now lower than readState.Index' (duration: 189.040517ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:39.571859Z","caller":"traceutil/trace.go:171","msg":"trace[2111567855] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"221.902621ms","start":"2026-02-18T05:20:39.349937Z","end":"2026-02-18T05:20:39.571839Z","steps":["trace[2111567855] 'process raft request' (duration: 221.52444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.57228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.567661ms","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.572375Z","caller":"traceutil/trace.go:171","msg":"trace[772933996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:739; }","duration":"197.715575ms","start":"2026-02-18T05:20:39.374647Z","end":"2026-02-18T05:20:39.572363Z","steps":["trace[772933996] 'agreement among raft nodes before linearized reading' (duration: 197.545501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:39.573626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.314859ms","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:39.573683Z","caller":"traceutil/trace.go:171","msg":"trace[65290800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"174.394842ms","start":"2026-02-18T05:20:39.39928Z","end":"2026-02-18T05:20:39.573675Z","steps":["trace[65290800] 'agreement among raft nodes before linearized reading' (duration: 174.292059ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:41.009856Z","caller":"traceutil/trace.go:171","msg":"trace[1518297590] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"338.448855ms","start":"2026-02-18T05:20:40.671385Z","end":"2026-02-18T05:20:41.009834Z","steps":["trace[1518297590] 'process raft request' (duration: 338.054536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:41.011453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:40.671318Z","time spent":"338.593459ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:41.887091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483176829,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:20:42.112217Z","caller":"traceutil/trace.go:171","msg":"trace[1410882416] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"726.008161ms","start":"2026-02-18T05:20:41.386185Z","end":"2026-02-18T05:20:42.112193Z","steps":["trace[1410882416] 'read index received' (duration: 725.648582ms)","trace[1410882416] 'applied index is now lower than readState.Index' (duration: 358.709µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:42.112424Z","caller":"traceutil/trace.go:171","msg":"trace[885915671] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"752.894752ms","start":"2026-02-18T05:20:41.359478Z","end":"2026-02-18T05:20:42.112373Z","steps":["trace[885915671] 'process raft request' (duration: 752.40702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.112826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.359466Z","time spent":"753.232642ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:42.113188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.895593ms","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:20:42.11322Z","caller":"traceutil/trace.go:171","msg":"trace[230688993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"727.063538ms","start":"2026-02-18T05:20:41.386148Z","end":"2026-02-18T05:20:42.113211Z","steps":["trace[230688993] 'agreement among raft nodes before linearized reading' (duration: 726.76837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.113341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.386137Z","time spent":"727.197261ms","remote":"127.0.0.1:43190","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:42.114261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.136055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:20:42.114285Z","caller":"traceutil/trace.go:171","msg":"trace[1034557369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"533.202907ms","start":"2026-02-18T05:20:41.581076Z","end":"2026-02-18T05:20:42.114279Z","steps":["trace[1034557369] 'agreement among raft nodes before linearized reading' (duration: 532.729975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.114302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.58105Z","time spent":"533.247648ms","remote":"127.0.0.1:43190","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:42.114405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.594551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:20:42.114423Z","caller":"traceutil/trace.go:171","msg":"trace[253902805] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:743; }","duration":"173.650023ms","start":"2026-02-18T05:20:41.940768Z","end":"2026-02-18T05:20:42.114418Z","steps":["trace[253902805] 'agreement among raft nodes before linearized reading' (duration: 173.596062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.114566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.7411ms","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:42.114585Z","caller":"traceutil/trace.go:171","msg":"trace[770842025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"186.828392ms","start":"2026-02-18T05:20:41.927752Z","end":"2026-02-18T05:20:42.11458Z","steps":["trace[770842025] 'agreement among raft nodes before linearized reading' (duration: 186.799752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.114713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.658328ms","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:42.114732Z","caller":"traceutil/trace.go:171","msg":"trace[1932930329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"509.71794ms","start":"2026-02-18T05:20:41.60501Z","end":"2026-02-18T05:20:42.114728Z","steps":["trace[1932930329] 'agreement among raft nodes before linearized reading' (duration: 509.657628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.114771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.605Z","time spent":"509.766591ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:20:42.114871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.459724ms","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:42.114889Z","caller":"traceutil/trace.go:171","msg":"trace[368358697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"523.509385ms","start":"2026-02-18T05:20:41.591376Z","end":"2026-02-18T05:20:42.114885Z","steps":["trace[368358697] 'agreement among raft nodes before linearized reading' (duration: 523.434053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.114923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:41.59136Z","time spent":"523.558257ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T05:20:42.781242Z","caller":"traceutil/trace.go:171","msg":"trace[1281850412] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"659.420354ms","start":"2026-02-18T05:20:42.121803Z","end":"2026-02-18T05:20:42.781223Z","steps":["trace[1281850412] 'process raft request' (duration: 659.322841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.781576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.121793Z","time spent":"659.709071ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:42.799733Z","caller":"traceutil/trace.go:171","msg":"trace[377899434] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"676.953653ms","start":"2026-02-18T05:20:42.122763Z","end":"2026-02-18T05:20:42.799717Z","steps":["trace[377899434] 'process raft request' (duration: 676.917622ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:42.800133Z","caller":"traceutil/trace.go:171","msg":"trace[484073311] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"678.277486ms","start":"2026-02-18T05:20:42.121841Z","end":"2026-02-18T05:20:42.800118Z","steps":["trace[484073311] 'process raft request' (duration: 677.728992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.800253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.121815Z","time spent":"678.358007ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:42.80058Z","caller":"traceutil/trace.go:171","msg":"trace[493808966] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"678.656284ms","start":"2026-02-18T05:20:42.121896Z","end":"2026-02-18T05:20:42.800552Z","steps":["trace[493808966] 'process raft request' (duration: 677.751722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:42.800681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.121892Z","time spent":"678.722386ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:42.800881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:42.122694Z","time spent":"677.410504ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:45.251826Z","caller":"traceutil/trace.go:171","msg":"trace[473642605] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"462.60525ms","start":"2026-02-18T05:20:44.789169Z","end":"2026-02-18T05:20:45.251775Z","steps":["trace[473642605] 'process raft request' (duration: 462.389985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.252215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:44.789145Z","time spent":"462.751883ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:45.251912Z","caller":"traceutil/trace.go:171","msg":"trace[1922211919] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:779; }","duration":"446.500977ms","start":"2026-02-18T05:20:44.80538Z","end":"2026-02-18T05:20:45.251881Z","steps":["trace[1922211919] 'read index received' (duration: 446.491627ms)","trace[1922211919] 'applied index is now lower than readState.Index' (duration: 7.61µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:45.253978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.587709ms","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:45.254485Z","caller":"traceutil/trace.go:171","msg":"trace[568117764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:749; }","duration":"449.101062ms","start":"2026-02-18T05:20:44.805375Z","end":"2026-02-18T05:20:45.254476Z","steps":["trace[568117764] 'agreement among raft nodes before linearized reading' (duration: 448.498087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.254537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:44.805338Z","time spent":"449.189654ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:20:45.254688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.094367ms","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:20:45.254803Z","caller":"traceutil/trace.go:171","msg":"trace[1232974182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:749; }","duration":"448.24295ms","start":"2026-02-18T05:20:44.806552Z","end":"2026-02-18T05:20:45.254795Z","steps":["trace[1232974182] 'agreement among raft nodes before linearized reading' (duration: 448.093147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.254843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:44.806544Z","time spent":"448.291911ms","remote":"127.0.0.1:43190","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:45.255117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.151862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T05:20:45.255159Z","caller":"traceutil/trace.go:171","msg":"trace[1430776309] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:749; }","duration":"325.205883ms","start":"2026-02-18T05:20:44.929946Z","end":"2026-02-18T05:20:45.255152Z","steps":["trace[1430776309] 'agreement among raft nodes before linearized reading' (duration: 325.123141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.255192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:44.92994Z","time spent":"325.245444ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-02-18T05:20:45.255348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.901565ms","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:45.255384Z","caller":"traceutil/trace.go:171","msg":"trace[830430533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"326.964987ms","start":"2026-02-18T05:20:44.928413Z","end":"2026-02-18T05:20:45.255378Z","steps":["trace[830430533] 'agreement among raft nodes before linearized reading' (duration: 326.916586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.255416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:44.928393Z","time spent":"327.016988ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:20:45.603846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.868044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:20:45.604463Z","caller":"traceutil/trace.go:171","msg":"trace[178229932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"796.501549ms","start":"2026-02-18T05:20:44.807914Z","end":"2026-02-18T05:20:45.604415Z","steps":["trace[178229932] 'agreement among raft nodes before linearized reading' (duration: 448.333052ms)","trace[178229932] 'range keys from in-memory index tree' (duration: 347.458959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:45.60491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:44.807893Z","time spent":"796.989921ms","remote":"127.0.0.1:43190","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:45.604104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.562106ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-18T05:20:45.60541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:45.25953Z","time spent":"345.878299ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T05:20:45.674907Z","caller":"traceutil/trace.go:171","msg":"trace[1145060122] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:779; }","duration":"415.158582ms","start":"2026-02-18T05:20:45.259725Z","end":"2026-02-18T05:20:45.674884Z","steps":["trace[1145060122] 'read index received' (duration: 93.797565ms)","trace[1145060122] 'applied index is now lower than readState.Index' (duration: 321.360047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:20:45.675101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.350637ms","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:45.675177Z","caller":"traceutil/trace.go:171","msg":"trace[983882641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"415.452439ms","start":"2026-02-18T05:20:45.259713Z","end":"2026-02-18T05:20:45.675166Z","steps":["trace[983882641] 'agreement among raft nodes before linearized reading' (duration: 415.263405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.675508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:45.259709Z","time spent":"415.639064ms","remote":"127.0.0.1:42952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:20:45.67669Z","caller":"traceutil/trace.go:171","msg":"trace[1836381094] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"416.989819ms","start":"2026-02-18T05:20:45.259685Z","end":"2026-02-18T05:20:45.676675Z","steps":["trace[1836381094] 'process raft request' (duration: 415.005709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.677002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:45.259676Z","time spent":"417.07609ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:20:45.677258Z","caller":"traceutil/trace.go:171","msg":"trace[1623803441] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"415.85965ms","start":"2026-02-18T05:20:45.261387Z","end":"2026-02-18T05:20:45.677247Z","steps":["trace[1623803441] 'process raft request' (duration: 413.453279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:45.677355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:45.261379Z","time spent":"415.912761ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:45.877885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.135275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:20:45.878445Z","caller":"traceutil/trace.go:171","msg":"trace[132351051] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"265.882029ms","start":"2026-02-18T05:20:45.612547Z","end":"2026-02-18T05:20:45.878429Z","steps":["trace[132351051] 'process raft request' (duration: 265.825347ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:20:45.878649Z","caller":"traceutil/trace.go:171","msg":"trace[394896004] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"271.020548ms","start":"2026-02-18T05:20:45.60762Z","end":"2026-02-18T05:20:45.878641Z","steps":["trace[394896004] 'process raft request' (duration: 116.066673ms)","trace[394896004] 'compare' (duration: 153.900269ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:57.424273Z","caller":"traceutil/trace.go:171","msg":"trace[914923791] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"127.805732ms","start":"2026-02-18T05:20:57.296452Z","end":"2026-02-18T05:20:57.424257Z","steps":["trace[914923791] 'process raft request' (duration: 127.70656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.235547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483177040,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:20:58.554449Z","caller":"traceutil/trace.go:171","msg":"trace[81317301] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"819.158042ms","start":"2026-02-18T05:20:57.735276Z","end":"2026-02-18T05:20:58.554434Z","steps":["trace[81317301] 'read index received' (duration: 819.043719ms)","trace[81317301] 'applied index is now lower than readState.Index' (duration: 113.803µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:20:58.554627Z","caller":"traceutil/trace.go:171","msg":"trace[1710871587] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"822.163968ms","start":"2026-02-18T05:20:57.732457Z","end":"2026-02-18T05:20:58.554621Z","steps":["trace[1710871587] 'process raft request' (duration: 821.901581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.554698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:57.732446Z","time spent":"822.204389ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:20:58.554875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.596073ms","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:20:58.554896Z","caller":"traceutil/trace.go:171","msg":"trace[520059091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"819.649805ms","start":"2026-02-18T05:20:57.735241Z","end":"2026-02-18T05:20:58.554891Z","steps":["trace[520059091] 'agreement among raft nodes before linearized reading' (duration: 819.579553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.55491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:57.735223Z","time spent":"819.684465ms","remote":"127.0.0.1:43190","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:58.554977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.648102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:20:58.554994Z","caller":"traceutil/trace.go:171","msg":"trace[912713904] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:840; }","duration":"768.721464ms","start":"2026-02-18T05:20:57.786268Z","end":"2026-02-18T05:20:58.55499Z","steps":["trace[912713904] 'agreement among raft nodes before linearized reading' (duration: 768.694193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.555011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:57.786255Z","time spent":"768.750904ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:20:58.555182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.836546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:20:58.555202Z","caller":"traceutil/trace.go:171","msg":"trace[1255146207] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:840; }","duration":"213.878426ms","start":"2026-02-18T05:20:58.341318Z","end":"2026-02-18T05:20:58.555196Z","steps":["trace[1255146207] 'agreement among raft nodes before linearized reading' (duration: 213.847895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.555291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.9326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:20:58.555307Z","caller":"traceutil/trace.go:171","msg":"trace[1620679610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"231.970462ms","start":"2026-02-18T05:20:58.323333Z","end":"2026-02-18T05:20:58.555304Z","steps":["trace[1620679610] 'agreement among raft nodes before linearized reading' (duration: 231.929441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.55541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.866717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:20:58.555431Z","caller":"traceutil/trace.go:171","msg":"trace[1112018777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"624.914809ms","start":"2026-02-18T05:20:57.930513Z","end":"2026-02-18T05:20:58.555428Z","steps":["trace[1112018777] 'agreement among raft nodes before linearized reading' (duration: 624.842937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.555443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:57.930498Z","time spent":"624.942429ms","remote":"127.0.0.1:43190","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:58.555498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.643778ms","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:58.555515Z","caller":"traceutil/trace.go:171","msg":"trace[1774162478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"629.680479ms","start":"2026-02-18T05:20:57.92583Z","end":"2026-02-18T05:20:58.55551Z","steps":["trace[1774162478] 'agreement among raft nodes before linearized reading' (duration: 629.657518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:20:58.555526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:57.925814Z","time spent":"629.7097ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:21:00.882997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064666683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:21:00.883075Z","caller":"traceutil/trace.go:171","msg":"trace[1377215577] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:844; }","duration":"1.064779946s","start":"2026-02-18T05:20:59.818277Z","end":"2026-02-18T05:21:00.883057Z","steps":["trace[1377215577] 'count revisions from in-memory index tree' (duration: 1.064615823s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.883167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:59.818264Z","time spent":"1.064889159s","remote":"127.0.0.1:43318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:21:00.88349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.715835ms","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:00.883543Z","caller":"traceutil/trace.go:171","msg":"trace[964275186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:844; }","duration":"955.789166ms","start":"2026-02-18T05:20:59.927742Z","end":"2026-02-18T05:21:00.883532Z","steps":["trace[964275186] 'range keys from in-memory index tree' (duration: 955.656953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.88357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:20:59.927728Z","time spent":"955.835778ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:21:00.884358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.578458ms","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:00.884411Z","caller":"traceutil/trace.go:171","msg":"trace[195981136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:844; }","duration":"322.66902ms","start":"2026-02-18T05:21:00.561729Z","end":"2026-02-18T05:21:00.884398Z","steps":["trace[195981136] 'range keys from in-memory index tree' (duration: 322.401214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.884452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.561709Z","time spent":"322.736013ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:21:00.884654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.429614ms","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:21:00.884692Z","caller":"traceutil/trace.go:171","msg":"trace[558006706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:844; }","duration":"312.488835ms","start":"2026-02-18T05:21:00.572194Z","end":"2026-02-18T05:21:00.884683Z","steps":["trace[558006706] 'range keys from in-memory index tree' (duration: 312.29523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.884728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.572184Z","time spent":"312.535507ms","remote":"127.0.0.1:43190","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:00.884918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.47671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:21:00.884989Z","caller":"traceutil/trace.go:171","msg":"trace[1023070373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:844; }","duration":"309.598913ms","start":"2026-02-18T05:21:00.575381Z","end":"2026-02-18T05:21:00.88498Z","steps":["trace[1023070373] 'range keys from in-memory index tree' (duration: 309.385578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.885022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.575361Z","time spent":"309.654565ms","remote":"127.0.0.1:43190","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:00.885202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.667085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:21:00.885236Z","caller":"traceutil/trace.go:171","msg":"trace[674088509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:844; }","duration":"309.740617ms","start":"2026-02-18T05:21:00.575487Z","end":"2026-02-18T05:21:00.885228Z","steps":["trace[674088509] 'range keys from in-memory index tree' (duration: 309.561192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:00.885262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.575475Z","time spent":"309.780938ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:01.180747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.170878ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T05:21:01.403712Z","caller":"traceutil/trace.go:171","msg":"trace[1620806165] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:879; }","duration":"507.828526ms","start":"2026-02-18T05:21:00.895866Z","end":"2026-02-18T05:21:01.403695Z","steps":["trace[1620806165] 'read index received' (duration: 7.870087ms)","trace[1620806165] 'applied index is now lower than readState.Index' (duration: 499.957909ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:01.403924Z","caller":"traceutil/trace.go:171","msg":"trace[264067410] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"509.55274ms","start":"2026-02-18T05:21:00.894365Z","end":"2026-02-18T05:21:01.403917Z","steps":["trace[264067410] 'process raft request' (duration: 509.1407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:01.403999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.894348Z","time spent":"509.59816ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:01.40412Z","caller":"traceutil/trace.go:171","msg":"trace[689023123] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"500.949884ms","start":"2026-02-18T05:21:00.903164Z","end":"2026-02-18T05:21:01.404114Z","steps":["trace[689023123] 'process raft request' (duration: 500.485672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:01.404155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.903157Z","time spent":"500.977584ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:01.404222Z","caller":"traceutil/trace.go:171","msg":"trace[1056678162] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"500.988374ms","start":"2026-02-18T05:21:00.903229Z","end":"2026-02-18T05:21:01.404217Z","steps":["trace[1056678162] 'process raft request' (duration: 500.444121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:01.404259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.903225Z","time spent":"501.009995ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:01.404325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.458022ms","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:01.404343Z","caller":"traceutil/trace.go:171","msg":"trace[557794695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"508.493222ms","start":"2026-02-18T05:21:00.895846Z","end":"2026-02-18T05:21:01.404339Z","steps":["trace[557794695] 'agreement among raft nodes before linearized reading' (duration: 508.462161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:01.404357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.895833Z","time spent":"508.521203ms","remote":"127.0.0.1:42952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T05:21:01.661105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.553789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:01.661205Z","caller":"traceutil/trace.go:171","msg":"trace[204518183] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"257.421081ms","start":"2026-02-18T05:21:01.403758Z","end":"2026-02-18T05:21:01.661179Z","steps":["trace[204518183] 'read index received' (duration: 105.679747ms)","trace[204518183] 'applied index is now lower than readState.Index' (duration: 151.740274ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:01.6619Z","caller":"traceutil/trace.go:171","msg":"trace[616879705] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"756.048526ms","start":"2026-02-18T05:21:00.905833Z","end":"2026-02-18T05:21:01.661882Z","steps":["trace[616879705] 'process raft request' (duration: 603.651036ms)","trace[616879705] 'compare' (duration: 151.468907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:01.66198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:00.905812Z","time spent":"756.129838ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:01.662685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.56363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:21:01.662734Z","caller":"traceutil/trace.go:171","msg":"trace[1234068310] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:848; }","duration":"301.637812ms","start":"2026-02-18T05:21:01.361083Z","end":"2026-02-18T05:21:01.662721Z","steps":["trace[1234068310] 'agreement among raft nodes before linearized reading' (duration: 301.5572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:01.662772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:01.361071Z","time spent":"301.692673ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-18T05:21:02.101542Z","caller":"traceutil/trace.go:171","msg":"trace[707865310] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"440.294958ms","start":"2026-02-18T05:21:01.661233Z","end":"2026-02-18T05:21:02.101528Z","steps":["trace[707865310] 'read index received' (duration: 437.688103ms)","trace[707865310] 'applied index is now lower than readState.Index' (duration: 2.606145ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:02.101647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.237847ms","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:02.101662Z","caller":"traceutil/trace.go:171","msg":"trace[2025050240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"689.292548ms","start":"2026-02-18T05:21:01.412365Z","end":"2026-02-18T05:21:02.101658Z","steps":["trace[2025050240] 'agreement among raft nodes before linearized reading' (duration: 689.221876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.101678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:01.412346Z","time spent":"689.328349ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:21:02.101848Z","caller":"traceutil/trace.go:171","msg":"trace[1304380410] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"481.583616ms","start":"2026-02-18T05:21:01.62025Z","end":"2026-02-18T05:21:02.101833Z","steps":["trace[1304380410] 'process raft request' (duration: 478.715394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.101897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:01.620224Z","time spent":"481.640938ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:02.102002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.918179ms","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.102015Z","caller":"traceutil/trace.go:171","msg":"trace[186222014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:849; }","duration":"618.931149ms","start":"2026-02-18T05:21:01.48308Z","end":"2026-02-18T05:21:02.102011Z","steps":["trace[186222014] 'agreement among raft nodes before linearized reading' (duration: 618.875818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:02.102027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:01.483028Z","time spent":"618.995121ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T05:21:07.695457Z","caller":"traceutil/trace.go:171","msg":"trace[1838950009] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"178.966599ms","start":"2026-02-18T05:21:07.516441Z","end":"2026-02-18T05:21:07.695408Z","steps":["trace[1838950009] 'process raft request' (duration: 178.882287ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:07.832079Z","caller":"traceutil/trace.go:171","msg":"trace[1509708570] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:900; }","duration":"136.788768ms","start":"2026-02-18T05:21:07.695275Z","end":"2026-02-18T05:21:07.832063Z","steps":["trace[1509708570] 'read index received' (duration: 136.637284ms)","trace[1509708570] 'applied index is now lower than readState.Index' (duration: 150.944µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:07.832212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.921572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:21:07.832229Z","caller":"traceutil/trace.go:171","msg":"trace[377286304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"136.980313ms","start":"2026-02-18T05:21:07.695243Z","end":"2026-02-18T05:21:07.832223Z","steps":["trace[377286304] 'agreement among raft nodes before linearized reading' (duration: 136.876901ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:07.832407Z","caller":"traceutil/trace.go:171","msg":"trace[1914663724] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"315.946143ms","start":"2026-02-18T05:21:07.516455Z","end":"2026-02-18T05:21:07.832401Z","steps":["trace[1914663724] 'process raft request' (duration: 315.499011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:07.832452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:07.516434Z","time spent":"315.985013ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:07.832533Z","caller":"traceutil/trace.go:171","msg":"trace[904419623] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"315.952583ms","start":"2026-02-18T05:21:07.516559Z","end":"2026-02-18T05:21:07.832512Z","steps":["trace[904419623] 'process raft request' (duration: 315.474191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:07.832645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:07.516555Z","time spent":"316.024184ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:10.120649Z","caller":"traceutil/trace.go:171","msg":"trace[2014852766] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"285.538867ms","start":"2026-02-18T05:21:09.835095Z","end":"2026-02-18T05:21:10.120634Z","steps":["trace[2014852766] 'read index received' (duration: 285.399564ms)","trace[2014852766] 'applied index is now lower than readState.Index' (duration: 138.513µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:10.120806Z","caller":"traceutil/trace.go:171","msg":"trace[1343836388] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"421.2663ms","start":"2026-02-18T05:21:09.699534Z","end":"2026-02-18T05:21:10.120801Z","steps":["trace[1343836388] 'process raft request' (duration: 421.013553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:10.120864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:09.699526Z","time spent":"421.294081ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:10.12099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.893857ms","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:21:10.121006Z","caller":"traceutil/trace.go:171","msg":"trace[1832683590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"285.933997ms","start":"2026-02-18T05:21:09.835067Z","end":"2026-02-18T05:21:10.121001Z","steps":["trace[1832683590] 'agreement among raft nodes before linearized reading' (duration: 285.876116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:10.121089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.864192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T05:21:10.121107Z","caller":"traceutil/trace.go:171","msg":"trace[1334058390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"284.893742ms","start":"2026-02-18T05:21:09.836209Z","end":"2026-02-18T05:21:10.121103Z","steps":["trace[1334058390] 'agreement among raft nodes before linearized reading' (duration: 284.853831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:10.121199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.445778ms","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:10.121216Z","caller":"traceutil/trace.go:171","msg":"trace[346772209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:868; }","duration":"196.49761ms","start":"2026-02-18T05:21:09.924714Z","end":"2026-02-18T05:21:10.121212Z","steps":["trace[346772209] 'agreement among raft nodes before linearized reading' (duration: 196.473709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:10.121304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.390477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:21:10.12132Z","caller":"traceutil/trace.go:171","msg":"trace[1808058443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"266.421677ms","start":"2026-02-18T05:21:09.854895Z","end":"2026-02-18T05:21:10.121317Z","steps":["trace[1808058443] 'agreement among raft nodes before linearized reading' (duration: 266.382016ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:10.426388Z","caller":"traceutil/trace.go:171","msg":"trace[502997184] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"300.370942ms","start":"2026-02-18T05:21:10.126Z","end":"2026-02-18T05:21:10.426371Z","steps":["trace[502997184] 'process raft request' (duration: 239.110782ms)","trace[502997184] 'compare' (duration: 61.013864ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:10.426473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:10.125988Z","time spent":"300.439673ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:10.489763Z","caller":"traceutil/trace.go:171","msg":"trace[1717485840] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"363.651092ms","start":"2026-02-18T05:21:10.126095Z","end":"2026-02-18T05:21:10.489746Z","steps":["trace[1717485840] 'process raft request' (duration: 363.441117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:10.489859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:10.12609Z","time spent":"363.717124ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:10.489933Z","caller":"traceutil/trace.go:171","msg":"trace[1439076952] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"363.775665ms","start":"2026-02-18T05:21:10.126121Z","end":"2026-02-18T05:21:10.489897Z","steps":["trace[1439076952] 'process raft request' (duration: 363.492308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:10.490049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:10.126118Z","time spent":"363.863867ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:10.587487Z","caller":"traceutil/trace.go:171","msg":"trace[1377691384] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:905; }","duration":"410.91309ms","start":"2026-02-18T05:21:10.176554Z","end":"2026-02-18T05:21:10.587467Z","steps":["trace[1377691384] 'read index received' (duration: 188.564281ms)","trace[1377691384] 'applied index is now lower than readState.Index' (duration: 222.347949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:10.587625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.053083ms","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:10.587645Z","caller":"traceutil/trace.go:171","msg":"trace[1074229301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"411.120055ms","start":"2026-02-18T05:21:10.17652Z","end":"2026-02-18T05:21:10.58764Z","steps":["trace[1074229301] 'agreement among raft nodes before linearized reading' (duration: 411.010622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:10.587667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:10.176504Z","time spent":"411.159446ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T05:21:15.771826Z","caller":"traceutil/trace.go:171","msg":"trace[1618031815] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"591.075659ms","start":"2026-02-18T05:21:15.180733Z","end":"2026-02-18T05:21:15.771809Z","steps":["trace[1618031815] 'process raft request' (duration: 590.654838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:15.77192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:15.180715Z","time spent":"591.155461ms","remote":"127.0.0.1:42972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:16.281528Z","caller":"traceutil/trace.go:171","msg":"trace[729043913] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"133.182329ms","start":"2026-02-18T05:21:16.148321Z","end":"2026-02-18T05:21:16.281503Z","steps":["trace[729043913] 'process raft request' (duration: 133.005774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:16.665473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.496195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:21:16.665537Z","caller":"traceutil/trace.go:171","msg":"trace[94877625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"158.591917ms","start":"2026-02-18T05:21:16.506932Z","end":"2026-02-18T05:21:16.665524Z","steps":["trace[94877625] 'range keys from in-memory index tree' (duration: 158.378712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:16.665781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.463015ms","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:21:16.665795Z","caller":"traceutil/trace.go:171","msg":"trace[867093468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"197.478815ms","start":"2026-02-18T05:21:16.468312Z","end":"2026-02-18T05:21:16.665791Z","steps":["trace[867093468] 'range keys from in-memory index tree' (duration: 197.345552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:16.665718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.829003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T05:21:16.66586Z","caller":"traceutil/trace.go:171","msg":"trace[1793004682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:885; }","duration":"159.006948ms","start":"2026-02-18T05:21:16.506829Z","end":"2026-02-18T05:21:16.665836Z","steps":["trace[1793004682] 'range keys from in-memory index tree' (duration: 158.586228ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:16.837622Z","caller":"traceutil/trace.go:171","msg":"trace[1303671887] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"167.247164ms","start":"2026-02-18T05:21:16.670352Z","end":"2026-02-18T05:21:16.8376Z","steps":["trace[1303671887] 'process raft request' (duration: 167.11263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:17.324244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.765015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:17.324332Z","caller":"traceutil/trace.go:171","msg":"trace[140145990] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:924; }","duration":"646.982344ms","start":"2026-02-18T05:21:16.677341Z","end":"2026-02-18T05:21:17.324323Z","steps":["trace[140145990] 'read index received' (duration: 160.123415ms)","trace[140145990] 'applied index is now lower than readState.Index' (duration: 486.858409ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:17.324466Z","caller":"traceutil/trace.go:171","msg":"trace[503092801] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"650.650967ms","start":"2026-02-18T05:21:16.673808Z","end":"2026-02-18T05:21:17.324459Z","steps":["trace[503092801] 'process raft request' (duration: 426.626545ms)","trace[503092801] 'compare' (duration: 223.598711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:17.324496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:16.6738Z","time spent":"650.680507ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:17.324582Z","caller":"traceutil/trace.go:171","msg":"trace[806598964] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"649.112759ms","start":"2026-02-18T05:21:16.675465Z","end":"2026-02-18T05:21:17.324578Z","steps":["trace[806598964] 'process raft request' (duration: 648.833242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:17.324605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:16.675457Z","time spent":"649.134839ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:17.324703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.362294ms","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:17.324716Z","caller":"traceutil/trace.go:171","msg":"trace[1726116285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"647.391015ms","start":"2026-02-18T05:21:16.677321Z","end":"2026-02-18T05:21:17.324712Z","steps":["trace[1726116285] 'agreement among raft nodes before linearized reading' (duration: 647.336934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:17.324731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:16.677313Z","time spent":"647.413605ms","remote":"127.0.0.1:43190","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:21:17.824754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483177176,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:21:17.90721Z","caller":"traceutil/trace.go:171","msg":"trace[839321268] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"841.208276ms","start":"2026-02-18T05:21:17.065979Z","end":"2026-02-18T05:21:17.907187Z","steps":["trace[839321268] 'process raft request' (duration: 810.29859ms)","trace[839321268] 'compare' (duration: 30.467295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:17.907675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:17.065927Z","time spent":"841.664407ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:17.951655Z","caller":"traceutil/trace.go:171","msg":"trace[350292372] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:927; }","duration":"627.294819ms","start":"2026-02-18T05:21:17.324345Z","end":"2026-02-18T05:21:17.95164Z","steps":["trace[350292372] 'read index received' (duration: 551.943045ms)","trace[350292372] 'applied index is now lower than readState.Index' (duration: 75.350854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:17.951982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.028113ms","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:17.952091Z","caller":"traceutil/trace.go:171","msg":"trace[982165170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"884.162237ms","start":"2026-02-18T05:21:17.067916Z","end":"2026-02-18T05:21:17.952078Z","steps":["trace[982165170] 'agreement among raft nodes before linearized reading' (duration: 884.022754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:17.952195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:17.067908Z","time spent":"884.27108ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T05:21:17.95198Z","caller":"traceutil/trace.go:171","msg":"trace[1655544705] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"623.36108ms","start":"2026-02-18T05:21:17.3286Z","end":"2026-02-18T05:21:17.951961Z","steps":["trace[1655544705] 'process raft request' (duration: 622.96631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:17.952768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:17.328576Z","time spent":"624.098509ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:20.161175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.097497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:21:20.161242Z","caller":"traceutil/trace.go:171","msg":"trace[921573115] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:900; }","duration":"390.20595ms","start":"2026-02-18T05:21:19.771022Z","end":"2026-02-18T05:21:20.161228Z","steps":["trace[921573115] 'range keys from in-memory index tree' (duration: 390.035006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:20.161272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:19.771003Z","time spent":"390.262352ms","remote":"127.0.0.1:43328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-02-18T05:21:20.161885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.450686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:20.161956Z","caller":"traceutil/trace.go:171","msg":"trace[1016468179] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"297.444478ms","start":"2026-02-18T05:21:19.864501Z","end":"2026-02-18T05:21:20.161946Z","steps":["trace[1016468179] 'read index received' (duration: 20.271µs)","trace[1016468179] 'applied index is now lower than readState.Index' (duration: 297.423257ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:20.16206Z","caller":"traceutil/trace.go:171","msg":"trace[2048348212] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"387.972413ms","start":"2026-02-18T05:21:19.774078Z","end":"2026-02-18T05:21:20.16205Z","steps":["trace[2048348212] 'process raft request' (duration: 71.302333ms)","trace[2048348212] 'compare' (duration: 316.151397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:20.16211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:19.774066Z","time spent":"388.023005ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":786,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T05:21:20.162348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.840077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:21:20.162383Z","caller":"traceutil/trace.go:171","msg":"trace[1802835152] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:901; }","duration":"297.900018ms","start":"2026-02-18T05:21:19.864475Z","end":"2026-02-18T05:21:20.162375Z","steps":["trace[1802835152] 'agreement among raft nodes before linearized reading' (duration: 297.777775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:20.162525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.682685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T05:21:20.162558Z","caller":"traceutil/trace.go:171","msg":"trace[706488827] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:901; }","duration":"268.737866ms","start":"2026-02-18T05:21:19.893812Z","end":"2026-02-18T05:21:20.16255Z","steps":["trace[706488827] 'agreement among raft nodes before linearized reading' (duration: 268.689244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:20.163381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.49215ms","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:20.163424Z","caller":"traceutil/trace.go:171","msg":"trace[1620105274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"235.553232ms","start":"2026-02-18T05:21:19.927861Z","end":"2026-02-18T05:21:20.163414Z","steps":["trace[1620105274] 'agreement among raft nodes before linearized reading' (duration: 235.48726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:20.163681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.26866ms","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:20.163716Z","caller":"traceutil/trace.go:171","msg":"trace[902956758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"207.324672ms","start":"2026-02-18T05:21:19.956383Z","end":"2026-02-18T05:21:20.163708Z","steps":["trace[902956758] 'agreement among raft nodes before linearized reading' (duration: 207.215869ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:20.738571Z","caller":"traceutil/trace.go:171","msg":"trace[756978823] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"568.717856ms","start":"2026-02-18T05:21:20.169837Z","end":"2026-02-18T05:21:20.738555Z","steps":["trace[756978823] 'process raft request' (duration: 555.219057ms)","trace[756978823] 'compare' (duration: 13.10004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:20.738652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:20.169821Z","time spent":"568.790098ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:21.901182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.122857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:21.901901Z","caller":"traceutil/trace.go:171","msg":"trace[1373873560] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"1.729336883s","start":"2026-02-18T05:21:20.172551Z","end":"2026-02-18T05:21:21.901888Z","steps":["trace[1373873560] 'process raft request' (duration: 766.453217ms)","trace[1373873560] 'compare' (duration: 962.017844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:21.901943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:20.172538Z","time spent":"1.729386673s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T05:21:21.916836Z","caller":"traceutil/trace.go:171","msg":"trace[1899304675] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:940; }","duration":"1.61786684s","start":"2026-02-18T05:21:20.298948Z","end":"2026-02-18T05:21:21.916815Z","steps":["trace[1899304675] 'read index received' (duration: 426.117571ms)","trace[1899304675] 'applied index is now lower than readState.Index' (duration: 1.191748159s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:21.916959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382020061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T05:21:21.916973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618024494s","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:21.916988Z","caller":"traceutil/trace.go:171","msg":"trace[1441646826] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:903; }","duration":"1.382066043s","start":"2026-02-18T05:21:20.534913Z","end":"2026-02-18T05:21:21.916979Z","steps":["trace[1441646826] 'agreement among raft nodes before linearized reading' (duration: 1.382003651s)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:21.916995Z","caller":"traceutil/trace.go:171","msg":"trace[102092041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"1.618049605s","start":"2026-02-18T05:21:20.29894Z","end":"2026-02-18T05:21:21.916989Z","steps":["trace[102092041] 'agreement among raft nodes before linearized reading' (duration: 1.617940932s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:21.917015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:20.298904Z","time spent":"1.618106515s","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T05:21:21.917035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:20.53486Z","time spent":"1.382150224s","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T05:21:21.917239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069172957s","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:21:21.917253Z","caller":"traceutil/trace.go:171","msg":"trace[1177629167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"1.069211847s","start":"2026-02-18T05:21:20.848037Z","end":"2026-02-18T05:21:21.917248Z","steps":["trace[1177629167] 'agreement among raft nodes before linearized reading' (duration: 1.069171696s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:21.917265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:20.848023Z","time spent":"1.069238959s","remote":"127.0.0.1:43190","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:21.917368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.280675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T05:21:21.91738Z","caller":"traceutil/trace.go:171","msg":"trace[581129159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:903; }","duration":"564.314316ms","start":"2026-02-18T05:21:21.353062Z","end":"2026-02-18T05:21:21.917376Z","steps":["trace[581129159] 'agreement among raft nodes before linearized reading' (duration: 564.277655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:21.9174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:21.353051Z","time spent":"564.345657ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:21.917495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.264296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:21:21.917518Z","caller":"traceutil/trace.go:171","msg":"trace[1245955317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"566.322007ms","start":"2026-02-18T05:21:21.35119Z","end":"2026-02-18T05:21:21.917512Z","steps":["trace[1245955317] 'agreement among raft nodes before linearized reading' (duration: 566.258085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:21.917536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:21.351171Z","time spent":"566.361088ms","remote":"127.0.0.1:43190","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:32.178907Z","caller":"traceutil/trace.go:171","msg":"trace[1657119999] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"185.454163ms","start":"2026-02-18T05:21:31.993434Z","end":"2026-02-18T05:21:32.178889Z","steps":["trace[1657119999] 'read index received' (duration: 185.164775ms)","trace[1657119999] 'applied index is now lower than readState.Index' (duration: 288.718µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:32.179216Z","caller":"traceutil/trace.go:171","msg":"trace[450974114] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"208.691467ms","start":"2026-02-18T05:21:31.970516Z","end":"2026-02-18T05:21:32.179208Z","steps":["trace[450974114] 'process raft request' (duration: 208.201034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.179691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.626006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:21:32.179783Z","caller":"traceutil/trace.go:171","msg":"trace[1058729258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"183.738489ms","start":"2026-02-18T05:21:31.996022Z","end":"2026-02-18T05:21:32.17976Z","steps":["trace[1058729258] 'agreement among raft nodes before linearized reading' (duration: 183.483292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.180149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.227664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-18T05:21:32.180378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.93579ms","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:21:32.180414Z","caller":"traceutil/trace.go:171","msg":"trace[591422010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"186.985071ms","start":"2026-02-18T05:21:31.993419Z","end":"2026-02-18T05:21:32.180404Z","steps":["trace[591422010] 'agreement among raft nodes before linearized reading' (duration: 186.887798ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:32.183835Z","caller":"traceutil/trace.go:171","msg":"trace[43032865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"131.939866ms","start":"2026-02-18T05:21:32.05188Z","end":"2026-02-18T05:21:32.18382Z","steps":["trace[43032865] 'agreement among raft nodes before linearized reading' (duration: 128.161702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.752432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.649819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:32.75265Z","caller":"traceutil/trace.go:171","msg":"trace[58808333] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"564.033598ms","start":"2026-02-18T05:21:32.188599Z","end":"2026-02-18T05:21:32.752632Z","steps":["trace[58808333] 'process raft request' (duration: 563.996827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.752727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:32.18859Z","time spent":"564.09042ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:32.753446Z","caller":"traceutil/trace.go:171","msg":"trace[1134041389] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"568.463ms","start":"2026-02-18T05:21:32.184945Z","end":"2026-02-18T05:21:32.753408Z","steps":["trace[1134041389] 'process raft request' (duration: 567.578938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:32.753733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:32.184931Z","time spent":"568.568513ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:32.753856Z","caller":"traceutil/trace.go:171","msg":"trace[3521838] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"570.404809ms","start":"2026-02-18T05:21:32.183443Z","end":"2026-02-18T05:21:32.753847Z","steps":["trace[3521838] 'process raft request' (duration: 297.259583ms)","trace[3521838] 'compare' (duration: 271.348011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:32.753919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:32.183426Z","time spent":"570.470432ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:32.914518Z","caller":"traceutil/trace.go:171","msg":"trace[408612008] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"102.390473ms","start":"2026-02-18T05:21:32.812107Z","end":"2026-02-18T05:21:32.914497Z","steps":["trace[408612008] 'process raft request' (duration: 97.975753ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:34.298238Z","caller":"traceutil/trace.go:171","msg":"trace[276819960] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"112.895937ms","start":"2026-02-18T05:21:34.185296Z","end":"2026-02-18T05:21:34.298191Z","steps":["trace[276819960] 'process raft request' (duration: 112.767694ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:35.020003Z","caller":"traceutil/trace.go:171","msg":"trace[792259193] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1006; }","duration":"101.0432ms","start":"2026-02-18T05:21:34.918944Z","end":"2026-02-18T05:21:35.019988Z","steps":["trace[792259193] 'read index received' (duration: 100.143457ms)","trace[792259193] 'applied index is now lower than readState.Index' (duration: 899.163µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:35.020104Z","caller":"traceutil/trace.go:171","msg":"trace[1586473249] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"256.372665ms","start":"2026-02-18T05:21:34.763725Z","end":"2026-02-18T05:21:35.020097Z","steps":["trace[1586473249] 'process raft request' (duration: 256.134949ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:35.02019Z","caller":"traceutil/trace.go:171","msg":"trace[1363156089] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"256.410836ms","start":"2026-02-18T05:21:34.763773Z","end":"2026-02-18T05:21:35.020184Z","steps":["trace[1363156089] 'process raft request' (duration: 256.15189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:35.020294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.336927ms","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:35.020313Z","caller":"traceutil/trace.go:171","msg":"trace[1955996568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"101.402569ms","start":"2026-02-18T05:21:34.918905Z","end":"2026-02-18T05:21:35.020308Z","steps":["trace[1955996568] 'agreement among raft nodes before linearized reading' (duration: 101.319537ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:35.019297Z","caller":"traceutil/trace.go:171","msg":"trace[1890574355] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"255.742149ms","start":"2026-02-18T05:21:34.763528Z","end":"2026-02-18T05:21:35.01927Z","steps":["trace[1890574355] 'process raft request' (duration: 255.452891ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:36.184886Z","caller":"traceutil/trace.go:171","msg":"trace[1463400213] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"289.717634ms","start":"2026-02-18T05:21:35.895147Z","end":"2026-02-18T05:21:36.184865Z","steps":["trace[1463400213] 'process raft request' (duration: 289.58425ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:36.185333Z","caller":"traceutil/trace.go:171","msg":"trace[1566817648] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1014; }","duration":"258.189451ms","start":"2026-02-18T05:21:35.927128Z","end":"2026-02-18T05:21:36.185318Z","steps":["trace[1566817648] 'read index received' (duration: 257.545094ms)","trace[1566817648] 'applied index is now lower than readState.Index' (duration: 643.137µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:36.185437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.292463ms","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:36.185465Z","caller":"traceutil/trace.go:171","msg":"trace[321392652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"258.362024ms","start":"2026-02-18T05:21:35.927095Z","end":"2026-02-18T05:21:36.185457Z","steps":["trace[321392652] 'agreement among raft nodes before linearized reading' (duration: 258.270632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.195961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.971916ms","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:21:39.196072Z","caller":"traceutil/trace.go:171","msg":"trace[847559987] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:986; }","duration":"192.090399ms","start":"2026-02-18T05:21:39.003966Z","end":"2026-02-18T05:21:39.196056Z","steps":["trace[847559987] 'range keys from in-memory index tree' (duration: 191.960446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.196295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.372427ms","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:39.199866Z","caller":"traceutil/trace.go:171","msg":"trace[1547649195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:986; }","duration":"162.953696ms","start":"2026-02-18T05:21:39.036877Z","end":"2026-02-18T05:21:39.199831Z","steps":["trace[1547649195] 'range keys from in-memory index tree' (duration: 159.262344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.200166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.814518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T05:21:39.20019Z","caller":"traceutil/trace.go:171","msg":"trace[187964988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"155.877749ms","start":"2026-02-18T05:21:39.044306Z","end":"2026-02-18T05:21:39.200184Z","steps":["trace[187964988] 'range keys from in-memory index tree' (duration: 155.716595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:39.200274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.816198ms","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.200287Z","caller":"traceutil/trace.go:171","msg":"trace[1878283325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"155.868339ms","start":"2026-02-18T05:21:39.044414Z","end":"2026-02-18T05:21:39.200282Z","steps":["trace[1878283325] 'range keys from in-memory index tree' (duration: 155.722306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:43.345555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.467651ms","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:43.34578Z","caller":"traceutil/trace.go:171","msg":"trace[1725560963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"103.722567ms","start":"2026-02-18T05:21:43.242043Z","end":"2026-02-18T05:21:43.345766Z","steps":["trace[1725560963] 'range keys from in-memory index tree' (duration: 103.300067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:43.346245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.407566ms","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:43.346427Z","caller":"traceutil/trace.go:171","msg":"trace[1372663553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"108.526199ms","start":"2026-02-18T05:21:43.237771Z","end":"2026-02-18T05:21:43.346298Z","steps":["trace[1372663553] 'range keys from in-memory index tree' (duration: 108.241531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:49.940795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603918483177503,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T05:21:50.192563Z","caller":"traceutil/trace.go:171","msg":"trace[28812324] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1086; }","duration":"752.174698ms","start":"2026-02-18T05:21:49.440366Z","end":"2026-02-18T05:21:50.192541Z","steps":["trace[28812324] 'read index received' (duration: 751.943133ms)","trace[28812324] 'applied index is now lower than readState.Index' (duration: 230.575µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:21:50.192678Z","caller":"traceutil/trace.go:171","msg":"trace[1466523721] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"805.008976ms","start":"2026-02-18T05:21:49.387658Z","end":"2026-02-18T05:21:50.192667Z","steps":["trace[1466523721] 'process raft request' (duration: 804.715188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.192797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:49.387641Z","time spent":"805.065827ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:50.192904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.537766ms","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:21:50.192972Z","caller":"traceutil/trace.go:171","msg":"trace[1863677928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"742.659819ms","start":"2026-02-18T05:21:49.450293Z","end":"2026-02-18T05:21:50.192953Z","steps":["trace[1863677928] 'agreement among raft nodes before linearized reading' (duration: 742.490244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.193013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:49.450276Z","time spent":"742.726531ms","remote":"127.0.0.1:43190","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:50.193206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.299569ms","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:50.193244Z","caller":"traceutil/trace.go:171","msg":"trace[1363689460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"263.37626ms","start":"2026-02-18T05:21:49.929857Z","end":"2026-02-18T05:21:50.193233Z","steps":["trace[1363689460] 'agreement among raft nodes before linearized reading' (duration: 263.311549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.193352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.470704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T05:21:50.193387Z","caller":"traceutil/trace.go:171","msg":"trace[427972856] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1042; }","duration":"596.544146ms","start":"2026-02-18T05:21:49.596833Z","end":"2026-02-18T05:21:50.193377Z","steps":["trace[427972856] 'agreement among raft nodes before linearized reading' (duration: 596.449224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.193418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:49.596813Z","time spent":"596.599527ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T05:21:50.193469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.104322ms","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:50.1935Z","caller":"traceutil/trace.go:171","msg":"trace[913658167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1042; }","duration":"753.156083ms","start":"2026-02-18T05:21:49.440335Z","end":"2026-02-18T05:21:50.193491Z","steps":["trace[913658167] 'agreement among raft nodes before linearized reading' (duration: 753.04425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.193526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:49.440323Z","time spent":"753.196455ms","remote":"127.0.0.1:43190","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:21:50.193586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.676824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T05:21:50.193616Z","caller":"traceutil/trace.go:171","msg":"trace[1968647430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1042; }","duration":"739.762366ms","start":"2026-02-18T05:21:49.453844Z","end":"2026-02-18T05:21:50.193607Z","steps":["trace[1968647430] 'agreement among raft nodes before linearized reading' (duration: 739.683354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.193641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:49.453834Z","time spent":"739.801487ms","remote":"127.0.0.1:43190","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:50.193728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.965266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-18T05:21:50.193757Z","caller":"traceutil/trace.go:171","msg":"trace[383786132] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1042; }","duration":"126.034498ms","start":"2026-02-18T05:21:50.067714Z","end":"2026-02-18T05:21:50.193749Z","steps":["trace[383786132] 'agreement among raft nodes before linearized reading' (duration: 125.923845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.193935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.106096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T05:21:50.19397Z","caller":"traceutil/trace.go:171","msg":"trace[424680079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1042; }","duration":"742.195497ms","start":"2026-02-18T05:21:49.451765Z","end":"2026-02-18T05:21:50.193961Z","steps":["trace[424680079] 'agreement among raft nodes before linearized reading' (duration: 742.104285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.193996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:49.451755Z","time spent":"742.235308ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T05:21:50.569939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.670927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:50.570403Z","caller":"traceutil/trace.go:171","msg":"trace[895715002] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"368.894424ms","start":"2026-02-18T05:21:50.201482Z","end":"2026-02-18T05:21:50.570376Z","steps":["trace[895715002] 'process raft request' (duration: 79.693693ms)","trace[895715002] 'compare' (duration: 288.334188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:50.570624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.201466Z","time spent":"369.048388ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:50.58219Z","caller":"traceutil/trace.go:171","msg":"trace[1346833651] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"375.827998ms","start":"2026-02-18T05:21:50.206346Z","end":"2026-02-18T05:21:50.582174Z","steps":["trace[1346833651] 'process raft request' (duration: 375.777986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.582298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.206336Z","time spent":"375.906939ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:50.582392Z","caller":"traceutil/trace.go:171","msg":"trace[226235781] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"379.314635ms","start":"2026-02-18T05:21:50.203071Z","end":"2026-02-18T05:21:50.582385Z","steps":["trace[226235781] 'process raft request' (duration: 378.977576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.582428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.203054Z","time spent":"379.355775ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:50.582628Z","caller":"traceutil/trace.go:171","msg":"trace[1053431985] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"374.32512ms","start":"2026-02-18T05:21:50.20827Z","end":"2026-02-18T05:21:50.582595Z","steps":["trace[1053431985] 'process raft request' (duration: 373.878959ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:21:50.582833Z","caller":"traceutil/trace.go:171","msg":"trace[962742520] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"373.53617ms","start":"2026-02-18T05:21:50.209287Z","end":"2026-02-18T05:21:50.582823Z","steps":["trace[962742520] 'process raft request' (duration: 373.227332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.582885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.209276Z","time spent":"373.583551ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T05:21:50.583042Z","caller":"traceutil/trace.go:171","msg":"trace[134139808] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"351.485805ms","start":"2026-02-18T05:21:50.23155Z","end":"2026-02-18T05:21:50.583035Z","steps":["trace[134139808] 'process raft request' (duration: 351.236279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:50.583082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.231541Z","time spent":"351.521406ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:50.583766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.208259Z","time spent":"375.470968ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:51.044289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.58078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T05:21:51.044826Z","caller":"traceutil/trace.go:171","msg":"trace[561030863] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"667.015577ms","start":"2026-02-18T05:21:50.377779Z","end":"2026-02-18T05:21:51.044795Z","steps":["trace[561030863] 'process raft request' (duration: 376.857573ms)","trace[561030863] 'compare' (duration: 289.449927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:51.044982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.377749Z","time spent":"667.13855ms","remote":"127.0.0.1:43112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T05:21:51.24559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.988532ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T05:21:51.245693Z","caller":"traceutil/trace.go:171","msg":"trace[2053371038] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1088; }","duration":"843.208167ms","start":"2026-02-18T05:21:50.402453Z","end":"2026-02-18T05:21:51.245661Z","steps":["trace[2053371038] 'read index received' (duration: 179.588704ms)","trace[2053371038] 'applied index is now lower than readState.Index' (duration: 663.618043ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:21:51.247922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.448763ms","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:51.248121Z","caller":"traceutil/trace.go:171","msg":"trace[2137618260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"845.621687ms","start":"2026-02-18T05:21:50.402411Z","end":"2026-02-18T05:21:51.248033Z","steps":["trace[2137618260] 'agreement among raft nodes before linearized reading' (duration: 843.309559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:21:51.24825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T05:21:50.402392Z","time spent":"845.829402ms","remote":"127.0.0.1:43190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T05:22:01.566574Z","caller":"traceutil/trace.go:171","msg":"trace[84108057] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"134.233926ms","start":"2026-02-18T05:22:01.432306Z","end":"2026-02-18T05:22:01.56654Z","steps":["trace[84108057] 'process raft request' (duration: 85.542225ms)","trace[84108057] 'compare' (duration: 48.579168ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:22:02.894116Z","caller":"traceutil/trace.go:171","msg":"trace[1731305670] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1141; }","duration":"166.042721ms","start":"2026-02-18T05:22:02.728056Z","end":"2026-02-18T05:22:02.894098Z","steps":["trace[1731305670] 'read index received' (duration: 34.089934ms)","trace[1731305670] 'applied index is now lower than readState.Index' (duration: 131.952287ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T05:22:02.894213Z","caller":"traceutil/trace.go:171","msg":"trace[1661419585] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"188.686299ms","start":"2026-02-18T05:22:02.70552Z","end":"2026-02-18T05:22:02.894206Z","steps":["trace[1661419585] 'process raft request' (duration: 109.184417ms)","trace[1661419585] 'compare' (duration: 79.315398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:22:02.894397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.854147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T05:22:02.894454Z","caller":"traceutil/trace.go:171","msg":"trace[1080480561] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1095; }","duration":"159.929129ms","start":"2026-02-18T05:22:02.734511Z","end":"2026-02-18T05:22:02.89444Z","steps":["trace[1080480561] 'agreement among raft nodes before linearized reading' (duration: 159.781105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T05:22:02.894555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.485363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T05:22:02.89458Z","caller":"traceutil/trace.go:171","msg":"trace[1948385156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"166.547474ms","start":"2026-02-18T05:22:02.728025Z","end":"2026-02-18T05:22:02.894573Z","steps":["trace[1948385156] 'agreement among raft nodes before linearized reading' (duration: 166.452822ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:24:47.089107Z","caller":"traceutil/trace.go:171","msg":"trace[1721948243] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"194.328163ms","start":"2026-02-18T05:24:46.894766Z","end":"2026-02-18T05:24:47.089094Z","steps":["trace[1721948243] 'process raft request' (duration: 115.658216ms)","trace[1721948243] 'compare' (duration: 78.347569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:24:47.104586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.530051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T05:24:47.104639Z","caller":"traceutil/trace.go:171","msg":"trace[694095089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1791; }","duration":"177.616234ms","start":"2026-02-18T05:24:46.927008Z","end":"2026-02-18T05:24:47.104624Z","steps":["trace[694095089] 'agreement among raft nodes before linearized reading' (duration: 177.522811ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:24:47.088905Z","caller":"traceutil/trace.go:171","msg":"trace[257617090] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"161.825956ms","start":"2026-02-18T05:24:46.927046Z","end":"2026-02-18T05:24:47.088872Z","steps":["trace[257617090] 'read index received' (duration: 83.313653ms)","trace[257617090] 'applied index is now lower than readState.Index' (duration: 78.507163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:24:49.804282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.967475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-18T05:24:49.804336Z","caller":"traceutil/trace.go:171","msg":"trace[1460385007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1799; }","duration":"126.114439ms","start":"2026-02-18T05:24:49.678207Z","end":"2026-02-18T05:24:49.804321Z","steps":["trace[1460385007] 'range keys from in-memory index tree' (duration: 125.727429ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:24:50.02528Z","caller":"traceutil/trace.go:171","msg":"trace[872456851] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"110.148888ms","start":"2026-02-18T05:24:49.915086Z","end":"2026-02-18T05:24:50.025235Z","steps":["trace[872456851] 'read index received' (duration: 109.903452ms)","trace[872456851] 'applied index is now lower than readState.Index' (duration: 244.546µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T05:24:50.02572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.483356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T05:24:50.02576Z","caller":"traceutil/trace.go:171","msg":"trace[860852388] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1800; }","duration":"110.691061ms","start":"2026-02-18T05:24:49.915058Z","end":"2026-02-18T05:24:50.025749Z","steps":["trace[860852388] 'agreement among raft nodes before linearized reading' (duration: 110.307741ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T05:24:50.026649Z","caller":"traceutil/trace.go:171","msg":"trace[149909511] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"216.564132ms","start":"2026-02-18T05:24:49.810066Z","end":"2026-02-18T05:24:50.02663Z","steps":["trace[149909511] 'process raft request' (duration: 215.003413ms)"],"step_count":1}