{"level":"warn","ts":"2026-02-13T06:56:40.014045Z","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-13T06:56:40.014787Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-13T06:56:40.015018Z","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-13T06:56:40.015041Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-13T06:56:40.015081Z","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-13T06:56:40.015994Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-02-13T06:56:40.016134Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-13T06:56:40.023331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.606528ms"} {"level":"info","ts":"2026-02-13T06:56:40.029268Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-02-13T06:56:40.029524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T06:56:40.029542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-02-13T06:56:40.029549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T06:56:40.029555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-02-13T06:56:40.029595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-02-13T06:56:40.036439Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T06:56:40.046372Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T06:56:40.047432Z","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-13T06:56:40.049185Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T06:56:40.053692Z","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-13T06:56:40.053862Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T06:56:40.053879Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T06:56:40.053986Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T06:56:40.054115Z","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-13T06:56:40.054133Z","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-13T06:56:40.054139Z","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-13T06:56:40.054708Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-13T06:56:40.054719Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-13T06:56:40.055607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-02-13T06:56:40.055669Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-13T06:56:40.531168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T06:56:40.531271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T06:56:40.531361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-02-13T06:56:40.531424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-02-13T06:56:40.531447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-13T06:56:40.53149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-02-13T06:56:40.531513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-13T06:56:40.581107Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T06:56:40.581449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T06:56:40.581551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T06:56:40.582575Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T06:56:40.582599Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T06:56:40.582562Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:56:40.589822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-02-13T06:56:40.59543Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:56:40.596502Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:56:40.601774Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T06:56:40.612926Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T06:57:07.125208Z","caller":"traceutil/trace.go:171","msg":"trace[1950812014] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"124.139502ms","start":"2026-02-13T06:57:07.001043Z","end":"2026-02-13T06:57:07.125182Z","steps":["trace[1950812014] 'read index received' (duration: 123.800555ms)","trace[1950812014] 'applied index is now lower than readState.Index' (duration: 337.947µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:07.125401Z","caller":"traceutil/trace.go:171","msg":"trace[1053196968] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"172.868582ms","start":"2026-02-13T06:57:06.952521Z","end":"2026-02-13T06:57:07.12539Z","steps":["trace[1053196968] 'process raft request' (duration: 172.436113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:07.125678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.621063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:57:07.125736Z","caller":"traceutil/trace.go:171","msg":"trace[2007719760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"124.731064ms","start":"2026-02-13T06:57:07.000992Z","end":"2026-02-13T06:57:07.125723Z","steps":["trace[2007719760] 'agreement among raft nodes before linearized reading' (duration: 124.601482ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:07.528457Z","caller":"traceutil/trace.go:171","msg":"trace[1262370320] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"396.9015ms","start":"2026-02-13T06:57:07.131505Z","end":"2026-02-13T06:57:07.528407Z","steps":["trace[1262370320] 'process raft request' (duration: 382.007409ms)","trace[1262370320] 'compare' (duration: 12.998631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:07.529236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:07.131486Z","time spent":"397.053763ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:07.529773Z","caller":"traceutil/trace.go:171","msg":"trace[439354479] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"397.706447ms","start":"2026-02-13T06:57:07.13206Z","end":"2026-02-13T06:57:07.529767Z","steps":["trace[439354479] 'process raft request' (duration: 397.515083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:07.529818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:07.132039Z","time spent":"397.755298ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:07.529902Z","caller":"traceutil/trace.go:171","msg":"trace[414122634] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"393.130781ms","start":"2026-02-13T06:57:07.136611Z","end":"2026-02-13T06:57:07.529742Z","steps":["trace[414122634] 'process raft request' (duration: 393.10168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:07.529942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:07.136598Z","time spent":"393.323875ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:16.132203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.35418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:57:16.13232Z","caller":"traceutil/trace.go:171","msg":"trace[169326304] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"608.877787ms","start":"2026-02-13T06:57:15.523424Z","end":"2026-02-13T06:57:16.132302Z","steps":["trace[169326304] 'read index received' (duration: 87.168361ms)","trace[169326304] 'applied index is now lower than readState.Index' (duration: 521.708026ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:16.13238Z","caller":"traceutil/trace.go:171","msg":"trace[116453109] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"613.743779ms","start":"2026-02-13T06:57:15.518627Z","end":"2026-02-13T06:57:16.132371Z","steps":["trace[116453109] 'process raft request' (duration: 92.015182ms)","trace[116453109] 'compare' (duration: 521.226356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:16.13242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.518608Z","time spent":"613.79312ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T06:57:16.132571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.108052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rgw6q\" ","response":"range_response_count:1 size:4818"} {"level":"info","ts":"2026-02-13T06:57:16.132658Z","caller":"traceutil/trace.go:171","msg":"trace[517622470] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rgw6q; range_end:; response_count:1; response_revision:545; }","duration":"609.255575ms","start":"2026-02-13T06:57:15.523385Z","end":"2026-02-13T06:57:16.13264Z","steps":["trace[517622470] 'agreement among raft nodes before linearized reading' (duration: 609.02275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:16.132756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.523372Z","time spent":"609.377097ms","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4842,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rgw6q\" "} {"level":"warn","ts":"2026-02-13T06:57:16.639395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.654739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:57:16.639499Z","caller":"traceutil/trace.go:171","msg":"trace[778469207] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"940.194298ms","start":"2026-02-13T06:57:15.69929Z","end":"2026-02-13T06:57:16.639485Z","steps":["trace[778469207] 'process raft request' (duration: 594.309474ms)","trace[778469207] 'compare' (duration: 345.494266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:16.63956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.699265Z","time spent":"940.277309ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:57:17.272136Z","caller":"traceutil/trace.go:171","msg":"trace[1612212520] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:562; }","duration":"1.139776837s","start":"2026-02-13T06:57:16.13234Z","end":"2026-02-13T06:57:17.272117Z","steps":["trace[1612212520] 'read index received' (duration: 161.271799ms)","trace[1612212520] 'applied index is now lower than readState.Index' (duration: 978.504258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:17.272562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213876154s","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-13T06:57:17.272631Z","caller":"traceutil/trace.go:171","msg":"trace[1787333098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"1.214006616s","start":"2026-02-13T06:57:16.058615Z","end":"2026-02-13T06:57:17.272622Z","steps":["trace[1787333098] 'agreement among raft nodes before linearized reading' (duration: 1.213785861s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.272821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.058594Z","time spent":"1.214076998s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T06:57:17.272855Z","caller":"traceutil/trace.go:171","msg":"trace[34603945] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.35348437s","start":"2026-02-13T06:57:15.919349Z","end":"2026-02-13T06:57:17.272833Z","steps":["trace[34603945] 'process raft request' (duration: 1.352542601s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.272956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.919306Z","time spent":"1.353601763s","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:57:17.273246Z","caller":"traceutil/trace.go:171","msg":"trace[595788393] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.129781049s","start":"2026-02-13T06:57:16.143458Z","end":"2026-02-13T06:57:17.273239Z","steps":["trace[595788393] 'process raft request' (duration: 1.128578763s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.27332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.143432Z","time spent":"1.12985725s","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4609,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.27345Z","caller":"traceutil/trace.go:171","msg":"trace[1013835282] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.1298403s","start":"2026-02-13T06:57:16.143597Z","end":"2026-02-13T06:57:17.273437Z","steps":["trace[1013835282] 'process raft request' (duration: 1.128487941s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.273493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.143576Z","time spent":"1.129891921s","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:17.273844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647468721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:57:17.273867Z","caller":"traceutil/trace.go:171","msg":"trace[835139409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"1.647517531s","start":"2026-02-13T06:57:15.626341Z","end":"2026-02-13T06:57:17.273858Z","steps":["trace[835139409] 'agreement among raft nodes before linearized reading' (duration: 1.64747124s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.273887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.626322Z","time spent":"1.647560252s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T06:57:17.274009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348720231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:17.274027Z","caller":"traceutil/trace.go:171","msg":"trace[765250197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"1.348778673s","start":"2026-02-13T06:57:15.925242Z","end":"2026-02-13T06:57:17.274021Z","steps":["trace[765250197] 'agreement among raft nodes before linearized reading' (duration: 1.348748112s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.274047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.925229Z","time spent":"1.348812203s","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:57:17.274141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644400576s","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-13T06:57:17.274158Z","caller":"traceutil/trace.go:171","msg":"trace[111802157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"1.644424697s","start":"2026-02-13T06:57:15.629728Z","end":"2026-02-13T06:57:17.274153Z","steps":["trace[111802157] 'agreement among raft nodes before linearized reading' (duration: 1.644392237s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.274174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.629726Z","time spent":"1.644445038s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T06:57:17.282258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652541106s","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-13T06:57:17.282284Z","caller":"traceutil/trace.go:171","msg":"trace[1570339780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"1.652584077s","start":"2026-02-13T06:57:15.629692Z","end":"2026-02-13T06:57:17.282276Z","steps":["trace[1570339780] 'agreement among raft nodes before linearized reading' (duration: 1.644547279s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.282304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.629683Z","time spent":"1.652616648s","remote":"127.0.0.1:36474","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-13T06:57:17.272536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167396803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:17.282826Z","caller":"traceutil/trace.go:171","msg":"trace[32065972] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:549; }","duration":"1.177733278s","start":"2026-02-13T06:57:16.10505Z","end":"2026-02-13T06:57:17.282783Z","steps":["trace[32065972] 'agreement among raft nodes before linearized reading' (duration: 1.167318421s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.282995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.105025Z","time spent":"1.177919013s","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-13T06:57:17.666415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.067616687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:17.666515Z","caller":"traceutil/trace.go:171","msg":"trace[1659323986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"2.067724939s","start":"2026-02-13T06:57:15.598761Z","end":"2026-02-13T06:57:17.666486Z","steps":["trace[1659323986] 'agreement among raft nodes before linearized reading' (duration: 1.68381888s)","trace[1659323986] 'range keys from in-memory index tree' (duration: 383.758785ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:17.666564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:15.598671Z","time spent":"2.067881971s","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:57:17.666583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.66709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:57:17.666682Z","caller":"traceutil/trace.go:171","msg":"trace[65490817] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"394.286035ms","start":"2026-02-13T06:57:17.272379Z","end":"2026-02-13T06:57:17.666665Z","steps":["trace[65490817] 'read index received' (duration: 274.393791ms)","trace[65490817] 'applied index is now lower than readState.Index' (duration: 119.891004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:17.666879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.668575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-02-13T06:57:17.66688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.212981ms","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-13T06:57:17.666899Z","caller":"traceutil/trace.go:171","msg":"trace[224211347] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:550; }","duration":"756.732576ms","start":"2026-02-13T06:57:16.910158Z","end":"2026-02-13T06:57:17.66689Z","steps":["trace[224211347] 'agreement among raft nodes before linearized reading' (duration: 756.564043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.666924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.910134Z","time spent":"756.783977ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-02-13T06:57:17.66692Z","caller":"traceutil/trace.go:171","msg":"trace[870514389] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:550; }","duration":"571.297942ms","start":"2026-02-13T06:57:17.095609Z","end":"2026-02-13T06:57:17.666907Z","steps":["trace[870514389] 'agreement among raft nodes before linearized reading' (duration: 571.094468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.666966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.095586Z","time spent":"571.371124ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-13T06:57:17.667093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.054502ms","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-13T06:57:17.667109Z","caller":"traceutil/trace.go:171","msg":"trace[1037732295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:550; }","duration":"791.071122ms","start":"2026-02-13T06:57:16.876033Z","end":"2026-02-13T06:57:17.667104Z","steps":["trace[1037732295] 'agreement among raft nodes before linearized reading' (duration: 791.022751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.667121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.876005Z","time spent":"791.113774ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T06:57:17.667224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.549631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-13T06:57:17.667236Z","caller":"traceutil/trace.go:171","msg":"trace[1195658760] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:550; }","duration":"969.610412ms","start":"2026-02-13T06:57:16.697622Z","end":"2026-02-13T06:57:17.667232Z","steps":["trace[1195658760] 'agreement among raft nodes before linearized reading' (duration: 969.566321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.667248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.697599Z","time spent":"969.646182ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-13T06:57:17.667341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171169912s","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-13T06:57:17.66736Z","caller":"traceutil/trace.go:171","msg":"trace[10469543] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"1.171224762s","start":"2026-02-13T06:57:16.496127Z","end":"2026-02-13T06:57:17.667352Z","steps":["trace[10469543] 'agreement among raft nodes before linearized reading' (duration: 1.171196962s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.667379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.496103Z","time spent":"1.171271534s","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-13T06:57:17.667385Z","caller":"traceutil/trace.go:171","msg":"trace[1396984486] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"465.38233ms","start":"2026-02-13T06:57:17.201985Z","end":"2026-02-13T06:57:17.667367Z","steps":["trace[1396984486] 'process raft request' (duration: 344.858902ms)","trace[1396984486] 'compare' (duration: 119.233431ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:17.667469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36881061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:17.667493Z","caller":"traceutil/trace.go:171","msg":"trace[437743324] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:550; }","duration":"1.368882661s","start":"2026-02-13T06:57:16.298601Z","end":"2026-02-13T06:57:17.667483Z","steps":["trace[437743324] 'agreement among raft nodes before linearized reading' (duration: 1.36884955s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.667514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:16.298578Z","time spent":"1.368928442s","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-13T06:57:17.667524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.201964Z","time spent":"465.484702ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.803862Z","caller":"traceutil/trace.go:171","msg":"trace[818565905] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"474.045681ms","start":"2026-02-13T06:57:17.329789Z","end":"2026-02-13T06:57:17.803835Z","steps":["trace[818565905] 'process raft request' (duration: 471.554069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.804013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.329786Z","time spent":"474.148563ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.804321Z","caller":"traceutil/trace.go:171","msg":"trace[276537954] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"474.564242ms","start":"2026-02-13T06:57:17.329745Z","end":"2026-02-13T06:57:17.80431Z","steps":["trace[276537954] 'process raft request' (duration: 473.339327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.80438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.329737Z","time spent":"474.609883ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.80489Z","caller":"traceutil/trace.go:171","msg":"trace[298881557] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"467.074645ms","start":"2026-02-13T06:57:17.337802Z","end":"2026-02-13T06:57:17.804877Z","steps":["trace[298881557] 'process raft request' (duration: 465.461722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.80499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.337783Z","time spent":"467.136616ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.80514Z","caller":"traceutil/trace.go:171","msg":"trace[1391255119] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"469.19335ms","start":"2026-02-13T06:57:17.335936Z","end":"2026-02-13T06:57:17.80513Z","steps":["trace[1391255119] 'process raft request' (duration: 467.25544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.805194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.335906Z","time spent":"469.25658ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.810816Z","caller":"traceutil/trace.go:171","msg":"trace[960754708] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"466.755678ms","start":"2026-02-13T06:57:17.344035Z","end":"2026-02-13T06:57:17.810791Z","steps":["trace[960754708] 'process raft request' (duration: 459.264842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.811014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.344004Z","time spent":"466.891861ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.811182Z","caller":"traceutil/trace.go:171","msg":"trace[2132960981] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"464.842219ms","start":"2026-02-13T06:57:17.346333Z","end":"2026-02-13T06:57:17.811175Z","steps":["trace[2132960981] 'process raft request' (duration: 457.014265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.811223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.34632Z","time spent":"464.873639ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.811303Z","caller":"traceutil/trace.go:171","msg":"trace[272254860] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"462.762186ms","start":"2026-02-13T06:57:17.348534Z","end":"2026-02-13T06:57:17.811296Z","steps":["trace[272254860] 'process raft request' (duration: 454.833621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.811329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.348511Z","time spent":"462.803287ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:17.811379Z","caller":"traceutil/trace.go:171","msg":"trace[1374191589] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:567; }","duration":"144.660822ms","start":"2026-02-13T06:57:17.666699Z","end":"2026-02-13T06:57:17.81136Z","steps":["trace[1374191589] 'read index received' (duration: 134.55672ms)","trace[1374191589] 'applied index is now lower than readState.Index' (duration: 10.103122ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:17.811613Z","caller":"traceutil/trace.go:171","msg":"trace[912997368] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"263.044505ms","start":"2026-02-13T06:57:17.548559Z","end":"2026-02-13T06:57:17.811604Z","steps":["trace[912997368] 'process raft request' (duration: 262.583525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.811735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.962876ms","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-13T06:57:17.811779Z","caller":"traceutil/trace.go:171","msg":"trace[1003288568] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:560; }","duration":"482.113549ms","start":"2026-02-13T06:57:17.329657Z","end":"2026-02-13T06:57:17.811771Z","steps":["trace[1003288568] 'agreement among raft nodes before linearized reading' (duration: 482.041408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.811783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.673745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rgw6q\" ","response":"range_response_count:1 size:4624"} {"level":"warn","ts":"2026-02-13T06:57:17.811796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.32964Z","time spent":"482.151571ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-02-13T06:57:17.811815Z","caller":"traceutil/trace.go:171","msg":"trace[438474974] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rgw6q; range_end:; response_count:1; response_revision:560; }","duration":"486.741427ms","start":"2026-02-13T06:57:17.325062Z","end":"2026-02-13T06:57:17.811803Z","steps":["trace[438474974] 'agreement among raft nodes before linearized reading' (duration: 486.669345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.811841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.325043Z","time spent":"486.789988ms","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4648,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rgw6q\" "} {"level":"info","ts":"2026-02-13T06:57:17.811929Z","caller":"traceutil/trace.go:171","msg":"trace[2046243915] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"201.124951ms","start":"2026-02-13T06:57:17.610794Z","end":"2026-02-13T06:57:17.811919Z","steps":["trace[2046243915] 'process raft request' (duration: 200.476868ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:17.812048Z","caller":"traceutil/trace.go:171","msg":"trace[779199329] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"137.205466ms","start":"2026-02-13T06:57:17.674834Z","end":"2026-02-13T06:57:17.81204Z","steps":["trace[779199329] 'process raft request' (duration: 136.483491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.812969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.030467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:17.812993Z","caller":"traceutil/trace.go:171","msg":"trace[538905614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"199.098868ms","start":"2026-02-13T06:57:17.613889Z","end":"2026-02-13T06:57:17.812988Z","steps":["trace[538905614] 'agreement among raft nodes before linearized reading' (duration: 199.052827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.813247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.352459ms","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-13T06:57:17.813537Z","caller":"traceutil/trace.go:171","msg":"trace[1397887335] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:560; }","duration":"317.41729ms","start":"2026-02-13T06:57:17.495846Z","end":"2026-02-13T06:57:17.813263Z","steps":["trace[1397887335] 'agreement among raft nodes before linearized reading' (duration: 317.292617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:17.813577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:17.495823Z","time spent":"317.746516ms","remote":"127.0.0.1:36276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-13T06:57:17.831652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.9277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-13T06:57:17.831738Z","caller":"traceutil/trace.go:171","msg":"trace[498397244] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:560; }","duration":"136.068933ms","start":"2026-02-13T06:57:17.69565Z","end":"2026-02-13T06:57:17.831719Z","steps":["trace[498397244] 'agreement among raft nodes before linearized reading' (duration: 116.29226ms)","trace[498397244] 'range keys from bolt db' (duration: 19.61711ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:18.40047Z","caller":"traceutil/trace.go:171","msg":"trace[767775048] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"244.706731ms","start":"2026-02-13T06:57:18.155739Z","end":"2026-02-13T06:57:18.400446Z","steps":["trace[767775048] 'process raft request' (duration: 239.288258ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:23.983842Z","caller":"traceutil/trace.go:171","msg":"trace[341728731] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"117.213049ms","start":"2026-02-13T06:57:23.866593Z","end":"2026-02-13T06:57:23.983806Z","steps":["trace[341728731] 'process raft request' (duration: 116.810931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:23.987467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.98539ms","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-13T06:57:23.987556Z","caller":"traceutil/trace.go:171","msg":"trace[586442376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"103.121603ms","start":"2026-02-13T06:57:23.884419Z","end":"2026-02-13T06:57:23.987541Z","steps":["trace[586442376] 'agreement among raft nodes before linearized reading' (duration: 99.639201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.09913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751994884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:57:26.511733Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034436707s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T06:57:26.512132Z","caller":"traceutil/trace.go:171","msg":"trace[547273949] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"913.714386ms","start":"2026-02-13T06:57:25.598392Z","end":"2026-02-13T06:57:26.512106Z","steps":["trace[547273949] 'read index received' (duration: 913.531902ms)","trace[547273949] 'applied index is now lower than readState.Index' (duration: 181.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:26.512285Z","caller":"traceutil/trace.go:171","msg":"trace[1238303503] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.035131112s","start":"2026-02-13T06:57:25.477126Z","end":"2026-02-13T06:57:26.512258Z","steps":["trace[1238303503] 'process raft request' (duration: 1.034819554s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.5124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:25.477095Z","time spent":"1.035221584s","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:26.512649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.255957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:26.512679Z","caller":"traceutil/trace.go:171","msg":"trace[682339869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"914.337499ms","start":"2026-02-13T06:57:25.598332Z","end":"2026-02-13T06:57:26.51267Z","steps":["trace[682339869] 'agreement among raft nodes before linearized reading' (duration: 914.198486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.512706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:25.59831Z","time spent":"914.39093ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:57:26.512977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.589829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T06:57:26.513036Z","caller":"traceutil/trace.go:171","msg":"trace[1035408034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"615.715021ms","start":"2026-02-13T06:57:25.897299Z","end":"2026-02-13T06:57:26.513014Z","steps":["trace[1035408034] 'agreement among raft nodes before linearized reading' (duration: 615.513747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.513086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:25.897275Z","time spent":"615.801582ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T06:57:26.513107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.823701ms","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-13T06:57:26.513147Z","caller":"traceutil/trace.go:171","msg":"trace[2127381742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:595; }","duration":"511.877833ms","start":"2026-02-13T06:57:26.001257Z","end":"2026-02-13T06:57:26.513135Z","steps":["trace[2127381742] 'agreement among raft nodes before linearized reading' (duration: 511.806431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.513174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.001249Z","time spent":"511.919173ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T06:57:26.513265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.565736ms","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-13T06:57:26.513289Z","caller":"traceutil/trace.go:171","msg":"trace[530854009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"511.604096ms","start":"2026-02-13T06:57:26.001675Z","end":"2026-02-13T06:57:26.51328Z","steps":["trace[530854009] 'agreement among raft nodes before linearized reading' (duration: 511.562016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.513312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.001664Z","time spent":"511.642917ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T06:57:26.513336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.096876ms","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-13T06:57:26.513407Z","caller":"traceutil/trace.go:171","msg":"trace[1261940264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"512.174868ms","start":"2026-02-13T06:57:26.001185Z","end":"2026-02-13T06:57:26.51336Z","steps":["trace[1261940264] 'agreement among raft nodes before linearized reading' (duration: 512.110987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.513437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.001173Z","time spent":"512.25729ms","remote":"127.0.0.1:36474","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-13T06:57:26.51357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.828429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:57:26.5136Z","caller":"traceutil/trace.go:171","msg":"trace[1026608211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"521.895421ms","start":"2026-02-13T06:57:25.991696Z","end":"2026-02-13T06:57:26.513591Z","steps":["trace[1026608211] 'agreement among raft nodes before linearized reading' (duration: 521.84704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:26.513629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:25.991677Z","time spent":"521.944712ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T06:57:27.390957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.049598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:57:27.391067Z","caller":"traceutil/trace.go:171","msg":"trace[541259943] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"864.101178ms","start":"2026-02-13T06:57:26.526946Z","end":"2026-02-13T06:57:27.391048Z","steps":["trace[541259943] 'read index received' (duration: 302.890986ms)","trace[541259943] 'applied index is now lower than readState.Index' (duration: 561.208912ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:27.391239Z","caller":"traceutil/trace.go:171","msg":"trace[1701532446] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"865.397435ms","start":"2026-02-13T06:57:26.525829Z","end":"2026-02-13T06:57:27.391226Z","steps":["trace[1701532446] 'process raft request' (duration: 303.99713ms)","trace[1701532446] 'compare' (duration: 560.67707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:27.391302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.525804Z","time spent":"865.466647ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:27.391567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.617038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8pqdv\" ","response":"range_response_count:1 size:16814"} {"level":"info","ts":"2026-02-13T06:57:27.391598Z","caller":"traceutil/trace.go:171","msg":"trace[1764673490] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8pqdv; range_end:; response_count:1; response_revision:596; }","duration":"864.66324ms","start":"2026-02-13T06:57:26.526924Z","end":"2026-02-13T06:57:27.391587Z","steps":["trace[1764673490] 'agreement among raft nodes before linearized reading' (duration: 864.536187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:27.391629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.526915Z","time spent":"864.70761ms","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16838,"request content":"key:\"/registry/pods/kube-system/cilium-8pqdv\" "} {"level":"info","ts":"2026-02-13T06:57:27.486504Z","caller":"traceutil/trace.go:171","msg":"trace[1100463017] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"959.47159ms","start":"2026-02-13T06:57:26.527002Z","end":"2026-02-13T06:57:27.486474Z","steps":["trace[1100463017] 'process raft request' (duration: 959.284556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:27.486757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.526995Z","time spent":"959.660954ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:27.486992Z","caller":"traceutil/trace.go:171","msg":"trace[1060882296] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"959.612324ms","start":"2026-02-13T06:57:26.527336Z","end":"2026-02-13T06:57:27.486948Z","steps":["trace[1060882296] 'process raft request' (duration: 959.085452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:27.487166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.527328Z","time spent":"959.751456ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:27.487397Z","caller":"traceutil/trace.go:171","msg":"trace[879163211] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"958.423609ms","start":"2026-02-13T06:57:26.52896Z","end":"2026-02-13T06:57:27.487384Z","steps":["trace[879163211] 'process raft request' (duration: 957.904607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:27.487472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.528951Z","time spent":"958.48197ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:27.487668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.958175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:27.487711Z","caller":"traceutil/trace.go:171","msg":"trace[2121677301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"886.033697ms","start":"2026-02-13T06:57:26.601659Z","end":"2026-02-13T06:57:27.487693Z","steps":["trace[2121677301] 'agreement among raft nodes before linearized reading' (duration: 885.920605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:27.48775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.601632Z","time spent":"886.109319ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T06:57:27.487769Z","caller":"traceutil/trace.go:171","msg":"trace[1260676650] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"957.041ms","start":"2026-02-13T06:57:26.530711Z","end":"2026-02-13T06:57:27.487752Z","steps":["trace[1260676650] 'process raft request' (duration: 956.596711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:27.487839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:26.530702Z","time spent":"957.098201ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:34.804617Z","caller":"traceutil/trace.go:171","msg":"trace[145191561] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"307.001223ms","start":"2026-02-13T06:57:34.497591Z","end":"2026-02-13T06:57:34.804592Z","steps":["trace[145191561] 'process raft request' (duration: 306.85816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:34.804776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:34.497563Z","time spent":"307.141266ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4468,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:34.814098Z","caller":"traceutil/trace.go:171","msg":"trace[729699784] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"262.921373ms","start":"2026-02-13T06:57:34.551156Z","end":"2026-02-13T06:57:34.814077Z","steps":["trace[729699784] 'read index received' (duration: 253.328493ms)","trace[729699784] 'applied index is now lower than readState.Index' (duration: 9.59165ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:34.81413Z","caller":"traceutil/trace.go:171","msg":"trace[10282771] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"315.199994ms","start":"2026-02-13T06:57:34.498886Z","end":"2026-02-13T06:57:34.814086Z","steps":["trace[10282771] 'process raft request' (duration: 315.044651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:34.814278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:34.498879Z","time spent":"315.330646ms","remote":"127.0.0.1:36308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":614,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T06:57:34.814385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.210028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8pqdv\" ","response":"range_response_count:1 size:18071"} {"level":"info","ts":"2026-02-13T06:57:34.814445Z","caller":"traceutil/trace.go:171","msg":"trace[2123123168] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8pqdv; range_end:; response_count:1; response_revision:644; }","duration":"263.340521ms","start":"2026-02-13T06:57:34.551096Z","end":"2026-02-13T06:57:34.814437Z","steps":["trace[2123123168] 'agreement among raft nodes before linearized reading' (duration: 263.087246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:34.814665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.251346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:34.814697Z","caller":"traceutil/trace.go:171","msg":"trace[88045254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"215.350948ms","start":"2026-02-13T06:57:34.599339Z","end":"2026-02-13T06:57:34.81469Z","steps":["trace[88045254] 'agreement among raft nodes before linearized reading' (duration: 215.280287ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:37.653771Z","caller":"traceutil/trace.go:171","msg":"trace[1998139948] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"103.489902ms","start":"2026-02-13T06:57:37.550247Z","end":"2026-02-13T06:57:37.653737Z","steps":["trace[1998139948] 'process raft request' (duration: 103.224506ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:38.183397Z","caller":"traceutil/trace.go:171","msg":"trace[262130490] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"215.760537ms","start":"2026-02-13T06:57:37.967616Z","end":"2026-02-13T06:57:38.183376Z","steps":["trace[262130490] 'process raft request' (duration: 211.904606ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:38.318403Z","caller":"traceutil/trace.go:171","msg":"trace[573460536] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"335.963378ms","start":"2026-02-13T06:57:37.982419Z","end":"2026-02-13T06:57:38.318382Z","steps":["trace[573460536] 'process raft request' (duration: 335.923767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:38.323422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:37.982402Z","time spent":"336.056349ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4634,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:38.323679Z","caller":"traceutil/trace.go:171","msg":"trace[1790632529] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"353.723859ms","start":"2026-02-13T06:57:37.969943Z","end":"2026-02-13T06:57:38.323667Z","steps":["trace[1790632529] 'process raft request' (duration: 347.627771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:38.323737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:37.969919Z","time spent":"353.785659ms","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:38.323889Z","caller":"traceutil/trace.go:171","msg":"trace[33019240] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"353.870221ms","start":"2026-02-13T06:57:37.970013Z","end":"2026-02-13T06:57:38.323883Z","steps":["trace[33019240] 'process raft request' (duration: 347.711042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:38.323931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:37.970008Z","time spent":"353.898511ms","remote":"127.0.0.1:36392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:38.328175Z","caller":"traceutil/trace.go:171","msg":"trace[123078104] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:690; }","duration":"117.144756ms","start":"2026-02-13T06:57:38.211021Z","end":"2026-02-13T06:57:38.328166Z","steps":["trace[123078104] 'read index received' (duration: 107.084156ms)","trace[123078104] 'applied index is now lower than readState.Index' (duration: 10.0602ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:38.328484Z","caller":"traceutil/trace.go:171","msg":"trace[546838244] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"140.452745ms","start":"2026-02-13T06:57:38.188025Z","end":"2026-02-13T06:57:38.328477Z","steps":["trace[546838244] 'process raft request' (duration: 138.211788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:38.334705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.666622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-k4z4c\" ","response":"range_response_count:1 size:3813"} {"level":"info","ts":"2026-02-13T06:57:38.334905Z","caller":"traceutil/trace.go:171","msg":"trace[1533174110] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-k4z4c; range_end:; response_count:1; response_revision:671; }","duration":"123.903258ms","start":"2026-02-13T06:57:38.210982Z","end":"2026-02-13T06:57:38.334885Z","steps":["trace[1533174110] 'agreement among raft nodes before linearized reading' (duration: 117.317891ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:38.480451Z","caller":"traceutil/trace.go:171","msg":"trace[1929302208] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"131.488097ms","start":"2026-02-13T06:57:38.348799Z","end":"2026-02-13T06:57:38.480287Z","steps":["trace[1929302208] 'process raft request' (duration: 68.735606ms)","trace[1929302208] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/kube-system/coredns-5dd5756b68-k4z4c; req_size:4678; } (duration: 62.168639ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:39.824104Z","caller":"traceutil/trace.go:171","msg":"trace[1436886279] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"352.184587ms","start":"2026-02-13T06:57:39.471896Z","end":"2026-02-13T06:57:39.824081Z","steps":["trace[1436886279] 'read index received' (duration: 351.974642ms)","trace[1436886279] 'applied index is now lower than readState.Index' (duration: 209.145µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:39.824209Z","caller":"traceutil/trace.go:171","msg":"trace[1784531808] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"628.651575ms","start":"2026-02-13T06:57:39.195551Z","end":"2026-02-13T06:57:39.824203Z","steps":["trace[1784531808] 'process raft request' (duration: 628.309487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:39.824315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:39.195524Z","time spent":"628.715296ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1236,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T06:57:39.824484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.563887ms","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-13T06:57:39.82455Z","caller":"traceutil/trace.go:171","msg":"trace[996243693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"352.675069ms","start":"2026-02-13T06:57:39.471858Z","end":"2026-02-13T06:57:39.824533Z","steps":["trace[996243693] 'agreement among raft nodes before linearized reading' (duration: 352.434213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:39.82458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:39.47184Z","time spent":"352.734131ms","remote":"127.0.0.1:36474","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-13T06:57:39.871782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.024105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:39.87185Z","caller":"traceutil/trace.go:171","msg":"trace[1298649515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"275.140908ms","start":"2026-02-13T06:57:39.596697Z","end":"2026-02-13T06:57:39.871837Z","steps":["trace[1298649515] 'agreement among raft nodes before linearized reading' (duration: 275.030575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:39.872165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.702302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:57:39.87218Z","caller":"traceutil/trace.go:171","msg":"trace[1969381609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"187.725393ms","start":"2026-02-13T06:57:39.68445Z","end":"2026-02-13T06:57:39.872175Z","steps":["trace[1969381609] 'agreement among raft nodes before linearized reading' (duration: 187.61868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:39.872274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.866726ms","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-13T06:57:39.872286Z","caller":"traceutil/trace.go:171","msg":"trace[930430015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"187.888307ms","start":"2026-02-13T06:57:39.684393Z","end":"2026-02-13T06:57:39.872282Z","steps":["trace[930430015] 'agreement among raft nodes before linearized reading' (duration: 187.850676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:39.872363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.875339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T06:57:39.872376Z","caller":"traceutil/trace.go:171","msg":"trace[557794323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"188.89713ms","start":"2026-02-13T06:57:39.683475Z","end":"2026-02-13T06:57:39.872372Z","steps":["trace[557794323] 'agreement among raft nodes before linearized reading' (duration: 188.858829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:39.872446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.991782ms","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-13T06:57:39.872458Z","caller":"traceutil/trace.go:171","msg":"trace[1302468763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:677; }","duration":"189.002782ms","start":"2026-02-13T06:57:39.683451Z","end":"2026-02-13T06:57:39.872454Z","steps":["trace[1302468763] 'agreement among raft nodes before linearized reading' (duration: 188.969581ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:42.21663Z","caller":"traceutil/trace.go:171","msg":"trace[1795802413] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"260.216025ms","start":"2026-02-13T06:57:41.956384Z","end":"2026-02-13T06:57:42.2166Z","steps":["trace[1795802413] 'read index received' (duration: 259.909177ms)","trace[1795802413] 'applied index is now lower than readState.Index' (duration: 305.718µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:42.216765Z","caller":"traceutil/trace.go:171","msg":"trace[1986957604] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"329.482223ms","start":"2026-02-13T06:57:41.887273Z","end":"2026-02-13T06:57:42.216755Z","steps":["trace[1986957604] 'process raft request' (duration: 329.145725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:42.216889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:41.887258Z","time spent":"329.538184ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:42.217361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.970842ms","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-13T06:57:42.21748Z","caller":"traceutil/trace.go:171","msg":"trace[1377886504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"261.123005ms","start":"2026-02-13T06:57:41.956345Z","end":"2026-02-13T06:57:42.217468Z","steps":["trace[1377886504] 'agreement among raft nodes before linearized reading' (duration: 260.87668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:42.217676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.222797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T06:57:42.21771Z","caller":"traceutil/trace.go:171","msg":"trace[1732756666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"261.266728ms","start":"2026-02-13T06:57:41.956436Z","end":"2026-02-13T06:57:42.217702Z","steps":["trace[1732756666] 'agreement among raft nodes before linearized reading' (duration: 261.183396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:42.218039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.501037ms","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-13T06:57:42.218076Z","caller":"traceutil/trace.go:171","msg":"trace[573351237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"258.552778ms","start":"2026-02-13T06:57:41.959515Z","end":"2026-02-13T06:57:42.218068Z","steps":["trace[573351237] 'agreement among raft nodes before linearized reading' (duration: 258.468226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:42.218647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.645752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:57:42.21888Z","caller":"traceutil/trace.go:171","msg":"trace[386029934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"259.910658ms","start":"2026-02-13T06:57:41.958917Z","end":"2026-02-13T06:57:42.218828Z","steps":["trace[386029934] 'agreement among raft nodes before linearized reading' (duration: 259.267614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:43.381664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.521494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:57:43.381792Z","caller":"traceutil/trace.go:171","msg":"trace[1351317627] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.156898201s","start":"2026-02-13T06:57:42.224871Z","end":"2026-02-13T06:57:43.381769Z","steps":["trace[1351317627] 'process raft request' (duration: 1.041162743s)","trace[1351317627] 'compare' (duration: 115.253258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:43.381872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:42.224815Z","time spent":"1.157017754s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:43.387119Z","caller":"traceutil/trace.go:171","msg":"trace[507250582] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.160683685s","start":"2026-02-13T06:57:42.226413Z","end":"2026-02-13T06:57:43.387097Z","steps":["trace[507250582] 'process raft request' (duration: 1.15594501s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:43.387243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:42.226403Z","time spent":"1.160779167s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:43.387533Z","caller":"traceutil/trace.go:171","msg":"trace[722673552] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.159736334s","start":"2026-02-13T06:57:42.227787Z","end":"2026-02-13T06:57:43.387523Z","steps":["trace[722673552] 'process raft request' (duration: 1.159249262s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:43.387577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:42.227771Z","time spent":"1.159780925s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:43.388059Z","caller":"traceutil/trace.go:171","msg":"trace[636409621] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:710; }","duration":"638.118146ms","start":"2026-02-13T06:57:42.749899Z","end":"2026-02-13T06:57:43.388018Z","steps":["trace[636409621] 'read index received' (duration: 516.161788ms)","trace[636409621] 'applied index is now lower than readState.Index' (duration: 121.953798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:43.388248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.32041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:57:43.388272Z","caller":"traceutil/trace.go:171","msg":"trace[718810360] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:690; }","duration":"638.385051ms","start":"2026-02-13T06:57:42.74988Z","end":"2026-02-13T06:57:43.388265Z","steps":["trace[718810360] 'agreement among raft nodes before linearized reading' (duration: 638.250688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:43.3883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:42.749823Z","time spent":"638.470183ms","remote":"127.0.0.1:36330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-13T06:57:43.388632Z","caller":"traceutil/trace.go:171","msg":"trace[1288683532] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.160378208s","start":"2026-02-13T06:57:42.228241Z","end":"2026-02-13T06:57:43.38862Z","steps":["trace[1288683532] 'process raft request' (duration: 1.159239942s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:43.388726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:42.228234Z","time spent":"1.16046333s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:44.641162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.751346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:57:44.64129Z","caller":"traceutil/trace.go:171","msg":"trace[562997531] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"221.819968ms","start":"2026-02-13T06:57:44.419458Z","end":"2026-02-13T06:57:44.641278Z","steps":["trace[562997531] 'process raft request' (duration: 221.788127ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:44.641491Z","caller":"traceutil/trace.go:171","msg":"trace[1736958396] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"397.077544ms","start":"2026-02-13T06:57:44.244399Z","end":"2026-02-13T06:57:44.641477Z","steps":["trace[1736958396] 'process raft request' (duration: 81.95297ms)","trace[1736958396] 'compare' (duration: 314.583802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:44.641521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:44.244384Z","time spent":"397.120685ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:44.641575Z","caller":"traceutil/trace.go:171","msg":"trace[127756634] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"353.66767ms","start":"2026-02-13T06:57:44.287903Z","end":"2026-02-13T06:57:44.641571Z","steps":["trace[127756634] 'read index received' (duration: 38.396223ms)","trace[127756634] 'applied index is now lower than readState.Index' (duration: 315.270897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:44.641721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.817313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T06:57:44.641735Z","caller":"traceutil/trace.go:171","msg":"trace[1003413733] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:701; }","duration":"353.861274ms","start":"2026-02-13T06:57:44.287869Z","end":"2026-02-13T06:57:44.64173Z","steps":["trace[1003413733] 'agreement among raft nodes before linearized reading' (duration: 353.792092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:44.641749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:44.287854Z","time spent":"353.892645ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-02-13T06:57:45.320699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.232563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:57:45.320891Z","caller":"traceutil/trace.go:171","msg":"trace[1372921683] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:747; }","duration":"403.758361ms","start":"2026-02-13T06:57:44.917116Z","end":"2026-02-13T06:57:45.320874Z","steps":["trace[1372921683] 'read index received' (duration: 276.293333ms)","trace[1372921683] 'applied index is now lower than readState.Index' (duration: 127.464328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:45.321016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.902754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3845"} {"level":"info","ts":"2026-02-13T06:57:45.321029Z","caller":"traceutil/trace.go:171","msg":"trace[623760603] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:723; }","duration":"403.946846ms","start":"2026-02-13T06:57:44.917077Z","end":"2026-02-13T06:57:45.321024Z","steps":["trace[623760603] 'agreement among raft nodes before linearized reading' (duration: 403.838723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.321048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:44.917068Z","time spent":"403.974506ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3869,"request content":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" "} {"level":"info","ts":"2026-02-13T06:57:45.321175Z","caller":"traceutil/trace.go:171","msg":"trace[2006121146] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"406.742887ms","start":"2026-02-13T06:57:44.914426Z","end":"2026-02-13T06:57:45.321168Z","steps":["trace[2006121146] 'process raft request' (duration: 278.971242ms)","trace[2006121146] 'compare' (duration: 127.020897ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:45.321211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:44.914399Z","time spent":"406.789958ms","remote":"127.0.0.1:36308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T06:57:45.321377Z","caller":"traceutil/trace.go:171","msg":"trace[1076824046] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"404.400796ms","start":"2026-02-13T06:57:44.91696Z","end":"2026-02-13T06:57:45.32136Z","steps":["trace[1076824046] 'process raft request' (duration: 403.838934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.321415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:44.916939Z","time spent":"404.450488ms","remote":"127.0.0.1:36480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:45.321538Z","caller":"traceutil/trace.go:171","msg":"trace[628209808] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"398.221639ms","start":"2026-02-13T06:57:44.923312Z","end":"2026-02-13T06:57:45.321533Z","steps":["trace[628209808] 'process raft request' (duration: 397.528724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.321562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:44.923299Z","time spent":"398.249619ms","remote":"127.0.0.1:36366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:57:45.821835Z","caller":"traceutil/trace.go:171","msg":"trace[1906516679] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"431.015827ms","start":"2026-02-13T06:57:45.390793Z","end":"2026-02-13T06:57:45.821809Z","steps":["trace[1906516679] 'read index received' (duration: 430.845903ms)","trace[1906516679] 'applied index is now lower than readState.Index' (duration: 169.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:45.821957Z","caller":"traceutil/trace.go:171","msg":"trace[1981233902] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"458.350374ms","start":"2026-02-13T06:57:45.363593Z","end":"2026-02-13T06:57:45.821943Z","steps":["trace[1981233902] 'process raft request' (duration: 458.080157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.822072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:45.363571Z","time spent":"458.423986ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:57:45.822159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.754035ms","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-13T06:57:45.822198Z","caller":"traceutil/trace.go:171","msg":"trace[291999278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"427.805376ms","start":"2026-02-13T06:57:45.394381Z","end":"2026-02-13T06:57:45.822187Z","steps":["trace[291999278] 'agreement among raft nodes before linearized reading' (duration: 427.663583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.822227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:45.394374Z","time spent":"427.844987ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T06:57:45.822414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.618511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-13T06:57:45.822419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.258293ms","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-13T06:57:45.822444Z","caller":"traceutil/trace.go:171","msg":"trace[661585772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"431.680802ms","start":"2026-02-13T06:57:45.390754Z","end":"2026-02-13T06:57:45.822435Z","steps":["trace[661585772] 'agreement among raft nodes before linearized reading' (duration: 431.59658ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:45.822449Z","caller":"traceutil/trace.go:171","msg":"trace[140238245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:724; }","duration":"404.322594ms","start":"2026-02-13T06:57:45.418118Z","end":"2026-02-13T06:57:45.822441Z","steps":["trace[140238245] 'agreement among raft nodes before linearized reading' (duration: 404.241102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.822474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:45.390738Z","time spent":"431.728343ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T06:57:45.82248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:45.418099Z","time spent":"404.373865ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T06:57:45.822547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.767517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:57:45.82257Z","caller":"traceutil/trace.go:171","msg":"trace[1012440031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"209.832368ms","start":"2026-02-13T06:57:45.612731Z","end":"2026-02-13T06:57:45.822563Z","steps":["trace[1012440031] 'agreement among raft nodes before linearized reading' (duration: 209.798278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.822649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.262604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:57:45.822677Z","caller":"traceutil/trace.go:171","msg":"trace[756416221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:724; }","duration":"427.301656ms","start":"2026-02-13T06:57:45.395367Z","end":"2026-02-13T06:57:45.822669Z","steps":["trace[756416221] 'agreement among raft nodes before linearized reading' (duration: 427.224874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:45.822705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:45.395356Z","time spent":"427.339426ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-13T06:57:52.067663Z","caller":"traceutil/trace.go:171","msg":"trace[565074071] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"151.950152ms","start":"2026-02-13T06:57:51.915688Z","end":"2026-02-13T06:57:52.067638Z","steps":["trace[565074071] 'read index received' (duration: 151.748888ms)","trace[565074071] 'applied index is now lower than readState.Index' (duration: 200.604µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:57:52.068387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.666338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-13T06:57:52.070436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.015689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:57:52.070552Z","caller":"traceutil/trace.go:171","msg":"trace[1704480897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"150.168763ms","start":"2026-02-13T06:57:51.92037Z","end":"2026-02-13T06:57:52.070539Z","steps":["trace[1704480897] 'agreement among raft nodes before linearized reading' (duration: 149.993229ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:52.0705Z","caller":"traceutil/trace.go:171","msg":"trace[905632000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"154.746873ms","start":"2026-02-13T06:57:51.915646Z","end":"2026-02-13T06:57:52.070392Z","steps":["trace[905632000] 'agreement among raft nodes before linearized reading' (duration: 152.220367ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:52.068686Z","caller":"traceutil/trace.go:171","msg":"trace[1640136885] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"186.977989ms","start":"2026-02-13T06:57:51.881691Z","end":"2026-02-13T06:57:52.068669Z","steps":["trace[1640136885] 'process raft request' (duration: 185.812503ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:52.222438Z","caller":"traceutil/trace.go:171","msg":"trace[1981594334] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"143.27395ms","start":"2026-02-13T06:57:52.079137Z","end":"2026-02-13T06:57:52.222411Z","steps":["trace[1981594334] 'process raft request' (duration: 142.580075ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:57:52.222602Z","caller":"traceutil/trace.go:171","msg":"trace[91282005] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"145.605091ms","start":"2026-02-13T06:57:52.076962Z","end":"2026-02-13T06:57:52.222567Z","steps":["trace[91282005] 'process raft request' (duration: 89.986367ms)","trace[91282005] 'compare' (duration: 54.599652ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:53.344059Z","caller":"traceutil/trace.go:171","msg":"trace[1653159163] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"134.975287ms","start":"2026-02-13T06:57:53.209064Z","end":"2026-02-13T06:57:53.344039Z","steps":["trace[1653159163] 'read index received' (duration: 134.374233ms)","trace[1653159163] 'applied index is now lower than readState.Index' (duration: 600.414µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:57:53.344413Z","caller":"traceutil/trace.go:171","msg":"trace[1069877024] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"636.057878ms","start":"2026-02-13T06:57:52.708327Z","end":"2026-02-13T06:57:53.344385Z","steps":["trace[1069877024] 'process raft request' (duration: 635.588678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:53.345549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.14504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:57:53.345604Z","caller":"traceutil/trace.go:171","msg":"trace[1816752834] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:750; }","duration":"136.574772ms","start":"2026-02-13T06:57:53.209019Z","end":"2026-02-13T06:57:53.345594Z","steps":["trace[1816752834] 'agreement among raft nodes before linearized reading' (duration: 135.150971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:57:53.345824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:57:52.708295Z","time spent":"637.287505ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:02.098845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751995256,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:58:02.438823Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012279988s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T06:58:02.439195Z","caller":"traceutil/trace.go:171","msg":"trace[1417374189] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"841.284384ms","start":"2026-02-13T06:58:01.597879Z","end":"2026-02-13T06:58:02.439164Z","steps":["trace[1417374189] 'read index received' (duration: 841.07362ms)","trace[1417374189] 'applied index is now lower than readState.Index' (duration: 210.034µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:02.43933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.425407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:02.43937Z","caller":"traceutil/trace.go:171","msg":"trace[1510181522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"841.54167ms","start":"2026-02-13T06:58:01.597815Z","end":"2026-02-13T06:58:02.439357Z","steps":["trace[1510181522] 'agreement among raft nodes before linearized reading' (duration: 841.446758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:02.439406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:01.597793Z","time spent":"841.603271ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:58:02.439531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.053956ms","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-13T06:58:02.439548Z","caller":"traceutil/trace.go:171","msg":"trace[564927228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"280.108507ms","start":"2026-02-13T06:58:02.159433Z","end":"2026-02-13T06:58:02.439541Z","steps":["trace[564927228] 'agreement among raft nodes before linearized reading' (duration: 280.013615ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:02.439615Z","caller":"traceutil/trace.go:171","msg":"trace[1048814395] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"1.013162188s","start":"2026-02-13T06:58:01.426442Z","end":"2026-02-13T06:58:02.439604Z","steps":["trace[1048814395] 'process raft request' (duration: 1.012587005s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:02.439672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.680654ms","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-13T06:58:02.439686Z","caller":"traceutil/trace.go:171","msg":"trace[1730418742] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"154.707345ms","start":"2026-02-13T06:58:02.284974Z","end":"2026-02-13T06:58:02.439682Z","steps":["trace[1730418742] 'agreement among raft nodes before linearized reading' (duration: 154.654483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:02.439698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:01.426419Z","time spent":"1.013220179s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:02.439789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.242379ms","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-13T06:58:02.439802Z","caller":"traceutil/trace.go:171","msg":"trace[928703798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"156.257899ms","start":"2026-02-13T06:58:02.283541Z","end":"2026-02-13T06:58:02.439798Z","steps":["trace[928703798] 'agreement among raft nodes before linearized reading' (duration: 156.216398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:02.439876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.913544ms","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-13T06:58:02.441603Z","caller":"traceutil/trace.go:171","msg":"trace[153758388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"245.671122ms","start":"2026-02-13T06:58:02.195924Z","end":"2026-02-13T06:58:02.441595Z","steps":["trace[153758388] 'agreement among raft nodes before linearized reading' (duration: 243.923714ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:02.560006Z","caller":"traceutil/trace.go:171","msg":"trace[494709243] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"112.508148ms","start":"2026-02-13T06:58:02.447457Z","end":"2026-02-13T06:58:02.559965Z","steps":["trace[494709243] 'process raft request' (duration: 86.464811ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:02.685603Z","caller":"traceutil/trace.go:171","msg":"trace[237197810] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"237.979012ms","start":"2026-02-13T06:58:02.447613Z","end":"2026-02-13T06:58:02.685592Z","steps":["trace[237197810] 'process raft request' (duration: 189.262691ms)","trace[237197810] 'compare' (duration: 48.479936ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:02.685538Z","caller":"traceutil/trace.go:171","msg":"trace[317008006] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"233.484322ms","start":"2026-02-13T06:58:02.452031Z","end":"2026-02-13T06:58:02.685515Z","steps":["trace[317008006] 'process raft request' (duration: 233.454822ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:02.685745Z","caller":"traceutil/trace.go:171","msg":"trace[1129600077] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"238.080334ms","start":"2026-02-13T06:58:02.44766Z","end":"2026-02-13T06:58:02.68574Z","steps":["trace[1129600077] 'process raft request' (duration: 237.803238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.148266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751995332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:58:13.648639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751995332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:58:13.775458Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294515223s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T06:58:13.775828Z","caller":"traceutil/trace.go:171","msg":"trace[659583031] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"1.12808969s","start":"2026-02-13T06:58:12.647716Z","end":"2026-02-13T06:58:13.775805Z","steps":["trace[659583031] 'read index received' (duration: 1.127844914s)","trace[659583031] 'applied index is now lower than readState.Index' (duration: 243.866µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:13.776034Z","caller":"traceutil/trace.go:171","msg":"trace[1880087121] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.295259679s","start":"2026-02-13T06:58:12.48075Z","end":"2026-02-13T06:58:13.77601Z","steps":["trace[1880087121] 'process raft request' (duration: 1.294872001s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.776128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:12.480731Z","time spent":"1.295325831s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:13.776336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128620061s","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-13T06:58:13.77636Z","caller":"traceutil/trace.go:171","msg":"trace[1620321136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"1.128692623s","start":"2026-02-13T06:58:12.647659Z","end":"2026-02-13T06:58:13.776351Z","steps":["trace[1620321136] 'agreement among raft nodes before linearized reading' (duration: 1.12859159s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.776377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:12.647639Z","time spent":"1.128734663s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T06:58:13.776507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043475111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:58:13.776524Z","caller":"traceutil/trace.go:171","msg":"trace[313527259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"1.043527352s","start":"2026-02-13T06:58:12.732992Z","end":"2026-02-13T06:58:13.776519Z","steps":["trace[313527259] 'agreement among raft nodes before linearized reading' (duration: 1.043459941s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.77654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:12.732956Z","time spent":"1.043580833s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T06:58:13.776746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.866789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:13.776766Z","caller":"traceutil/trace.go:171","msg":"trace[1358279859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"173.9317ms","start":"2026-02-13T06:58:13.602829Z","end":"2026-02-13T06:58:13.77676Z","steps":["trace[1358279859] 'agreement among raft nodes before linearized reading' (duration: 173.90007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.776891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.90535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:58:13.776909Z","caller":"traceutil/trace.go:171","msg":"trace[1544417568] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:806; }","duration":"291.991332ms","start":"2026-02-13T06:58:13.484912Z","end":"2026-02-13T06:58:13.776903Z","steps":["trace[1544417568] 'agreement among raft nodes before linearized reading' (duration: 291.943791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.777064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.506224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:58:13.777085Z","caller":"traceutil/trace.go:171","msg":"trace[1656043359] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:806; }","duration":"939.566875ms","start":"2026-02-13T06:58:12.83751Z","end":"2026-02-13T06:58:13.777076Z","steps":["trace[1656043359] 'agreement among raft nodes before linearized reading' (duration: 939.508494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.777101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:12.837488Z","time spent":"939.609306ms","remote":"127.0.0.1:36572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:58:13.77723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042466279s","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-13T06:58:13.777246Z","caller":"traceutil/trace.go:171","msg":"trace[1149750982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"1.04252853s","start":"2026-02-13T06:58:12.734712Z","end":"2026-02-13T06:58:13.777241Z","steps":["trace[1149750982] 'agreement among raft nodes before linearized reading' (duration: 1.042472069s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.777261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:12.734673Z","time spent":"1.042584562s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T06:58:13.777356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044164286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T06:58:13.791231Z","caller":"traceutil/trace.go:171","msg":"trace[1051545320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"1.045952016s","start":"2026-02-13T06:58:12.733176Z","end":"2026-02-13T06:58:13.779128Z","steps":["trace[1051545320] 'agreement among raft nodes before linearized reading' (duration: 1.044145777s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:13.791323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:12.73317Z","time spent":"1.058124107s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T06:58:15.246639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.236993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:58:15.247233Z","caller":"traceutil/trace.go:171","msg":"trace[705582370] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:838; }","duration":"957.508433ms","start":"2026-02-13T06:58:14.289704Z","end":"2026-02-13T06:58:15.247212Z","steps":["trace[705582370] 'read index received' (duration: 15.720518ms)","trace[705582370] 'applied index is now lower than readState.Index' (duration: 941.786845ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:15.247691Z","caller":"traceutil/trace.go:171","msg":"trace[339216225] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"1.460523149s","start":"2026-02-13T06:58:13.78715Z","end":"2026-02-13T06:58:15.247673Z","steps":["trace[339216225] 'process raft request' (duration: 518.15761ms)","trace[339216225] 'compare' (duration: 941.09368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:15.247949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:13.787139Z","time spent":"1.460741505s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:15.248305Z","caller":"traceutil/trace.go:171","msg":"trace[1039227549] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.454533586s","start":"2026-02-13T06:58:13.793729Z","end":"2026-02-13T06:58:15.248263Z","steps":["trace[1039227549] 'process raft request' (duration: 1.453448411s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:15.248459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:13.793703Z","time spent":"1.454672108s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:15.248524Z","caller":"traceutil/trace.go:171","msg":"trace[2053214022] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.456914518s","start":"2026-02-13T06:58:13.7916Z","end":"2026-02-13T06:58:15.248515Z","steps":["trace[2053214022] 'process raft request' (duration: 1.455481026s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:15.248566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:13.791592Z","time spent":"1.456951779s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:15.248752Z","caller":"traceutil/trace.go:171","msg":"trace[245210576] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.455185661s","start":"2026-02-13T06:58:13.793558Z","end":"2026-02-13T06:58:15.248744Z","steps":["trace[245210576] 'process raft request' (duration: 1.453590096s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:15.249109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:13.79355Z","time spent":"1.455522999s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:15.24932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.618021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T06:58:15.249556Z","caller":"traceutil/trace.go:171","msg":"trace[934306210] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:810; }","duration":"959.910197ms","start":"2026-02-13T06:58:14.28963Z","end":"2026-02-13T06:58:15.24954Z","steps":["trace[934306210] 'agreement among raft nodes before linearized reading' (duration: 958.67017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:15.24977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:14.289603Z","time spent":"960.152263ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-02-13T06:58:15.254488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.506468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:15.254706Z","caller":"traceutil/trace.go:171","msg":"trace[197577737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"590.757023ms","start":"2026-02-13T06:58:14.663935Z","end":"2026-02-13T06:58:15.254692Z","steps":["trace[197577737] 'agreement among raft nodes before linearized reading' (duration: 590.485177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:15.254917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:14.663917Z","time spent":"590.986998ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:58:15.479653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.376261ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-13T06:58:15.935772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.299656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:15.936518Z","caller":"traceutil/trace.go:171","msg":"trace[1255114181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"338.051412ms","start":"2026-02-13T06:58:15.598438Z","end":"2026-02-13T06:58:15.936489Z","steps":["trace[1255114181] 'range keys from in-memory index tree' (duration: 337.245084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:15.936866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:15.598402Z","time spent":"338.444432ms","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:58:15.936645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.255234ms","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-13T06:58:15.938004Z","caller":"traceutil/trace.go:171","msg":"trace[1086055083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"151.657656ms","start":"2026-02-13T06:58:15.786327Z","end":"2026-02-13T06:58:15.937985Z","steps":["trace[1086055083] 'range keys from in-memory index tree' (duration: 150.05694ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:32.296996Z","caller":"traceutil/trace.go:171","msg":"trace[681796841] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"180.15662ms","start":"2026-02-13T06:58:32.116804Z","end":"2026-02-13T06:58:32.29696Z","steps":["trace[681796841] 'process raft request' (duration: 180.000686ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:35.987647Z","caller":"traceutil/trace.go:171","msg":"trace[1367952848] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"408.891786ms","start":"2026-02-13T06:58:35.578721Z","end":"2026-02-13T06:58:35.987613Z","steps":["trace[1367952848] 'process raft request' (duration: 408.743303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:35.987806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:35.578702Z","time spent":"409.024128ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:35.988284Z","caller":"traceutil/trace.go:171","msg":"trace[879941628] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:959; }","duration":"396.314475ms","start":"2026-02-13T06:58:35.591961Z","end":"2026-02-13T06:58:35.988275Z","steps":["trace[879941628] 'read index received' (duration: 396.309115ms)","trace[879941628] 'applied index is now lower than readState.Index' (duration: 4.57µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:35.988401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.447829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:58:35.988425Z","caller":"traceutil/trace.go:171","msg":"trace[1126974411] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"396.484079ms","start":"2026-02-13T06:58:35.591932Z","end":"2026-02-13T06:58:35.988416Z","steps":["trace[1126974411] 'agreement among raft nodes before linearized reading' (duration: 396.383967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:35.988442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:35.591924Z","time spent":"396.51414ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-13T06:58:36.002093Z","caller":"traceutil/trace.go:171","msg":"trace[1926933499] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"175.225868ms","start":"2026-02-13T06:58:35.82685Z","end":"2026-02-13T06:58:36.002076Z","steps":["trace[1926933499] 'process raft request' (duration: 175.131556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:36.002559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.501633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:36.002581Z","caller":"traceutil/trace.go:171","msg":"trace[368159431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"406.559594ms","start":"2026-02-13T06:58:35.596015Z","end":"2026-02-13T06:58:36.002574Z","steps":["trace[368159431] 'agreement among raft nodes before linearized reading' (duration: 406.501503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:36.002596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:35.596007Z","time spent":"406.585394ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:58:36.004546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.421896ms","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-13T06:58:36.004566Z","caller":"traceutil/trace.go:171","msg":"trace[2057725169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"322.489557ms","start":"2026-02-13T06:58:35.682072Z","end":"2026-02-13T06:58:36.004561Z","steps":["trace[2057725169] 'agreement among raft nodes before linearized reading' (duration: 320.601815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:36.004581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:35.682046Z","time spent":"322.531149ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T06:58:36.004739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.423815ms","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-13T06:58:36.004754Z","caller":"traceutil/trace.go:171","msg":"trace[1461882639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"399.456745ms","start":"2026-02-13T06:58:35.605292Z","end":"2026-02-13T06:58:36.004749Z","steps":["trace[1461882639] 'agreement among raft nodes before linearized reading' (duration: 399.407874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:36.004767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:35.605285Z","time spent":"399.478507ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-13T06:58:38.17114Z","caller":"traceutil/trace.go:171","msg":"trace[1463544796] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"122.616481ms","start":"2026-02-13T06:58:38.048491Z","end":"2026-02-13T06:58:38.171108Z","steps":["trace[1463544796] 'process raft request' (duration: 35.750933ms)","trace[1463544796] 'compare' (duration: 86.159402ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:38.435107Z","caller":"traceutil/trace.go:171","msg":"trace[785678624] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:966; }","duration":"178.472342ms","start":"2026-02-13T06:58:38.256608Z","end":"2026-02-13T06:58:38.435081Z","steps":["trace[785678624] 'read index received' (duration: 91.595393ms)","trace[785678624] 'applied index is now lower than readState.Index' (duration: 86.875929ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:38.435259Z","caller":"traceutil/trace.go:171","msg":"trace[106505107] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"251.946533ms","start":"2026-02-13T06:58:38.183298Z","end":"2026-02-13T06:58:38.435244Z","steps":["trace[106505107] 'process raft request' (duration: 251.732548ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:38.435249Z","caller":"traceutil/trace.go:171","msg":"trace[1971373700] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"253.917827ms","start":"2026-02-13T06:58:38.18129Z","end":"2026-02-13T06:58:38.435208Z","steps":["trace[1971373700] 'process raft request' (duration: 166.903865ms)","trace[1971373700] 'compare' (duration: 86.678144ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:38.436233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.50745ms","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-13T06:58:38.436263Z","caller":"traceutil/trace.go:171","msg":"trace[987677431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"104.558851ms","start":"2026-02-13T06:58:38.331696Z","end":"2026-02-13T06:58:38.436255Z","steps":["trace[987677431] 'agreement among raft nodes before linearized reading' (duration: 104.398287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:38.438427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.194518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:58:38.438477Z","caller":"traceutil/trace.go:171","msg":"trace[1545652392] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:930; }","duration":"181.906648ms","start":"2026-02-13T06:58:38.256556Z","end":"2026-02-13T06:58:38.438463Z","steps":["trace[1545652392] 'agreement among raft nodes before linearized reading' (duration: 179.196068ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:38.77295Z","caller":"traceutil/trace.go:171","msg":"trace[712441601] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"175.833832ms","start":"2026-02-13T06:58:38.597088Z","end":"2026-02-13T06:58:38.772922Z","steps":["trace[712441601] 'read index received' (duration: 168.228974ms)","trace[712441601] 'applied index is now lower than readState.Index' (duration: 7.603428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:38.773106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.009335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:38.773131Z","caller":"traceutil/trace.go:171","msg":"trace[1189224704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"176.100638ms","start":"2026-02-13T06:58:38.597023Z","end":"2026-02-13T06:58:38.773123Z","steps":["trace[1189224704] 'agreement among raft nodes before linearized reading' (duration: 175.986656ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:38.77374Z","caller":"traceutil/trace.go:171","msg":"trace[2010472870] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"327.337566ms","start":"2026-02-13T06:58:38.44639Z","end":"2026-02-13T06:58:38.773728Z","steps":["trace[2010472870] 'process raft request' (duration: 318.99131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:38.773834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:38.446369Z","time spent":"327.412338ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:39.770669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.013581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T06:58:39.770726Z","caller":"traceutil/trace.go:171","msg":"trace[633116397] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:932; }","duration":"437.117632ms","start":"2026-02-13T06:58:39.333596Z","end":"2026-02-13T06:58:39.770713Z","steps":["trace[633116397] 'count revisions from in-memory index tree' (duration: 436.508429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:39.770745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:39.333578Z","time spent":"437.161305ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:58:39.770943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.661177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:39.770956Z","caller":"traceutil/trace.go:171","msg":"trace[1366521531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"151.698289ms","start":"2026-02-13T06:58:39.619254Z","end":"2026-02-13T06:58:39.770952Z","steps":["trace[1366521531] 'range keys from in-memory index tree' (duration: 151.611646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:40.255248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.61566ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T06:58:40.256608Z","caller":"traceutil/trace.go:171","msg":"trace[1980025317] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"206.616517ms","start":"2026-02-13T06:58:40.04996Z","end":"2026-02-13T06:58:40.256577Z","steps":["trace[1980025317] 'read index received' (duration: 40.651µs)","trace[1980025317] 'applied index is now lower than readState.Index' (duration: 206.574106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:40.260426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.500352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T06:58:40.264181Z","caller":"traceutil/trace.go:171","msg":"trace[1918277323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"214.321068ms","start":"2026-02-13T06:58:40.049832Z","end":"2026-02-13T06:58:40.264153Z","steps":["trace[1918277323] 'agreement among raft nodes before linearized reading' (duration: 210.431181ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:40.636684Z","caller":"traceutil/trace.go:171","msg":"trace[1327835573] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"351.418641ms","start":"2026-02-13T06:58:40.285246Z","end":"2026-02-13T06:58:40.636664Z","steps":["trace[1327835573] 'process raft request' (duration: 351.293509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:40.636945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:40.285228Z","time spent":"351.614245ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:41.439487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.627306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:58:41.439556Z","caller":"traceutil/trace.go:171","msg":"trace[1021518670] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"999.67944ms","start":"2026-02-13T06:58:40.439866Z","end":"2026-02-13T06:58:41.439545Z","steps":["trace[1021518670] 'read index received' (duration: 196.628786ms)","trace[1021518670] 'applied index is now lower than readState.Index' (duration: 803.049664ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:41.439936Z","caller":"traceutil/trace.go:171","msg":"trace[1826393736] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.154184819s","start":"2026-02-13T06:58:40.285734Z","end":"2026-02-13T06:58:41.439919Z","steps":["trace[1826393736] 'process raft request' (duration: 492.060122ms)","trace[1826393736] 'compare' (duration: 644.928425ms)","trace[1826393736] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 16.615059ms)"],"step_count":3} {"level":"warn","ts":"2026-02-13T06:58:41.439983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:40.285717Z","time spent":"1.154245581s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:41.445969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006074402s","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-13T06:58:41.446027Z","caller":"traceutil/trace.go:171","msg":"trace[2076780809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.006176774s","start":"2026-02-13T06:58:40.439835Z","end":"2026-02-13T06:58:41.446012Z","steps":["trace[2076780809] 'agreement among raft nodes before linearized reading' (duration: 1.002103143s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:41.446055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:40.439821Z","time spent":"1.006228246s","remote":"127.0.0.1:36474","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-13T06:58:41.446387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002964872s","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-13T06:58:41.446405Z","caller":"traceutil/trace.go:171","msg":"trace[130978018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.003005473s","start":"2026-02-13T06:58:40.443394Z","end":"2026-02-13T06:58:41.446399Z","steps":["trace[130978018] 'agreement among raft nodes before linearized reading' (duration: 1.002937782s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:41.446417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:40.443383Z","time spent":"1.003032014s","remote":"127.0.0.1:36474","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-13T06:58:41.446502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.971358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:41.446514Z","caller":"traceutil/trace.go:171","msg":"trace[1799832395] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:934; }","duration":"646.01426ms","start":"2026-02-13T06:58:40.800496Z","end":"2026-02-13T06:58:41.44651Z","steps":["trace[1799832395] 'agreement among raft nodes before linearized reading' (duration: 645.986919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:41.446526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:40.800482Z","time spent":"646.04085ms","remote":"127.0.0.1:36418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:58:41.446622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.627168ms","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-13T06:58:41.446635Z","caller":"traceutil/trace.go:171","msg":"trace[1181462958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"662.693429ms","start":"2026-02-13T06:58:40.783937Z","end":"2026-02-13T06:58:41.446631Z","steps":["trace[1181462958] 'agreement among raft nodes before linearized reading' (duration: 662.656599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:41.446649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:40.783916Z","time spent":"662.730311ms","remote":"127.0.0.1:36474","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-13T06:58:41.446703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.644922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:41.446714Z","caller":"traceutil/trace.go:171","msg":"trace[1616334188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"850.692432ms","start":"2026-02-13T06:58:40.596018Z","end":"2026-02-13T06:58:41.44671Z","steps":["trace[1616334188] 'agreement among raft nodes before linearized reading' (duration: 850.674772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:41.446724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:40.596003Z","time spent":"850.718903ms","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:58:49.540125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751995701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:58:50.041262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751995701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:58:50.213334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.548112002s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T06:58:50.214135Z","caller":"traceutil/trace.go:171","msg":"trace[1116449148] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.548990941s","start":"2026-02-13T06:58:48.665113Z","end":"2026-02-13T06:58:50.214104Z","steps":["trace[1116449148] 'process raft request' (duration: 1.548759886s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.214339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:48.665093Z","time spent":"1.549141505s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:50.26692Z","caller":"traceutil/trace.go:171","msg":"trace[1620455882] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.217836866s","start":"2026-02-13T06:58:49.049059Z","end":"2026-02-13T06:58:50.266896Z","steps":["trace[1620455882] 'process raft request' (duration: 1.217534889s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.267049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:49.049038Z","time spent":"1.217941818s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:50.515871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.078788ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T06:58:50.516033Z","caller":"traceutil/trace.go:171","msg":"trace[697591733] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:994; }","duration":"1.476952671s","start":"2026-02-13T06:58:49.039053Z","end":"2026-02-13T06:58:50.516005Z","steps":["trace[697591733] 'read index received' (duration: 1.174813482s)","trace[697591733] 'applied index is now lower than readState.Index' (duration: 302.137389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:50.517251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478175219s","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-13T06:58:50.517315Z","caller":"traceutil/trace.go:171","msg":"trace[443990463] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:956; }","duration":"1.478291772s","start":"2026-02-13T06:58:49.039008Z","end":"2026-02-13T06:58:50.517299Z","steps":["trace[443990463] 'agreement among raft nodes before linearized reading' (duration: 1.478163239s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.519203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:49.038989Z","time spent":"1.478369994s","remote":"127.0.0.1:36590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:58:50.519815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031633892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:58:50.519864Z","caller":"traceutil/trace.go:171","msg":"trace[526740378] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"1.031714064s","start":"2026-02-13T06:58:49.488138Z","end":"2026-02-13T06:58:50.519852Z","steps":["trace[526740378] 'agreement among raft nodes before linearized reading' (duration: 1.03153927s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.519903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:49.488122Z","time spent":"1.031773915s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T06:58:50.520249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.0931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:50.520315Z","caller":"traceutil/trace.go:171","msg":"trace[1502873477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"917.197901ms","start":"2026-02-13T06:58:49.603101Z","end":"2026-02-13T06:58:50.520298Z","steps":["trace[1502873477] 'agreement among raft nodes before linearized reading' (duration: 917.096039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.52037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:49.603079Z","time spent":"917.281044ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:58:50.521073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024348057s","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-13T06:58:50.521111Z","caller":"traceutil/trace.go:171","msg":"trace[1970128269] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:956; }","duration":"1.024387508s","start":"2026-02-13T06:58:49.496714Z","end":"2026-02-13T06:58:50.521102Z","steps":["trace[1970128269] 'agreement among raft nodes before linearized reading' (duration: 1.024288866s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.521141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:49.496664Z","time spent":"1.02446959s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T06:58:50.521275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.058714ms","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-13T06:58:50.521309Z","caller":"traceutil/trace.go:171","msg":"trace[528609165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"980.125925ms","start":"2026-02-13T06:58:49.541174Z","end":"2026-02-13T06:58:50.5213Z","steps":["trace[528609165] 'agreement among raft nodes before linearized reading' (duration: 980.034523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.521332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:49.541159Z","time spent":"980.167086ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T06:58:50.521451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.012505ms","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-13T06:58:50.521477Z","caller":"traceutil/trace.go:171","msg":"trace[376313165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"973.084006ms","start":"2026-02-13T06:58:49.548386Z","end":"2026-02-13T06:58:50.52147Z","steps":["trace[376313165] 'agreement among raft nodes before linearized reading' (duration: 972.192646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:50.521496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:49.548372Z","time spent":"973.119577ms","remote":"127.0.0.1:36474","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-13T06:58:51.339432Z","caller":"traceutil/trace.go:171","msg":"trace[1892491606] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"807.769454ms","start":"2026-02-13T06:58:50.531638Z","end":"2026-02-13T06:58:51.339408Z","steps":["trace[1892491606] 'process raft request' (duration: 807.639111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:51.339576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:50.531622Z","time spent":"807.878086ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:51.618994Z","caller":"traceutil/trace.go:171","msg":"trace[392545357] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.085882881s","start":"2026-02-13T06:58:50.533094Z","end":"2026-02-13T06:58:51.618977Z","steps":["trace[392545357] 'process raft request' (duration: 1.085743398s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:51.6191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:50.533084Z","time spent":"1.085960762s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:51.619225Z","caller":"traceutil/trace.go:171","msg":"trace[1904190098] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.085101682s","start":"2026-02-13T06:58:50.534119Z","end":"2026-02-13T06:58:51.61922Z","steps":["trace[1904190098] 'process raft request' (duration: 1.084835616s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:51.619251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:50.534112Z","time spent":"1.085124113s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:51.619395Z","caller":"traceutil/trace.go:171","msg":"trace[510779152] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:998; }","duration":"197.044951ms","start":"2026-02-13T06:58:51.422325Z","end":"2026-02-13T06:58:51.61937Z","steps":["trace[510779152] 'read index received' (duration: 196.466958ms)","trace[510779152] 'applied index is now lower than readState.Index' (duration: 576.743µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:51.619557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.243555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:51.619581Z","caller":"traceutil/trace.go:171","msg":"trace[1547120798] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:960; }","duration":"197.306496ms","start":"2026-02-13T06:58:51.422266Z","end":"2026-02-13T06:58:51.619572Z","steps":["trace[1547120798] 'agreement among raft nodes before linearized reading' (duration: 197.226824ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:51.619661Z","caller":"traceutil/trace.go:171","msg":"trace[2102921679] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"978.058189ms","start":"2026-02-13T06:58:50.641597Z","end":"2026-02-13T06:58:51.619655Z","steps":["trace[2102921679] 'process raft request' (duration: 977.593929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:51.619686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:50.641569Z","time spent":"978.10188ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:52.258223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.582654ms","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-13T06:58:52.258342Z","caller":"traceutil/trace.go:171","msg":"trace[587046750] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:960; }","duration":"588.718227ms","start":"2026-02-13T06:58:51.669606Z","end":"2026-02-13T06:58:52.258324Z","steps":["trace[587046750] 'range keys from in-memory index tree' (duration: 588.568993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:52.25874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.194899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:52.258766Z","caller":"traceutil/trace.go:171","msg":"trace[387207635] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:960; }","duration":"313.26202ms","start":"2026-02-13T06:58:51.945493Z","end":"2026-02-13T06:58:52.258755Z","steps":["trace[387207635] 'count revisions from in-memory index tree' (duration: 313.129418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:52.25879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:51.945472Z","time spent":"313.309752ms","remote":"127.0.0.1:36028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-13T06:58:52.772164Z","caller":"traceutil/trace.go:171","msg":"trace[1371311620] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"153.251339ms","start":"2026-02-13T06:58:52.618881Z","end":"2026-02-13T06:58:52.772133Z","steps":["trace[1371311620] 'read index received' (duration: 153.035554ms)","trace[1371311620] 'applied index is now lower than readState.Index' (duration: 214.605µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:52.772376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.473035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:58:52.772413Z","caller":"traceutil/trace.go:171","msg":"trace[286401916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"153.591307ms","start":"2026-02-13T06:58:52.61881Z","end":"2026-02-13T06:58:52.772401Z","steps":["trace[286401916] 'agreement among raft nodes before linearized reading' (duration: 153.458644ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:52.772842Z","caller":"traceutil/trace.go:171","msg":"trace[255006660] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"510.519595ms","start":"2026-02-13T06:58:52.262305Z","end":"2026-02-13T06:58:52.772825Z","steps":["trace[255006660] 'process raft request' (duration: 509.681617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:52.772959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:52.262279Z","time spent":"510.606748ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:53.811983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.131193ms","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-13T06:58:53.812087Z","caller":"traceutil/trace.go:171","msg":"trace[612672643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"186.301797ms","start":"2026-02-13T06:58:53.625762Z","end":"2026-02-13T06:58:53.812063Z","steps":["trace[612672643] 'range keys from in-memory index tree' (duration: 185.879128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:53.812257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.243471ms","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-13T06:58:53.812319Z","caller":"traceutil/trace.go:171","msg":"trace[503657581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"184.359974ms","start":"2026-02-13T06:58:53.627937Z","end":"2026-02-13T06:58:53.812297Z","steps":["trace[503657581] 'range keys from in-memory index tree' (duration: 183.697828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:53.81242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.876767ms","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-13T06:58:53.812432Z","caller":"traceutil/trace.go:171","msg":"trace[2133147208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"185.899547ms","start":"2026-02-13T06:58:53.626528Z","end":"2026-02-13T06:58:53.812428Z","steps":["trace[2133147208] 'range keys from in-memory index tree' (duration: 185.189481ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:53.957244Z","caller":"traceutil/trace.go:171","msg":"trace[1749771884] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"141.129525ms","start":"2026-02-13T06:58:53.816088Z","end":"2026-02-13T06:58:53.957217Z","steps":["trace[1749771884] 'process raft request' (duration: 140.967502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:54.670018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.667353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:58:54.670177Z","caller":"traceutil/trace.go:171","msg":"trace[228745616] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1004; }","duration":"373.571477ms","start":"2026-02-13T06:58:54.296589Z","end":"2026-02-13T06:58:54.670161Z","steps":["trace[228745616] 'read index received' (duration: 29.06µs)","trace[228745616] 'applied index is now lower than readState.Index' (duration: 373.541417ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:54.670385Z","caller":"traceutil/trace.go:171","msg":"trace[1505157498] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"853.252434ms","start":"2026-02-13T06:58:53.81712Z","end":"2026-02-13T06:58:54.670373Z","steps":["trace[1505157498] 'process raft request' (duration: 261.152311ms)","trace[1505157498] 'compare' (duration: 591.336476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:54.670448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:53.817114Z","time spent":"853.300195ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:54.670615Z","caller":"traceutil/trace.go:171","msg":"trace[1256889829] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"850.611834ms","start":"2026-02-13T06:58:53.819993Z","end":"2026-02-13T06:58:54.670605Z","steps":["trace[1256889829] 'process raft request' (duration: 850.114033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:54.670663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:53.819986Z","time spent":"850.650405ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:54.670867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.270502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T06:58:54.670894Z","caller":"traceutil/trace.go:171","msg":"trace[1943445703] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:965; }","duration":"374.300503ms","start":"2026-02-13T06:58:54.296585Z","end":"2026-02-13T06:58:54.670885Z","steps":["trace[1943445703] 'agreement among raft nodes before linearized reading' (duration: 374.194451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:54.67092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:54.296569Z","time spent":"374.345335ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"info","ts":"2026-02-13T06:58:54.926195Z","caller":"traceutil/trace.go:171","msg":"trace[2084326539] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"133.460551ms","start":"2026-02-13T06:58:54.792714Z","end":"2026-02-13T06:58:54.926175Z","steps":["trace[2084326539] 'read index received' (duration: 44.750303ms)","trace[2084326539] 'applied index is now lower than readState.Index' (duration: 88.709038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:58:54.926606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.887161ms","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-13T06:58:54.926636Z","caller":"traceutil/trace.go:171","msg":"trace[1765642214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"133.960733ms","start":"2026-02-13T06:58:54.792668Z","end":"2026-02-13T06:58:54.926628Z","steps":["trace[1765642214] 'agreement among raft nodes before linearized reading' (duration: 133.81337ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:58:55.388324Z","caller":"traceutil/trace.go:171","msg":"trace[869296412] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"457.805333ms","start":"2026-02-13T06:58:54.930502Z","end":"2026-02-13T06:58:55.388307Z","steps":["trace[869296412] 'process raft request' (duration: 457.69944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:55.388446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:54.930484Z","time spent":"457.906315ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:55.427297Z","caller":"traceutil/trace.go:171","msg":"trace[1656538721] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"462.51576ms","start":"2026-02-13T06:58:54.964764Z","end":"2026-02-13T06:58:55.42728Z","steps":["trace[1656538721] 'process raft request' (duration: 462.411408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:55.427409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:54.964749Z","time spent":"462.602392ms","remote":"127.0.0.1:36266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:55.677355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.030477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:58:55.677424Z","caller":"traceutil/trace.go:171","msg":"trace[2052297384] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:967; }","duration":"142.104639ms","start":"2026-02-13T06:58:55.535307Z","end":"2026-02-13T06:58:55.677412Z","steps":["trace[2052297384] 'range keys from in-memory index tree' (duration: 141.938165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:57.892154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751995748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:58:58.100445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.392341877s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T06:58:58.100895Z","caller":"traceutil/trace.go:171","msg":"trace[180461530] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.392835997s","start":"2026-02-13T06:58:56.70803Z","end":"2026-02-13T06:58:58.100866Z","steps":["trace[180461530] 'process raft request' (duration: 1.392635603s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:58.101097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:56.708012Z","time spent":"1.392990311s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:59.610598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010173773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-13T06:58:59.610694Z","caller":"traceutil/trace.go:171","msg":"trace[542325092] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.010331116s","start":"2026-02-13T06:58:57.600346Z","end":"2026-02-13T06:58:59.610677Z","steps":["trace[542325092] 'agreement among raft nodes before linearized reading' (duration: 2.010212294s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:59.610734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:57.60033Z","time spent":"2.010396487s","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/13 06:58:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-13T06:58:59.684037Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.583180066s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T06:58:59.684476Z","caller":"traceutil/trace.go:171","msg":"trace[1504758134] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"2.293006054s","start":"2026-02-13T06:58:57.391449Z","end":"2026-02-13T06:58:59.684455Z","steps":["trace[1504758134] 'read index received' (duration: 713.421179ms)","trace[1504758134] 'applied index is now lower than readState.Index' (duration: 1.579584185s)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:58:59.684787Z","caller":"traceutil/trace.go:171","msg":"trace[1967116370] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"2.976341611s","start":"2026-02-13T06:58:56.708437Z","end":"2026-02-13T06:58:59.684779Z","steps":["trace[1967116370] 'process raft request' (duration: 2.975874591s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:59.684862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:56.708419Z","time spent":"2.976386323s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:58:59.684989Z","caller":"traceutil/trace.go:171","msg":"trace[1265436368] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"2.91088655s","start":"2026-02-13T06:58:56.774097Z","end":"2026-02-13T06:58:59.684983Z","steps":["trace[1265436368] 'process raft request' (duration: 2.910325827s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:59.685021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:56.774086Z","time spent":"2.91091667s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:58:59.685161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.293710539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T06:58:59.68518Z","caller":"traceutil/trace.go:171","msg":"trace[199960595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:972; }","duration":"2.29375525s","start":"2026-02-13T06:58:57.391419Z","end":"2026-02-13T06:58:59.685174Z","steps":["trace[199960595] 'agreement among raft nodes before linearized reading' (duration: 2.293676398s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:58:59.685196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:57.391381Z","time spent":"2.293811221s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T06:59:00.184881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093865751995753,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T06:59:00.909495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.143013ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T06:59:00.909719Z","caller":"traceutil/trace.go:171","msg":"trace[996531692] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1014; }","duration":"1.225201613s","start":"2026-02-13T06:58:59.684501Z","end":"2026-02-13T06:59:00.909702Z","steps":["trace[996531692] 'read index received' (duration: 727.850485ms)","trace[996531692] 'applied index is now lower than readState.Index' (duration: 497.350258ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:59:00.909896Z","caller":"traceutil/trace.go:171","msg":"trace[657217488] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"1.220234661s","start":"2026-02-13T06:58:59.68965Z","end":"2026-02-13T06:59:00.909885Z","steps":["trace[657217488] 'process raft request' (duration: 1.219942663s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:00.909982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:59.689633Z","time spent":"1.220289653s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:59:00.910209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.751698527s","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-13T06:59:00.910237Z","caller":"traceutil/trace.go:171","msg":"trace[1296557534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"2.751749189s","start":"2026-02-13T06:58:58.158479Z","end":"2026-02-13T06:59:00.910228Z","steps":["trace[1296557534] 'agreement among raft nodes before linearized reading' (duration: 2.751646676s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:00.910267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:58.158462Z","time spent":"2.751798729s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T06:59:00.910473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.185488136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T06:59:00.9105Z","caller":"traceutil/trace.go:171","msg":"trace[1839293099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"3.185559968s","start":"2026-02-13T06:58:57.724932Z","end":"2026-02-13T06:59:00.910491Z","steps":["trace[1839293099] 'agreement among raft nodes before linearized reading' (duration: 3.185442175s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:00.910524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:57.724908Z","time spent":"3.185610918s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-13T06:59:01.000859Z","caller":"traceutil/trace.go:171","msg":"trace[1552977207] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"373.74399ms","start":"2026-02-13T06:59:00.627095Z","end":"2026-02-13T06:59:01.000839Z","steps":["trace[1552977207] 'process raft request' (duration: 373.614807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.001776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:00.62708Z","time spent":"374.522937ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:59:01.001904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.803689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:59:01.001947Z","caller":"traceutil/trace.go:171","msg":"trace[1550902757] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:974; }","duration":"319.87669ms","start":"2026-02-13T06:59:00.682057Z","end":"2026-02-13T06:59:01.001934Z","steps":["trace[1550902757] 'agreement among raft nodes before linearized reading' (duration: 319.822929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.00198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:00.682042Z","time spent":"319.930191ms","remote":"127.0.0.1:36620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:59:01.002202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.009078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:59:01.002236Z","caller":"traceutil/trace.go:171","msg":"trace[86969336] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:974; }","duration":"876.06984ms","start":"2026-02-13T06:59:00.126158Z","end":"2026-02-13T06:59:01.002227Z","steps":["trace[86969336] 'agreement among raft nodes before linearized reading' (duration: 876.016739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.002264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:00.12615Z","time spent":"876.107061ms","remote":"127.0.0.1:36046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:59:01.002476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.37277ms","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-13T06:59:01.002509Z","caller":"traceutil/trace.go:171","msg":"trace[280267076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"892.436111ms","start":"2026-02-13T06:59:00.110065Z","end":"2026-02-13T06:59:01.002501Z","steps":["trace[280267076] 'agreement among raft nodes before linearized reading' (duration: 892.324749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.002535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:00.110049Z","time spent":"892.480043ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T06:59:01.002621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.003575ms","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-13T06:59:01.002654Z","caller":"traceutil/trace.go:171","msg":"trace[230962795] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:974; }","duration":"984.066195ms","start":"2026-02-13T06:59:00.018576Z","end":"2026-02-13T06:59:01.002642Z","steps":["trace[230962795] 'agreement among raft nodes before linearized reading' (duration: 984.016254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.002798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:00.01855Z","time spent":"984.233889ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-13T06:59:01.001849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274600031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:59:01.003277Z","caller":"traceutil/trace.go:171","msg":"trace[1076554620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"1.276062725s","start":"2026-02-13T06:58:59.727203Z","end":"2026-02-13T06:59:01.003266Z","steps":["trace[1076554620] 'agreement among raft nodes before linearized reading' (duration: 1.274581921s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.003321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:58:59.727186Z","time spent":"1.276125486s","remote":"127.0.0.1:36240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T06:59:01.338159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.010534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:59:01.338356Z","caller":"traceutil/trace.go:171","msg":"trace[332601368] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1017; }","duration":"325.16579ms","start":"2026-02-13T06:59:01.013173Z","end":"2026-02-13T06:59:01.338338Z","steps":["trace[332601368] 'read index received' (duration: 87.832409ms)","trace[332601368] 'applied index is now lower than readState.Index' (duration: 237.332451ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T06:59:01.338546Z","caller":"traceutil/trace.go:171","msg":"trace[838127447] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"416.021368ms","start":"2026-02-13T06:59:00.922513Z","end":"2026-02-13T06:59:01.338534Z","steps":["trace[838127447] 'process raft request' (duration: 178.552102ms)","trace[838127447] 'compare' (duration: 236.161245ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:01.339824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:00.922498Z","time spent":"416.075599ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:59:01.340007Z","caller":"traceutil/trace.go:171","msg":"trace[1811146129] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"416.868906ms","start":"2026-02-13T06:59:00.923127Z","end":"2026-02-13T06:59:01.339996Z","steps":["trace[1811146129] 'process raft request' (duration: 415.124966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.340062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:00.923121Z","time spent":"416.920447ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:59:01.340199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.022383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:59:01.340223Z","caller":"traceutil/trace.go:171","msg":"trace[1494323539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:976; }","duration":"327.060643ms","start":"2026-02-13T06:59:01.013153Z","end":"2026-02-13T06:59:01.340213Z","steps":["trace[1494323539] 'agreement among raft nodes before linearized reading' (duration: 327.008982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.340241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:01.013143Z","time spent":"327.094505ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T06:59:01.386326Z","caller":"traceutil/trace.go:171","msg":"trace[1571462540] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"370.937968ms","start":"2026-02-13T06:59:01.015348Z","end":"2026-02-13T06:59:01.386286Z","steps":["trace[1571462540] 'process raft request' (duration: 370.736862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.386603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.490337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-13T06:59:01.386654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:01.015339Z","time spent":"371.212634ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:59:01.387673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.081572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893bc0bdbe7c01a\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-13T06:59:01.387726Z","caller":"traceutil/trace.go:171","msg":"trace[243333532] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1893bc0bdbe7c01a; range_end:; response_count:1; response_revision:977; }","duration":"349.159744ms","start":"2026-02-13T06:59:01.038553Z","end":"2026-02-13T06:59:01.387713Z","steps":["trace[243333532] 'agreement among raft nodes before linearized reading' (duration: 348.320606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:01.387808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:01.038543Z","time spent":"349.253546ms","remote":"127.0.0.1:36308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1893bc0bdbe7c01a\" "} {"level":"info","ts":"2026-02-13T06:59:01.386715Z","caller":"traceutil/trace.go:171","msg":"trace[2121442047] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:977; }","duration":"256.685011ms","start":"2026-02-13T06:59:01.130006Z","end":"2026-02-13T06:59:01.386691Z","steps":["trace[2121442047] 'agreement among raft nodes before linearized reading' (duration: 256.403884ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:01.887938Z","caller":"traceutil/trace.go:171","msg":"trace[1297218971] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"197.941421ms","start":"2026-02-13T06:59:01.689966Z","end":"2026-02-13T06:59:01.887907Z","steps":["trace[1297218971] 'read index received' (duration: 197.601714ms)","trace[1297218971] 'applied index is now lower than readState.Index' (duration: 338.327µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:01.888284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.278119ms","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-13T06:59:01.888324Z","caller":"traceutil/trace.go:171","msg":"trace[1478898876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"198.403831ms","start":"2026-02-13T06:59:01.689907Z","end":"2026-02-13T06:59:01.888311Z","steps":["trace[1478898876] 'agreement among raft nodes before linearized reading' (duration: 198.156556ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:01.888663Z","caller":"traceutil/trace.go:171","msg":"trace[1155157847] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"284.342557ms","start":"2026-02-13T06:59:01.604304Z","end":"2026-02-13T06:59:01.888647Z","steps":["trace[1155157847] 'process raft request' (duration: 283.348275ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:02.452279Z","caller":"traceutil/trace.go:171","msg":"trace[1994305133] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"555.832221ms","start":"2026-02-13T06:59:01.896415Z","end":"2026-02-13T06:59:02.452247Z","steps":["trace[1994305133] 'process raft request' (duration: 492.075858ms)","trace[1994305133] 'compare' (duration: 63.551739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:02.452448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:01.896389Z","time spent":"555.966905ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:59:06.842297Z","caller":"traceutil/trace.go:171","msg":"trace[1766037919] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"246.47244ms","start":"2026-02-13T06:59:06.595806Z","end":"2026-02-13T06:59:06.842278Z","steps":["trace[1766037919] 'read index received' (duration: 246.353287ms)","trace[1766037919] 'applied index is now lower than readState.Index' (duration: 118.402µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:06.842423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.599932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:59:06.842441Z","caller":"traceutil/trace.go:171","msg":"trace[1876209967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"246.662773ms","start":"2026-02-13T06:59:06.595772Z","end":"2026-02-13T06:59:06.842435Z","steps":["trace[1876209967] 'agreement among raft nodes before linearized reading' (duration: 246.594061ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:06.842703Z","caller":"traceutil/trace.go:171","msg":"trace[2047150046] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"367.086999ms","start":"2026-02-13T06:59:06.475611Z","end":"2026-02-13T06:59:06.842698Z","steps":["trace[2047150046] 'process raft request' (duration: 366.537597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:06.842777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:06.475599Z","time spent":"367.11618ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T06:59:07.210608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.072427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T06:59:07.210672Z","caller":"traceutil/trace.go:171","msg":"trace[353381115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"271.185088ms","start":"2026-02-13T06:59:06.939464Z","end":"2026-02-13T06:59:07.210649Z","steps":["trace[353381115] 'range keys from in-memory index tree' (duration: 270.909172ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:09.024083Z","caller":"traceutil/trace.go:171","msg":"trace[183429638] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"174.581852ms","start":"2026-02-13T06:59:08.849479Z","end":"2026-02-13T06:59:09.024061Z","steps":["trace[183429638] 'process raft request' (duration: 174.462079ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:09.34522Z","caller":"traceutil/trace.go:171","msg":"trace[1808208727] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"119.351792ms","start":"2026-02-13T06:59:09.225849Z","end":"2026-02-13T06:59:09.345201Z","steps":["trace[1808208727] 'process raft request' (duration: 119.243919ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:09.602868Z","caller":"traceutil/trace.go:171","msg":"trace[1329619497] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"165.808694ms","start":"2026-02-13T06:59:09.437044Z","end":"2026-02-13T06:59:09.602853Z","steps":["trace[1329619497] 'read index received' (duration: 165.679731ms)","trace[1329619497] 'applied index is now lower than readState.Index' (duration: 128.223µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:09.603082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.024839ms","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-13T06:59:09.603104Z","caller":"traceutil/trace.go:171","msg":"trace[1017297859] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"166.08672ms","start":"2026-02-13T06:59:09.43701Z","end":"2026-02-13T06:59:09.603096Z","steps":["trace[1017297859] 'agreement among raft nodes before linearized reading' (duration: 165.976948ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:09.603252Z","caller":"traceutil/trace.go:171","msg":"trace[1543039875] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"206.757421ms","start":"2026-02-13T06:59:09.396489Z","end":"2026-02-13T06:59:09.603247Z","steps":["trace[1543039875] 'process raft request' (duration: 206.28399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:09.603628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.420184ms","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-13T06:59:09.603648Z","caller":"traceutil/trace.go:171","msg":"trace[1517120289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"165.449866ms","start":"2026-02-13T06:59:09.438193Z","end":"2026-02-13T06:59:09.603643Z","steps":["trace[1517120289] 'agreement among raft nodes before linearized reading' (duration: 165.222501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:11.070759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.467468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T06:59:11.070862Z","caller":"traceutil/trace.go:171","msg":"trace[2072729285] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.462166267s","start":"2026-02-13T06:59:09.60868Z","end":"2026-02-13T06:59:11.070847Z","steps":["trace[2072729285] 'process raft request' (duration: 851.542175ms)","trace[2072729285] 'compare' (duration: 610.038258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:11.070907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:09.608668Z","time spent":"1.462218428s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:59:11.077828Z","caller":"traceutil/trace.go:171","msg":"trace[593316038] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.327571301s","start":"2026-02-13T06:59:09.750237Z","end":"2026-02-13T06:59:11.077808Z","steps":["trace[593316038] 'process raft request' (duration: 1.326361424s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:11.077975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:09.750219Z","time spent":"1.327679743s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:59:11.078221Z","caller":"traceutil/trace.go:171","msg":"trace[306941358] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.289259103s","start":"2026-02-13T06:59:09.788953Z","end":"2026-02-13T06:59:11.078212Z","steps":["trace[306941358] 'process raft request' (duration: 1.28865588s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:11.078265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:09.788937Z","time spent":"1.289307034s","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:59:11.083122Z","caller":"traceutil/trace.go:171","msg":"trace[256761084] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1063; }","duration":"486.360769ms","start":"2026-02-13T06:59:10.596749Z","end":"2026-02-13T06:59:11.083109Z","steps":["trace[256761084] 'read index received' (duration: 479.793011ms)","trace[256761084] 'applied index is now lower than readState.Index' (duration: 6.567048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:11.083367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.618226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T06:59:11.083397Z","caller":"traceutil/trace.go:171","msg":"trace[1999905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"486.681826ms","start":"2026-02-13T06:59:10.596708Z","end":"2026-02-13T06:59:11.08339Z","steps":["trace[1999905] 'agreement among raft nodes before linearized reading' (duration: 486.632995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:11.083427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:10.596695Z","time spent":"486.727718ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T06:59:21.334225Z","caller":"traceutil/trace.go:171","msg":"trace[147677002] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"723.506766ms","start":"2026-02-13T06:59:20.610671Z","end":"2026-02-13T06:59:21.334178Z","steps":["trace[147677002] 'process raft request' (duration: 723.377673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T06:59:21.334765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T06:59:20.610651Z","time spent":"723.643419ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5592,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T06:59:21.335528Z","caller":"traceutil/trace.go:171","msg":"trace[1984376111] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1117; }","duration":"128.522889ms","start":"2026-02-13T06:59:21.206995Z","end":"2026-02-13T06:59:21.335518Z","steps":["trace[1984376111] 'read index received' (duration: 128.519689ms)","trace[1984376111] 'applied index is now lower than readState.Index' (duration: 2.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:21.343324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.316706ms","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-13T06:59:21.343375Z","caller":"traceutil/trace.go:171","msg":"trace[624945010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"136.412348ms","start":"2026-02-13T06:59:21.206951Z","end":"2026-02-13T06:59:21.343364Z","steps":["trace[624945010] 'agreement among raft nodes before linearized reading' (duration: 128.615351ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:24.418351Z","caller":"traceutil/trace.go:171","msg":"trace[430443150] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"119.660318ms","start":"2026-02-13T06:59:24.298668Z","end":"2026-02-13T06:59:24.418328Z","steps":["trace[430443150] 'read index received' (duration: 119.427083ms)","trace[430443150] 'applied index is now lower than readState.Index' (duration: 232.535µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T06:59:24.418562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.872332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T06:59:24.418593Z","caller":"traceutil/trace.go:171","msg":"trace[240840300] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1096; }","duration":"119.980216ms","start":"2026-02-13T06:59:24.298603Z","end":"2026-02-13T06:59:24.418583Z","steps":["trace[240840300] 'agreement among raft nodes before linearized reading' (duration: 119.826002ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T06:59:24.419004Z","caller":"traceutil/trace.go:171","msg":"trace[10010936] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"129.677537ms","start":"2026-02-13T06:59:24.289315Z","end":"2026-02-13T06:59:24.418993Z","steps":["trace[10010936] 'process raft request' (duration: 128.856177ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:03.804549Z","caller":"traceutil/trace.go:171","msg":"trace[800918961] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"140.201962ms","start":"2026-02-13T07:00:03.66432Z","end":"2026-02-13T07:00:03.804521Z","steps":["trace[800918961] 'process raft request' (duration: 140.066779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:00:03.806624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.864471ms","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-13T07:00:03.806676Z","caller":"traceutil/trace.go:171","msg":"trace[1561289943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"141.942812ms","start":"2026-02-13T07:00:03.664721Z","end":"2026-02-13T07:00:03.806664Z","steps":["trace[1561289943] 'agreement among raft nodes before linearized reading' (duration: 141.776069ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:03.807161Z","caller":"traceutil/trace.go:171","msg":"trace[207940376] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1314; }","duration":"140.417878ms","start":"2026-02-13T07:00:03.664743Z","end":"2026-02-13T07:00:03.805161Z","steps":["trace[207940376] 'read index received' (duration: 140.412447ms)","trace[207940376] 'applied index is now lower than readState.Index' (duration: 4.131µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:00:03.808035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.776388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-13T07:00:03.808086Z","caller":"traceutil/trace.go:171","msg":"trace[82643739] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1258; }","duration":"116.87316ms","start":"2026-02-13T07:00:03.691199Z","end":"2026-02-13T07:00:03.808072Z","steps":["trace[82643739] 'agreement among raft nodes before linearized reading' (duration: 116.759547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:00:03.809205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.138172ms","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-13T07:00:03.809284Z","caller":"traceutil/trace.go:171","msg":"trace[581705798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"126.269895ms","start":"2026-02-13T07:00:03.682995Z","end":"2026-02-13T07:00:03.809265Z","steps":["trace[581705798] 'agreement among raft nodes before linearized reading' (duration: 125.973147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:00:03.81198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.447948ms","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-13T07:00:03.812017Z","caller":"traceutil/trace.go:171","msg":"trace[31002243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"129.526969ms","start":"2026-02-13T07:00:03.682481Z","end":"2026-02-13T07:00:03.812008Z","steps":["trace[31002243] 'agreement among raft nodes before linearized reading' (duration: 125.447586ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:03.927354Z","caller":"traceutil/trace.go:171","msg":"trace[946349289] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"111.853395ms","start":"2026-02-13T07:00:03.815464Z","end":"2026-02-13T07:00:03.927317Z","steps":["trace[946349289] 'process raft request' (duration: 35.114911ms)","trace[946349289] 'compare' (duration: 76.544569ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:00:03.92758Z","caller":"traceutil/trace.go:171","msg":"trace[1408549252] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"111.025436ms","start":"2026-02-13T07:00:03.816532Z","end":"2026-02-13T07:00:03.927557Z","steps":["trace[1408549252] 'process raft request' (duration: 110.75358ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:03.927839Z","caller":"traceutil/trace.go:171","msg":"trace[1156474216] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"109.087191ms","start":"2026-02-13T07:00:03.818746Z","end":"2026-02-13T07:00:03.927833Z","steps":["trace[1156474216] 'process raft request' (duration: 108.745124ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:03.92826Z","caller":"traceutil/trace.go:171","msg":"trace[30776951] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"108.199612ms","start":"2026-02-13T07:00:03.820051Z","end":"2026-02-13T07:00:03.928251Z","steps":["trace[30776951] 'process raft request' (duration: 107.725041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:00:04.280338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.620219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T07:00:04.280427Z","caller":"traceutil/trace.go:171","msg":"trace[2019953491] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"151.556292ms","start":"2026-02-13T07:00:04.128857Z","end":"2026-02-13T07:00:04.280413Z","steps":["trace[2019953491] 'read index received' (duration: 32.971µs)","trace[2019953491] 'applied index is now lower than readState.Index' (duration: 151.522161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:00:04.280547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.689444ms","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:532"} {"level":"info","ts":"2026-02-13T07:00:04.280561Z","caller":"traceutil/trace.go:171","msg":"trace[742174129] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1264; }","duration":"151.746586ms","start":"2026-02-13T07:00:04.128809Z","end":"2026-02-13T07:00:04.280555Z","steps":["trace[742174129] 'agreement among raft nodes before linearized reading' (duration: 151.637923ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:04.2808Z","caller":"traceutil/trace.go:171","msg":"trace[1088768326] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"341.713275ms","start":"2026-02-13T07:00:03.939076Z","end":"2026-02-13T07:00:04.280789Z","steps":["trace[1088768326] 'process raft request' (duration: 166.562824ms)","trace[1088768326] 'compare' (duration: 174.302052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:00:04.280837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:00:03.939067Z","time spent":"341.750356ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T07:00:06.102799Z","caller":"traceutil/trace.go:171","msg":"trace[1889164928] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"161.705043ms","start":"2026-02-13T07:00:05.941062Z","end":"2026-02-13T07:00:06.102767Z","steps":["trace[1889164928] 'process raft request' (duration: 133.406607ms)","trace[1889164928] 'compare' (duration: 28.098871ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:00:06.102934Z","caller":"traceutil/trace.go:171","msg":"trace[812677110] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1325; }","duration":"101.711483ms","start":"2026-02-13T07:00:06.001195Z","end":"2026-02-13T07:00:06.102907Z","steps":["trace[812677110] 'read index received' (duration: 73.250393ms)","trace[812677110] 'applied index is now lower than readState.Index' (duration: 28.46019ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:00:06.103472Z","caller":"traceutil/trace.go:171","msg":"trace[1646022093] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"160.170109ms","start":"2026-02-13T07:00:05.943289Z","end":"2026-02-13T07:00:06.103459Z","steps":["trace[1646022093] 'process raft request' (duration: 159.433052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:00:06.103648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.44804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-13T07:00:06.10368Z","caller":"traceutil/trace.go:171","msg":"trace[1635528046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"102.532042ms","start":"2026-02-13T07:00:06.001138Z","end":"2026-02-13T07:00:06.10367Z","steps":["trace[1635528046] 'agreement among raft nodes before linearized reading' (duration: 101.854486ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:36.249068Z","caller":"traceutil/trace.go:171","msg":"trace[1619845794] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"147.336907ms","start":"2026-02-13T07:00:36.101705Z","end":"2026-02-13T07:00:36.249042Z","steps":["trace[1619845794] 'process raft request' (duration: 147.012769ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:00:40.585028Z","caller":"traceutil/trace.go:171","msg":"trace[1389380528] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"147.770664ms","start":"2026-02-13T07:00:40.437225Z","end":"2026-02-13T07:00:40.584996Z","steps":["trace[1389380528] 'process raft request' (duration: 95.189004ms)","trace[1389380528] 'compare' (duration: 52.156071ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:00:46.893973Z","caller":"traceutil/trace.go:171","msg":"trace[1700831735] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1500; }","duration":"143.139529ms","start":"2026-02-13T07:00:46.750809Z","end":"2026-02-13T07:00:46.893948Z","steps":["trace[1700831735] 'read index received' (duration: 101.616622ms)","trace[1700831735] 'applied index is now lower than readState.Index' (duration: 41.521867ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:00:46.894456Z","caller":"traceutil/trace.go:171","msg":"trace[1376308282] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"168.15698ms","start":"2026-02-13T07:00:46.726152Z","end":"2026-02-13T07:00:46.894309Z","steps":["trace[1376308282] 'process raft request' (duration: 126.357716ms)","trace[1376308282] 'compare' (duration: 41.279252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:00:46.896232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.377941ms","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-13T07:00:46.896309Z","caller":"traceutil/trace.go:171","msg":"trace[1594936308] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1436; }","duration":"145.532674ms","start":"2026-02-13T07:00:46.750757Z","end":"2026-02-13T07:00:46.89629Z","steps":["trace[1594936308] 'agreement among raft nodes before linearized reading' (duration: 143.345104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:01:27.087699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.506231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-13T07:01:27.088304Z","caller":"traceutil/trace.go:171","msg":"trace[443028703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1598; }","duration":"111.122625ms","start":"2026-02-13T07:01:26.977165Z","end":"2026-02-13T07:01:27.088288Z","steps":["trace[443028703] 'range keys from in-memory index tree' (duration: 110.334427ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:02:05.299079Z","caller":"traceutil/trace.go:171","msg":"trace[1554275548] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"117.745526ms","start":"2026-02-13T07:02:05.181309Z","end":"2026-02-13T07:02:05.299055Z","steps":["trace[1554275548] 'process raft request' (duration: 38.933582ms)","trace[1554275548] 'compare' (duration: 78.694672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:02:42.195576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.033271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-13T07:02:42.195658Z","caller":"traceutil/trace.go:171","msg":"trace[2009298603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1923; }","duration":"461.150144ms","start":"2026-02-13T07:02:41.73449Z","end":"2026-02-13T07:02:42.19564Z","steps":["trace[2009298603] 'range keys from in-memory index tree' (duration: 460.892128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:42.195726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:41.734474Z","time spent":"461.234336ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T07:02:42.196007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.400975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T07:02:42.196055Z","caller":"traceutil/trace.go:171","msg":"trace[1927864520] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1923; }","duration":"442.472028ms","start":"2026-02-13T07:02:41.753572Z","end":"2026-02-13T07:02:42.196044Z","steps":["trace[1927864520] 'count revisions from in-memory index tree' (duration: 442.349774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:42.196086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:41.753559Z","time spent":"442.519728ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-13T07:02:42.196191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.070824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-13T07:02:42.196207Z","caller":"traceutil/trace.go:171","msg":"trace[1843151860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1923; }","duration":"373.113374ms","start":"2026-02-13T07:02:41.823089Z","end":"2026-02-13T07:02:42.196202Z","steps":["trace[1843151860] 'range keys from in-memory index tree' (duration: 372.737724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:42.196219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:41.823067Z","time spent":"373.149225ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T07:02:42.196373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.606039ms","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-13T07:02:42.196388Z","caller":"traceutil/trace.go:171","msg":"trace[1373603981] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1923; }","duration":"242.65374ms","start":"2026-02-13T07:02:41.953729Z","end":"2026-02-13T07:02:42.196383Z","steps":["trace[1373603981] 'range keys from in-memory index tree' (duration: 242.542048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:42.19686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.559819ms","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-13T07:02:42.196896Z","caller":"traceutil/trace.go:171","msg":"trace[782826232] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1923; }","duration":"527.59789ms","start":"2026-02-13T07:02:41.669293Z","end":"2026-02-13T07:02:42.196891Z","steps":["trace[782826232] 'range keys from in-memory index tree' (duration: 527.552689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:42.197001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.960176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T07:02:42.197015Z","caller":"traceutil/trace.go:171","msg":"trace[1350283518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1923; }","duration":"583.003247ms","start":"2026-02-13T07:02:41.614009Z","end":"2026-02-13T07:02:42.197012Z","steps":["trace[1350283518] 'range keys from in-memory index tree' (duration: 582.913254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:42.197028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:41.613993Z","time spent":"583.031167ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T07:02:42.195605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.323437ms","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-13T07:02:42.215733Z","caller":"traceutil/trace.go:171","msg":"trace[428736387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1923; }","duration":"654.4921ms","start":"2026-02-13T07:02:41.561219Z","end":"2026-02-13T07:02:42.215711Z","steps":["trace[428736387] 'range keys from in-memory index tree' (duration: 634.172444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:42.215794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:41.5612Z","time spent":"654.565342ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T07:02:47.66622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.871968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-13T07:02:47.666314Z","caller":"traceutil/trace.go:171","msg":"trace[1529257367] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1952; }","duration":"385.031682ms","start":"2026-02-13T07:02:47.281265Z","end":"2026-02-13T07:02:47.666297Z","steps":["trace[1529257367] 'range keys from in-memory index tree' (duration: 384.666653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:47.666355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:47.28123Z","time spent":"385.116574ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-13T07:02:47.667516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.391314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-13T07:02:47.667566Z","caller":"traceutil/trace.go:171","msg":"trace[2070862654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1952; }","duration":"286.485877ms","start":"2026-02-13T07:02:47.381066Z","end":"2026-02-13T07:02:47.667552Z","steps":["trace[2070862654] 'range keys from in-memory index tree' (duration: 286.23076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:47.66773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.501567ms","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-13T07:02:47.667746Z","caller":"traceutil/trace.go:171","msg":"trace[832670430] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1952; }","duration":"257.540358ms","start":"2026-02-13T07:02:47.410199Z","end":"2026-02-13T07:02:47.667739Z","steps":["trace[832670430] 'count revisions from in-memory index tree' (duration: 257.447495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:47.66798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.388033ms","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-13T07:02:47.667995Z","caller":"traceutil/trace.go:171","msg":"trace[478992512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1952; }","duration":"160.468966ms","start":"2026-02-13T07:02:47.507522Z","end":"2026-02-13T07:02:47.667991Z","steps":["trace[478992512] 'range keys from in-memory index tree' (duration: 160.266701ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:02:47.999961Z","caller":"traceutil/trace.go:171","msg":"trace[2022197620] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2043; }","duration":"138.042141ms","start":"2026-02-13T07:02:47.861902Z","end":"2026-02-13T07:02:47.999944Z","steps":["trace[2022197620] 'read index received' (duration: 136.166788ms)","trace[2022197620] 'applied index is now lower than readState.Index' (duration: 1.874533ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:02:48.000616Z","caller":"traceutil/trace.go:171","msg":"trace[1662340956] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"323.892282ms","start":"2026-02-13T07:02:47.67671Z","end":"2026-02-13T07:02:48.000603Z","steps":["trace[1662340956] 'process raft request' (duration: 323.091602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:48.000823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:47.676705Z","time spent":"323.946123ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T07:02:48.001002Z","caller":"traceutil/trace.go:171","msg":"trace[1793134391] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"324.314012ms","start":"2026-02-13T07:02:47.676675Z","end":"2026-02-13T07:02:48.000989Z","steps":["trace[1793134391] 'process raft request' (duration: 323.240916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:48.001047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:47.67666Z","time spent":"324.363602ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T07:02:48.001171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.266081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T07:02:48.001193Z","caller":"traceutil/trace.go:171","msg":"trace[891558066] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1955; }","duration":"139.323312ms","start":"2026-02-13T07:02:47.861863Z","end":"2026-02-13T07:02:48.001187Z","steps":["trace[891558066] 'agreement among raft nodes before linearized reading' (duration: 139.267191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:48.001304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.597463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T07:02:48.001321Z","caller":"traceutil/trace.go:171","msg":"trace[1163955474] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1955; }","duration":"120.614563ms","start":"2026-02-13T07:02:47.880701Z","end":"2026-02-13T07:02:48.001316Z","steps":["trace[1163955474] 'agreement among raft nodes before linearized reading' (duration: 120.586853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:48.287893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.416164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T07:02:48.287953Z","caller":"traceutil/trace.go:171","msg":"trace[1087480255] linearizableReadLoop","detail":"{readStateIndex:2046; appliedIndex:2045; }","duration":"180.384262ms","start":"2026-02-13T07:02:48.107559Z","end":"2026-02-13T07:02:48.287943Z","steps":["trace[1087480255] 'read index received' (duration: 37.831895ms)","trace[1087480255] 'applied index is now lower than readState.Index' (duration: 142.551877ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:02:48.288064Z","caller":"traceutil/trace.go:171","msg":"trace[816757722] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"209.889003ms","start":"2026-02-13T07:02:48.078169Z","end":"2026-02-13T07:02:48.288058Z","steps":["trace[816757722] 'process raft request' (duration: 67.261045ms)","trace[816757722] 'compare' (duration: 142.315771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:02:48.288172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.616647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T07:02:48.288185Z","caller":"traceutil/trace.go:171","msg":"trace[522631312] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1956; }","duration":"180.654628ms","start":"2026-02-13T07:02:48.107526Z","end":"2026-02-13T07:02:48.288181Z","steps":["trace[522631312] 'agreement among raft nodes before linearized reading' (duration: 180.626227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:49.112293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.753039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T07:02:49.112396Z","caller":"traceutil/trace.go:171","msg":"trace[350799777] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"806.753823ms","start":"2026-02-13T07:02:48.305623Z","end":"2026-02-13T07:02:49.112377Z","steps":["trace[350799777] 'process raft request' (duration: 684.83821ms)","trace[350799777] 'compare' (duration: 121.623286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:02:49.112455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:48.30561Z","time spent":"806.815194ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T07:02:49.138384Z","caller":"traceutil/trace.go:171","msg":"trace[225722152] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2047; }","duration":"538.564766ms","start":"2026-02-13T07:02:48.599803Z","end":"2026-02-13T07:02:49.138368Z","steps":["trace[225722152] 'read index received' (duration: 390.733526ms)","trace[225722152] 'applied index is now lower than readState.Index' (duration: 147.83055ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T07:02:49.138435Z","caller":"traceutil/trace.go:171","msg":"trace[702130581] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"831.461241ms","start":"2026-02-13T07:02:48.30695Z","end":"2026-02-13T07:02:49.138411Z","steps":["trace[702130581] 'process raft request' (duration: 831.308907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:49.138492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.673218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T07:02:49.138516Z","caller":"traceutil/trace.go:171","msg":"trace[1835038067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1960; }","duration":"538.74325ms","start":"2026-02-13T07:02:48.599766Z","end":"2026-02-13T07:02:49.138509Z","steps":["trace[1835038067] 'agreement among raft nodes before linearized reading' (duration: 538.675899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:49.138539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:48.599729Z","time spent":"538.805072ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T07:02:49.138554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:48.306942Z","time spent":"831.554543ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T07:02:49.138712Z","caller":"traceutil/trace.go:171","msg":"trace[779951073] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"830.460358ms","start":"2026-02-13T07:02:48.308244Z","end":"2026-02-13T07:02:49.138705Z","steps":["trace[779951073] 'process raft request' (duration: 830.094039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:49.138771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T07:02:48.308238Z","time spent":"830.492889ms","remote":"127.0.0.1:36474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T07:02:57.361652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.737551ms","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-13T07:02:57.361726Z","caller":"traceutil/trace.go:171","msg":"trace[1433866223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1998; }","duration":"108.009518ms","start":"2026-02-13T07:02:57.253696Z","end":"2026-02-13T07:02:57.361706Z","steps":["trace[1433866223] 'range keys from in-memory index tree' (duration: 107.564797ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:02:58.349679Z","caller":"traceutil/trace.go:171","msg":"trace[1845795523] linearizableReadLoop","detail":"{readStateIndex:2096; appliedIndex:2095; }","duration":"112.82799ms","start":"2026-02-13T07:02:58.236823Z","end":"2026-02-13T07:02:58.349651Z","steps":["trace[1845795523] 'read index received' (duration: 112.203616ms)","trace[1845795523] 'applied index is now lower than readState.Index' (duration: 623.354µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:02:58.349952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.099907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-13T07:02:58.350052Z","caller":"traceutil/trace.go:171","msg":"trace[975009934] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:2003; }","duration":"113.24804ms","start":"2026-02-13T07:02:58.236777Z","end":"2026-02-13T07:02:58.350025Z","steps":["trace[975009934] 'agreement among raft nodes before linearized reading' (duration: 113.062256ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:02:58.350291Z","caller":"traceutil/trace.go:171","msg":"trace[613976849] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"146.137291ms","start":"2026-02-13T07:02:58.204142Z","end":"2026-02-13T07:02:58.350279Z","steps":["trace[613976849] 'process raft request' (duration: 145.011375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:02:59.457301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.154444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-13T07:02:59.457552Z","caller":"traceutil/trace.go:171","msg":"trace[150946798] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:2006; }","duration":"144.456531ms","start":"2026-02-13T07:02:59.313066Z","end":"2026-02-13T07:02:59.457522Z","steps":["trace[150946798] 'range keys from in-memory index tree' (duration: 144.080813ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:03:14.336712Z","caller":"traceutil/trace.go:171","msg":"trace[1721859821] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"122.534681ms","start":"2026-02-13T07:03:14.214135Z","end":"2026-02-13T07:03:14.336669Z","steps":["trace[1721859821] 'process raft request' (duration: 122.338946ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T07:03:14.336886Z","caller":"traceutil/trace.go:171","msg":"trace[1585778615] linearizableReadLoop","detail":"{readStateIndex:2162; appliedIndex:2162; }","duration":"100.107763ms","start":"2026-02-13T07:03:14.23677Z","end":"2026-02-13T07:03:14.336878Z","steps":["trace[1585778615] 'read index received' (duration: 100.102593ms)","trace[1585778615] 'applied index is now lower than readState.Index' (duration: 3.85µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T07:03:14.337591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.797669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:12 size:88627"} {"level":"info","ts":"2026-02-13T07:03:14.337631Z","caller":"traceutil/trace.go:171","msg":"trace[927504098] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:12; response_revision:2066; }","duration":"100.862601ms","start":"2026-02-13T07:03:14.236762Z","end":"2026-02-13T07:03:14.337625Z","steps":["trace[927504098] 'agreement among raft nodes before linearized reading' (duration: 100.657686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:03:14.655916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.995422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T07:03:14.658366Z","caller":"traceutil/trace.go:171","msg":"trace[1466928467] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2067; }","duration":"105.49187ms","start":"2026-02-13T07:03:14.552859Z","end":"2026-02-13T07:03:14.658351Z","steps":["trace[1466928467] 'count revisions from in-memory index tree' (duration: 102.893058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T07:03:14.656317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.052989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:12 size:8411"} {"level":"info","ts":"2026-02-13T07:03:14.658849Z","caller":"traceutil/trace.go:171","msg":"trace[74032280] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:12; response_revision:2067; }","duration":"104.59176ms","start":"2026-02-13T07:03:14.554243Z","end":"2026-02-13T07:03:14.658835Z","steps":["trace[74032280] 'range keys from in-memory index tree' (duration: 101.907367ms)"],"step_count":1}