{"level":"warn","ts":"2026-05-07T09:17:09.675486Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T09:17:09.675588Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-05-07T09:17:09.675653Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T09:17:09.675674Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-07T09:17:09.675703Z","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-05-07T09:17:09.676404Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-05-07T09:17:09.676496Z","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":8,"max-cpu-available":8,"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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-05-07T09:17:09.681331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.551536ms"} {"level":"info","ts":"2026-05-07T09:17:09.688508Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-05-07T09:17:09.688585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T09:17:09.68861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-05-07T09:17:09.688627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T09:17:09.688636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-05-07T09:17:09.688868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-05-07T09:17:09.702784Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T09:17:09.71026Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T09:17:09.718354Z","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-05-07T09:17:09.720589Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T09:17:09.721132Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T09:17:09.721169Z","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-05-07T09:17:09.721256Z","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-05-07T09:17:09.721269Z","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-05-07T09:17:09.72374Z","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-05-07T09:17:09.723937Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-07T09:17:09.723999Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-05-07T09:17:09.724535Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T09:17:09.724565Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T09:17:09.728932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-05-07T09:17:09.729041Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-05-07T09:17:10.489439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T09:17:10.489535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T09:17:10.489605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-05-07T09:17:10.48963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-05-07T09:17:10.489646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-07T09:17:10.489689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-05-07T09:17:10.489704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-05-07T09:17:10.490716Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T09:17:10.490718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T09:17:10.490759Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T09:17:10.491084Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T09:17:10.491143Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T09:17:10.494739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-05-07T09:17:10.497387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T09:17:10.50226Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T09:17:10.503545Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T09:17:10.503712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T09:17:10.503759Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T09:17:18.396086Z","caller":"traceutil/trace.go:171","msg":"trace[357543945] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"167.211654ms","start":"2026-05-07T09:17:18.228853Z","end":"2026-05-07T09:17:18.396065Z","steps":["trace[357543945] 'process raft request' (duration: 157.784765ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:34.545687Z","caller":"traceutil/trace.go:171","msg":"trace[1417962486] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"218.275406ms","start":"2026-05-07T09:17:34.327384Z","end":"2026-05-07T09:17:34.54566Z","steps":["trace[1417962486] 'process raft request' (duration: 218.104882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:41.372554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514990222,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T09:17:41.495412Z","caller":"traceutil/trace.go:171","msg":"trace[1119873163] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"706.259662ms","start":"2026-05-07T09:17:40.789119Z","end":"2026-05-07T09:17:41.495379Z","steps":["trace[1119873163] 'process raft request' (duration: 705.998726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:41.49634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:40.789097Z","time spent":"706.411185ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:17:41.504329Z","caller":"traceutil/trace.go:171","msg":"trace[1284811199] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"712.399335ms","start":"2026-05-07T09:17:40.791887Z","end":"2026-05-07T09:17:41.504286Z","steps":["trace[1284811199] 'process raft request' (duration: 712.20453ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:41.504397Z","caller":"traceutil/trace.go:171","msg":"trace[1252363837] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"712.432546ms","start":"2026-05-07T09:17:40.791932Z","end":"2026-05-07T09:17:41.504364Z","steps":["trace[1252363837] 'process raft request' (duration: 712.276482ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:41.504504Z","caller":"traceutil/trace.go:171","msg":"trace[183668525] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:564; }","duration":"632.449578ms","start":"2026-05-07T09:17:40.87203Z","end":"2026-05-07T09:17:41.50448Z","steps":["trace[183668525] 'read index received' (duration: 623.182002ms)","trace[183668525] 'applied index is now lower than readState.Index' (duration: 9.265786ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:17:41.50454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:40.79188Z","time spent":"712.538368ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:17:41.504579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:40.791928Z","time spent":"712.549518ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:17:41.5046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.558351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:41.504689Z","caller":"traceutil/trace.go:171","msg":"trace[154406508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"632.670053ms","start":"2026-05-07T09:17:40.87201Z","end":"2026-05-07T09:17:41.50468Z","steps":["trace[154406508] 'agreement among raft nodes before linearized reading' (duration: 632.54184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:41.504714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:40.872002Z","time spent":"632.705884ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T09:17:41.504735Z","caller":"traceutil/trace.go:171","msg":"trace[941812347] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"697.706642ms","start":"2026-05-07T09:17:40.807016Z","end":"2026-05-07T09:17:41.504722Z","steps":["trace[941812347] 'process raft request' (duration: 697.247711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:41.504796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:40.806998Z","time spent":"697.769253ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:17:41.50487Z","caller":"traceutil/trace.go:171","msg":"trace[1699538160] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"649.881865ms","start":"2026-05-07T09:17:40.854978Z","end":"2026-05-07T09:17:41.50486Z","steps":["trace[1699538160] 'process raft request' (duration: 649.424935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:41.504928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:40.854951Z","time spent":"649.944897ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T09:17:43.084567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514990259,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T09:17:43.138761Z","caller":"traceutil/trace.go:171","msg":"trace[1847598671] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"767.444329ms","start":"2026-05-07T09:17:42.371236Z","end":"2026-05-07T09:17:43.13868Z","steps":["trace[1847598671] 'process raft request' (duration: 762.274738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:43.139003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:42.371215Z","time spent":"767.644484ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:17:43.150619Z","caller":"traceutil/trace.go:171","msg":"trace[538297141] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:608; }","duration":"566.819925ms","start":"2026-05-07T09:17:42.583756Z","end":"2026-05-07T09:17:43.150576Z","steps":["trace[538297141] 'read index received' (duration: 549.362427ms)","trace[538297141] 'applied index is now lower than readState.Index' (duration: 17.456138ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:17:43.150816Z","caller":"traceutil/trace.go:171","msg":"trace[1992467096] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"661.006284ms","start":"2026-05-07T09:17:42.489796Z","end":"2026-05-07T09:17:43.150802Z","steps":["trace[1992467096] 'process raft request' (duration: 660.688367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:43.151025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:42.489768Z","time spent":"661.094415ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:17:43.15105Z","caller":"traceutil/trace.go:171","msg":"trace[885779020] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"729.335289ms","start":"2026-05-07T09:17:42.421611Z","end":"2026-05-07T09:17:43.150946Z","steps":["trace[885779020] 'process raft request' (duration: 728.667754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:43.151167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:42.421575Z","time spent":"729.546194ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T09:17:43.151187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.008934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:43.15125Z","caller":"traceutil/trace.go:171","msg":"trace[1549535607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"276.106596ms","start":"2026-05-07T09:17:42.875123Z","end":"2026-05-07T09:17:43.151229Z","steps":["trace[1549535607] 'agreement among raft nodes before linearized reading' (duration: 275.978623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:43.151429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.675544ms","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-05-07T09:17:43.151454Z","caller":"traceutil/trace.go:171","msg":"trace[253390474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"567.737086ms","start":"2026-05-07T09:17:42.583711Z","end":"2026-05-07T09:17:43.151448Z","steps":["trace[253390474] 'agreement among raft nodes before linearized reading' (duration: 567.684275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:43.151473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:42.58369Z","time spent":"567.778127ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T09:17:47.810865Z","caller":"traceutil/trace.go:171","msg":"trace[1103367972] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"173.373998ms","start":"2026-05-07T09:17:47.637465Z","end":"2026-05-07T09:17:47.810839Z","steps":["trace[1103367972] 'process raft request' (duration: 172.845786ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:48.194202Z","caller":"traceutil/trace.go:171","msg":"trace[1883163212] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"242.984124ms","start":"2026-05-07T09:17:47.951187Z","end":"2026-05-07T09:17:48.194171Z","steps":["trace[1883163212] 'read index received' (duration: 242.81809ms)","trace[1883163212] 'applied index is now lower than readState.Index' (duration: 164.804µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:17:48.194547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.352282ms","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-05-07T09:17:48.194657Z","caller":"traceutil/trace.go:171","msg":"trace[857553749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"243.503446ms","start":"2026-05-07T09:17:47.951133Z","end":"2026-05-07T09:17:48.194636Z","steps":["trace[857553749] 'agreement among raft nodes before linearized reading' (duration: 243.211339ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:48.194656Z","caller":"traceutil/trace.go:171","msg":"trace[1156660584] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"305.998965ms","start":"2026-05-07T09:17:47.888615Z","end":"2026-05-07T09:17:48.194614Z","steps":["trace[1156660584] 'process raft request' (duration: 305.35904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:48.194704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.351975ms","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-05-07T09:17:48.194819Z","caller":"traceutil/trace.go:171","msg":"trace[1127859042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:620; }","duration":"205.497899ms","start":"2026-05-07T09:17:47.989275Z","end":"2026-05-07T09:17:48.194773Z","steps":["trace[1127859042] 'agreement among raft nodes before linearized reading' (duration: 205.319945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:48.194838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:47.888589Z","time spent":"306.17412ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T09:17:48.195173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.754358ms","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-05-07T09:17:48.195218Z","caller":"traceutil/trace.go:171","msg":"trace[2127093611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:620; }","duration":"210.436284ms","start":"2026-05-07T09:17:47.984769Z","end":"2026-05-07T09:17:48.195206Z","steps":["trace[2127093611] 'agreement among raft nodes before linearized reading' (duration: 209.580294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:49.373685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514990377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:17:49.874259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514990377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:17:50.175426Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.570178943s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T09:17:50.301055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984689511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cgpzr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-07T09:17:50.301128Z","caller":"traceutil/trace.go:171","msg":"trace[63642743] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cgpzr; range_end:; response_count:1; response_revision:623; }","duration":"1.984799604s","start":"2026-05-07T09:17:48.316311Z","end":"2026-05-07T09:17:50.301111Z","steps":["trace[63642743] 'range keys from in-memory index tree' (duration: 1.984481466s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:50.301342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:48.316295Z","time spent":"1.985033499s","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-cgpzr\" "} {"level":"warn","ts":"2026-05-07T09:17:50.301671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.627664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T09:17:50.301773Z","caller":"traceutil/trace.go:171","msg":"trace[448598992] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.69813371s","start":"2026-05-07T09:17:48.603626Z","end":"2026-05-07T09:17:50.30176Z","steps":["trace[448598992] 'process raft request' (duration: 1.57224586s)","trace[448598992] 'compare' (duration: 124.778394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:17:50.301839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:48.603593Z","time spent":"1.698224372s","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:17:50.337825Z","caller":"traceutil/trace.go:171","msg":"trace[966871949] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"1.464653189s","start":"2026-05-07T09:17:48.873153Z","end":"2026-05-07T09:17:50.337807Z","steps":["trace[966871949] 'read index received' (duration: 1.302543083s)","trace[966871949] 'applied index is now lower than readState.Index' (duration: 162.108965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:17:50.337932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464761311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:50.338054Z","caller":"traceutil/trace.go:171","msg":"trace[1009428337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"1.464931715s","start":"2026-05-07T09:17:48.873115Z","end":"2026-05-07T09:17:50.338046Z","steps":["trace[1009428337] 'agreement among raft nodes before linearized reading' (duration: 1.464774781s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:50.338112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:48.873095Z","time spent":"1.465008616s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T09:17:50.338501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158459029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T09:17:50.338551Z","caller":"traceutil/trace.go:171","msg":"trace[1303289037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"1.158561452s","start":"2026-05-07T09:17:49.179979Z","end":"2026-05-07T09:17:50.33854Z","steps":["trace[1303289037] 'agreement among raft nodes before linearized reading' (duration: 1.158417398s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:50.338578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:49.179958Z","time spent":"1.158614602s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T09:17:50.338984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.983618ms","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-05-07T09:17:50.338998Z","caller":"traceutil/trace.go:171","msg":"trace[1474533791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"122.041639ms","start":"2026-05-07T09:17:50.216952Z","end":"2026-05-07T09:17:50.338994Z","steps":["trace[1474533791] 'agreement among raft nodes before linearized reading' (duration: 121.997398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:50.339085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.213333ms","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-05-07T09:17:50.339096Z","caller":"traceutil/trace.go:171","msg":"trace[539285115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"122.272135ms","start":"2026-05-07T09:17:50.216821Z","end":"2026-05-07T09:17:50.339093Z","steps":["trace[539285115] 'agreement among raft nodes before linearized reading' (duration: 122.227244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:50.339175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.665001ms","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-05-07T09:17:50.339187Z","caller":"traceutil/trace.go:171","msg":"trace[2050601293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"130.710552ms","start":"2026-05-07T09:17:50.208473Z","end":"2026-05-07T09:17:50.339184Z","steps":["trace[2050601293] 'agreement among raft nodes before linearized reading' (duration: 130.672351ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:52.573709Z","caller":"traceutil/trace.go:171","msg":"trace[723782069] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"189.901924ms","start":"2026-05-07T09:17:52.38376Z","end":"2026-05-07T09:17:52.573662Z","steps":["trace[723782069] 'process raft request' (duration: 189.7338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:54.667306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.508694ms","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-05-07T09:17:54.667405Z","caller":"traceutil/trace.go:171","msg":"trace[1225494505] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"144.664497ms","start":"2026-05-07T09:17:54.522717Z","end":"2026-05-07T09:17:54.667382Z","steps":["trace[1225494505] 'range keys from in-memory index tree' (duration: 144.286849ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:55.599054Z","caller":"traceutil/trace.go:171","msg":"trace[874360536] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"197.968311ms","start":"2026-05-07T09:17:55.40105Z","end":"2026-05-07T09:17:55.599019Z","steps":["trace[874360536] 'process raft request' (duration: 197.729555ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:55.790401Z","caller":"traceutil/trace.go:171","msg":"trace[748832897] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"233.342498ms","start":"2026-05-07T09:17:55.557016Z","end":"2026-05-07T09:17:55.790359Z","steps":["trace[748832897] 'process raft request' (duration: 232.809196ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.138932Z","caller":"traceutil/trace.go:171","msg":"trace[442335069] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"187.835475ms","start":"2026-05-07T09:17:55.951078Z","end":"2026-05-07T09:17:56.138913Z","steps":["trace[442335069] 'process raft request' (duration: 187.577169ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.387222Z","caller":"traceutil/trace.go:171","msg":"trace[2113155931] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"397.750375ms","start":"2026-05-07T09:17:55.989416Z","end":"2026-05-07T09:17:56.387167Z","steps":["trace[2113155931] 'process raft request' (duration: 397.295514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:56.387389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:55.989394Z","time spent":"397.904739ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:17:56.38785Z","caller":"traceutil/trace.go:171","msg":"trace[1426333] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:736; }","duration":"265.744104ms","start":"2026-05-07T09:17:56.122085Z","end":"2026-05-07T09:17:56.387829Z","steps":["trace[1426333] 'read index received' (duration: 16.642358ms)","trace[1426333] 'applied index is now lower than readState.Index' (duration: 249.100326ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:17:56.388131Z","caller":"traceutil/trace.go:171","msg":"trace[1646295728] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"367.158271ms","start":"2026-05-07T09:17:56.020961Z","end":"2026-05-07T09:17:56.388119Z","steps":["trace[1646295728] 'process raft request' (duration: 366.044475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:56.388407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.318827ms","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":"info","ts":"2026-05-07T09:17:56.388449Z","caller":"traceutil/trace.go:171","msg":"trace[1391031041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"266.386269ms","start":"2026-05-07T09:17:56.12205Z","end":"2026-05-07T09:17:56.388436Z","steps":["trace[1391031041] 'agreement among raft nodes before linearized reading' (duration: 266.252216ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.388385Z","caller":"traceutil/trace.go:171","msg":"trace[675079994] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"323.82542ms","start":"2026-05-07T09:17:56.064533Z","end":"2026-05-07T09:17:56.388358Z","steps":["trace[675079994] 'process raft request' (duration: 323.248426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:56.388613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:56.064498Z","time spent":"324.052195ms","remote":"127.0.0.1:52198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T09:17:56.388262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:17:56.020937Z","time spent":"367.219032ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:17:56.432582Z","caller":"traceutil/trace.go:171","msg":"trace[1244068489] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"226.18708ms","start":"2026-05-07T09:17:56.206372Z","end":"2026-05-07T09:17:56.43256Z","steps":["trace[1244068489] 'process raft request' (duration: 225.736349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:56.433946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.98007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-07T09:17:56.434021Z","caller":"traceutil/trace.go:171","msg":"trace[733019141] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"111.112413ms","start":"2026-05-07T09:17:56.322892Z","end":"2026-05-07T09:17:56.434004Z","steps":["trace[733019141] 'agreement among raft nodes before linearized reading' (duration: 110.752275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:56.434216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.69928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T09:17:56.434444Z","caller":"traceutil/trace.go:171","msg":"trace[1188047130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"270.832593ms","start":"2026-05-07T09:17:56.163446Z","end":"2026-05-07T09:17:56.434278Z","steps":["trace[1188047130] 'agreement among raft nodes before linearized reading' (duration: 270.649099ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.586056Z","caller":"traceutil/trace.go:171","msg":"trace[1924196298] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"133.367104ms","start":"2026-05-07T09:17:56.452667Z","end":"2026-05-07T09:17:56.586034Z","steps":["trace[1924196298] 'process raft request' (duration: 133.276772ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.586081Z","caller":"traceutil/trace.go:171","msg":"trace[1616085664] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"140.303685ms","start":"2026-05-07T09:17:56.445744Z","end":"2026-05-07T09:17:56.586047Z","steps":["trace[1616085664] 'process raft request' (duration: 106.088846ms)","trace[1616085664] 'compare' (duration: 33.981764ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:17:56.586392Z","caller":"traceutil/trace.go:171","msg":"trace[1140495170] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"133.6484ms","start":"2026-05-07T09:17:56.45273Z","end":"2026-05-07T09:17:56.586378Z","steps":["trace[1140495170] 'process raft request' (duration: 133.266301ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.586708Z","caller":"traceutil/trace.go:171","msg":"trace[1636223020] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"133.474017ms","start":"2026-05-07T09:17:56.453193Z","end":"2026-05-07T09:17:56.586667Z","steps":["trace[1636223020] 'process raft request' (duration: 133.18607ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.726355Z","caller":"traceutil/trace.go:171","msg":"trace[934114472] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"201.433153ms","start":"2026-05-07T09:17:56.524898Z","end":"2026-05-07T09:17:56.726331Z","steps":["trace[934114472] 'process raft request' (duration: 177.815861ms)","trace[934114472] 'compare' (duration: 23.347576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:17:56.936701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.559274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T09:17:56.936721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.020935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:56.936792Z","caller":"traceutil/trace.go:171","msg":"trace[348384228] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:721; }","duration":"105.714577ms","start":"2026-05-07T09:17:56.831057Z","end":"2026-05-07T09:17:56.936771Z","steps":["trace[348384228] 'range keys from in-memory index tree' (duration: 105.507313ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:56.936804Z","caller":"traceutil/trace.go:171","msg":"trace[1188078193] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:721; }","duration":"106.165808ms","start":"2026-05-07T09:17:56.830619Z","end":"2026-05-07T09:17:56.936785Z","steps":["trace[1188078193] 'count revisions from in-memory index tree' (duration: 105.942113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:56.937396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.830271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:56.937494Z","caller":"traceutil/trace.go:171","msg":"trace[1892474688] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:721; }","duration":"106.751931ms","start":"2026-05-07T09:17:56.830703Z","end":"2026-05-07T09:17:56.937455Z","steps":["trace[1892474688] 'range keys from in-memory index tree' (duration: 105.736878ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:57.018175Z","caller":"traceutil/trace.go:171","msg":"trace[754890756] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"144.673007ms","start":"2026-05-07T09:17:56.873484Z","end":"2026-05-07T09:17:57.018157Z","steps":["trace[754890756] 'read index received' (duration: 144.523594ms)","trace[754890756] 'applied index is now lower than readState.Index' (duration: 148.383µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:17:57.018281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.79227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.018308Z","caller":"traceutil/trace.go:171","msg":"trace[1847632507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"144.856852ms","start":"2026-05-07T09:17:56.873444Z","end":"2026-05-07T09:17:57.018301Z","steps":["trace[1847632507] 'agreement among raft nodes before linearized reading' (duration: 144.7776ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:57.018302Z","caller":"traceutil/trace.go:171","msg":"trace[290664029] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"175.020466ms","start":"2026-05-07T09:17:56.843248Z","end":"2026-05-07T09:17:57.018268Z","steps":["trace[290664029] 'process raft request' (duration: 174.817001ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:57.166862Z","caller":"traceutil/trace.go:171","msg":"trace[830730636] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"137.610372ms","start":"2026-05-07T09:17:57.029229Z","end":"2026-05-07T09:17:57.166839Z","steps":["trace[830730636] 'process raft request' (duration: 137.450379ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:57.323932Z","caller":"traceutil/trace.go:171","msg":"trace[708993704] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"142.691901ms","start":"2026-05-07T09:17:57.181211Z","end":"2026-05-07T09:17:57.323903Z","steps":["trace[708993704] 'process raft request' (duration: 115.797033ms)","trace[708993704] 'compare' (duration: 26.659733ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:17:57.32395Z","caller":"traceutil/trace.go:171","msg":"trace[2079844306] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:751; }","duration":"132.426752ms","start":"2026-05-07T09:17:57.1915Z","end":"2026-05-07T09:17:57.323927Z","steps":["trace[2079844306] 'read index received' (duration: 105.524814ms)","trace[2079844306] 'applied index is now lower than readState.Index' (duration: 26.900888ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:17:57.324052Z","caller":"traceutil/trace.go:171","msg":"trace[1799694007] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"142.797784ms","start":"2026-05-07T09:17:57.181246Z","end":"2026-05-07T09:17:57.324044Z","steps":["trace[1799694007] 'process raft request' (duration: 142.560738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.32411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.592276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.324136Z","caller":"traceutil/trace.go:171","msg":"trace[437909484] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:726; }","duration":"132.662588ms","start":"2026-05-07T09:17:57.191467Z","end":"2026-05-07T09:17:57.32413Z","steps":["trace[437909484] 'agreement among raft nodes before linearized reading' (duration: 132.594226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.41922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.275006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.419303Z","caller":"traceutil/trace.go:171","msg":"trace[2019172992] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:727; }","duration":"111.39167ms","start":"2026-05-07T09:17:57.307895Z","end":"2026-05-07T09:17:57.419286Z","steps":["trace[2019172992] 'agreement among raft nodes before linearized reading' (duration: 111.226926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.419288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.168829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.419366Z","caller":"traceutil/trace.go:171","msg":"trace[2107624181] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:727; }","duration":"214.287102ms","start":"2026-05-07T09:17:57.20506Z","end":"2026-05-07T09:17:57.419347Z","steps":["trace[2107624181] 'agreement among raft nodes before linearized reading' (duration: 214.109868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.419546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.001822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.419607Z","caller":"traceutil/trace.go:171","msg":"trace[238302300] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:727; }","duration":"228.078193ms","start":"2026-05-07T09:17:57.191518Z","end":"2026-05-07T09:17:57.419597Z","steps":["trace[238302300] 'agreement among raft nodes before linearized reading' (duration: 227.996741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.419609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.974071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T09:17:57.41968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.27213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.419724Z","caller":"traceutil/trace.go:171","msg":"trace[262726454] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:727; }","duration":"228.143326ms","start":"2026-05-07T09:17:57.191556Z","end":"2026-05-07T09:17:57.419699Z","steps":["trace[262726454] 'agreement among raft nodes before linearized reading' (duration: 227.956571ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:57.419705Z","caller":"traceutil/trace.go:171","msg":"trace[1078092196] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"143.13345ms","start":"2026-05-07T09:17:57.275915Z","end":"2026-05-07T09:17:57.419048Z","steps":["trace[1078092196] 'process raft request' (duration: 142.996467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.419877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.473325ms","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-05-07T09:17:57.419957Z","caller":"traceutil/trace.go:171","msg":"trace[749111653] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:727; }","duration":"112.576997ms","start":"2026-05-07T09:17:57.307367Z","end":"2026-05-07T09:17:57.419944Z","steps":["trace[749111653] 'agreement among raft nodes before linearized reading' (duration: 112.465625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.419983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.907496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.420029Z","caller":"traceutil/trace.go:171","msg":"trace[1290181564] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:727; }","duration":"214.998799ms","start":"2026-05-07T09:17:57.205018Z","end":"2026-05-07T09:17:57.420017Z","steps":["trace[1290181564] 'agreement among raft nodes before linearized reading' (duration: 214.834225ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:57.419718Z","caller":"traceutil/trace.go:171","msg":"trace[233797091] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:727; }","duration":"112.346802ms","start":"2026-05-07T09:17:57.307357Z","end":"2026-05-07T09:17:57.419704Z","steps":["trace[233797091] 'agreement among raft nodes before linearized reading' (duration: 112.29489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.421004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.868029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.421091Z","caller":"traceutil/trace.go:171","msg":"trace[722837850] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:727; }","duration":"215.998122ms","start":"2026-05-07T09:17:57.205074Z","end":"2026-05-07T09:17:57.421072Z","steps":["trace[722837850] 'agreement among raft nodes before linearized reading' (duration: 214.717892ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:17:57.53965Z","caller":"traceutil/trace.go:171","msg":"trace[1140267585] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"120.528854ms","start":"2026-05-07T09:17:57.419089Z","end":"2026-05-07T09:17:57.539618Z","steps":["trace[1140267585] 'read index received' (duration: 114.185886ms)","trace[1140267585] 'applied index is now lower than readState.Index' (duration: 6.340568ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:17:57.539798Z","caller":"traceutil/trace.go:171","msg":"trace[1383193293] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"211.253021ms","start":"2026-05-07T09:17:57.328534Z","end":"2026-05-07T09:17:57.539787Z","steps":["trace[1383193293] 'process raft request' (duration: 204.820311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.540165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.225881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.540216Z","caller":"traceutil/trace.go:171","msg":"trace[1322998689] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:728; }","duration":"205.380335ms","start":"2026-05-07T09:17:57.334822Z","end":"2026-05-07T09:17:57.540202Z","steps":["trace[1322998689] 'agreement among raft nodes before linearized reading' (duration: 205.18266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.540577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.711731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-7v9ww\" ","response":"range_response_count:1 size:3835"} {"level":"info","ts":"2026-05-07T09:17:57.540742Z","caller":"traceutil/trace.go:171","msg":"trace[1661555194] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-7v9ww; range_end:; response_count:1; response_revision:728; }","duration":"211.912577ms","start":"2026-05-07T09:17:57.328807Z","end":"2026-05-07T09:17:57.540719Z","steps":["trace[1661555194] 'agreement among raft nodes before linearized reading' (duration: 211.456406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.540973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.143662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.541021Z","caller":"traceutil/trace.go:171","msg":"trace[2079888806] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:728; }","duration":"206.191813ms","start":"2026-05-07T09:17:57.334813Z","end":"2026-05-07T09:17:57.541005Z","steps":["trace[2079888806] 'agreement among raft nodes before linearized reading' (duration: 206.116202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:17:57.541221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.757017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:17:57.541259Z","caller":"traceutil/trace.go:171","msg":"trace[754851537] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:728; }","duration":"206.808378ms","start":"2026-05-07T09:17:57.334442Z","end":"2026-05-07T09:17:57.54125Z","steps":["trace[754851537] 'agreement among raft nodes before linearized reading' (duration: 206.751817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:05.989251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.392205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-87znh\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-07T09:18:05.989414Z","caller":"traceutil/trace.go:171","msg":"trace[817882801] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-87znh; range_end:; response_count:1; response_revision:788; }","duration":"549.60041ms","start":"2026-05-07T09:18:05.439788Z","end":"2026-05-07T09:18:05.989388Z","steps":["trace[817882801] 'range keys from in-memory index tree' (duration: 549.220522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:05.989493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:05.43977Z","time spent":"549.706702ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-87znh\" "} {"level":"info","ts":"2026-05-07T09:18:06.594446Z","caller":"traceutil/trace.go:171","msg":"trace[1702196659] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"598.134903ms","start":"2026-05-07T09:18:05.996287Z","end":"2026-05-07T09:18:06.594422Z","steps":["trace[1702196659] 'process raft request' (duration: 597.933868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:06.594608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:05.996262Z","time spent":"598.256006ms","remote":"127.0.0.1:52316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:18:24.402249Z","caller":"traceutil/trace.go:171","msg":"trace[1696393629] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"116.545791ms","start":"2026-05-07T09:18:24.285681Z","end":"2026-05-07T09:18:24.402227Z","steps":["trace[1696393629] 'process raft request' (duration: 116.398607ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:25.641252Z","caller":"traceutil/trace.go:171","msg":"trace[806567373] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"113.793537ms","start":"2026-05-07T09:18:25.527435Z","end":"2026-05-07T09:18:25.641228Z","steps":["trace[806567373] 'process raft request' (duration: 113.620563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:27.061369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.823073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T09:18:27.061629Z","caller":"traceutil/trace.go:171","msg":"trace[1239936021] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"226.416455ms","start":"2026-05-07T09:18:26.835185Z","end":"2026-05-07T09:18:27.061601Z","steps":["trace[1239936021] 'process raft request' (duration: 119.265564ms)","trace[1239936021] 'compare' (duration: 106.718571ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:18:27.06188Z","caller":"traceutil/trace.go:171","msg":"trace[2064432825] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"226.586239ms","start":"2026-05-07T09:18:26.835272Z","end":"2026-05-07T09:18:27.061858Z","steps":["trace[2064432825] 'process raft request' (duration: 226.230331ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:27.062025Z","caller":"traceutil/trace.go:171","msg":"trace[1774550256] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:990; }","duration":"187.59884ms","start":"2026-05-07T09:18:26.874408Z","end":"2026-05-07T09:18:27.062007Z","steps":["trace[1774550256] 'read index received' (duration: 80.061039ms)","trace[1774550256] 'applied index is now lower than readState.Index' (duration: 107.536711ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:18:27.062154Z","caller":"traceutil/trace.go:171","msg":"trace[753509553] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"226.59346ms","start":"2026-05-07T09:18:26.835551Z","end":"2026-05-07T09:18:27.062144Z","steps":["trace[753509553] 'process raft request' (duration: 226.190031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:27.062329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.913477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:18:27.062374Z","caller":"traceutil/trace.go:171","msg":"trace[76071008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"187.990809ms","start":"2026-05-07T09:18:26.874373Z","end":"2026-05-07T09:18:27.062363Z","steps":["trace[76071008] 'agreement among raft nodes before linearized reading' (duration: 187.709163ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:37.313301Z","caller":"traceutil/trace.go:171","msg":"trace[902241737] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"317.888841ms","start":"2026-05-07T09:18:36.995397Z","end":"2026-05-07T09:18:37.313285Z","steps":["trace[902241737] 'read index received' (duration: 317.81441ms)","trace[902241737] 'applied index is now lower than readState.Index' (duration: 73.871µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:18:37.313377Z","caller":"traceutil/trace.go:171","msg":"trace[1940966116] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"408.537947ms","start":"2026-05-07T09:18:36.904834Z","end":"2026-05-07T09:18:37.313372Z","steps":["trace[1940966116] 'process raft request' (duration: 408.330512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:37.313443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:36.904823Z","time spent":"408.568408ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:18:37.313576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.181018ms","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-05-07T09:18:37.313597Z","caller":"traceutil/trace.go:171","msg":"trace[2014597081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"318.237219ms","start":"2026-05-07T09:18:36.995355Z","end":"2026-05-07T09:18:37.313592Z","steps":["trace[2014597081] 'agreement among raft nodes before linearized reading' (duration: 318.163868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:37.313611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:36.995339Z","time spent":"318.26874ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T09:18:37.31381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.731986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T09:18:37.313829Z","caller":"traceutil/trace.go:171","msg":"trace[640887560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"194.777787ms","start":"2026-05-07T09:18:37.119047Z","end":"2026-05-07T09:18:37.313825Z","steps":["trace[640887560] 'agreement among raft nodes before linearized reading' (duration: 194.730756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:37.313847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.992112ms","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-05-07T09:18:37.313929Z","caller":"traceutil/trace.go:171","msg":"trace[1738851774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"210.122216ms","start":"2026-05-07T09:18:37.10379Z","end":"2026-05-07T09:18:37.313912Z","steps":["trace[1738851774] 'agreement among raft nodes before linearized reading' (duration: 209.914671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:37.313887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.985415ms","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-05-07T09:18:37.31404Z","caller":"traceutil/trace.go:171","msg":"trace[795009873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"196.15759ms","start":"2026-05-07T09:18:37.117874Z","end":"2026-05-07T09:18:37.314032Z","steps":["trace[795009873] 'agreement among raft nodes before linearized reading' (duration: 195.989726ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:41.875823Z","caller":"traceutil/trace.go:171","msg":"trace[586750999] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"162.128265ms","start":"2026-05-07T09:18:41.713674Z","end":"2026-05-07T09:18:41.875803Z","steps":["trace[586750999] 'process raft request' (duration: 162.033312ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:43.730212Z","caller":"traceutil/trace.go:171","msg":"trace[1557826533] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"365.167655ms","start":"2026-05-07T09:18:43.365019Z","end":"2026-05-07T09:18:43.730186Z","steps":["trace[1557826533] 'read index received' (duration: 364.984521ms)","trace[1557826533] 'applied index is now lower than readState.Index' (duration: 182.184µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:18:43.73034Z","caller":"traceutil/trace.go:171","msg":"trace[2035768551] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"385.9709ms","start":"2026-05-07T09:18:43.344355Z","end":"2026-05-07T09:18:43.730326Z","steps":["trace[2035768551] 'process raft request' (duration: 385.698854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:43.730453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.344338Z","time spent":"386.030182ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:18:43.730578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.245957ms","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-05-07T09:18:43.730646Z","caller":"traceutil/trace.go:171","msg":"trace[1270501862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"359.345008ms","start":"2026-05-07T09:18:43.371287Z","end":"2026-05-07T09:18:43.730632Z","steps":["trace[1270501862] 'agreement among raft nodes before linearized reading' (duration: 359.181835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:43.730673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.371276Z","time spent":"359.38936ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T09:18:43.73085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.751745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T09:18:43.730887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.129844ms","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-05-07T09:18:43.730925Z","caller":"traceutil/trace.go:171","msg":"trace[1537159043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1010; }","duration":"355.851768ms","start":"2026-05-07T09:18:43.375053Z","end":"2026-05-07T09:18:43.730905Z","steps":["trace[1537159043] 'agreement among raft nodes before linearized reading' (duration: 355.593942ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:43.730932Z","caller":"traceutil/trace.go:171","msg":"trace[1341371166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"356.217466ms","start":"2026-05-07T09:18:43.374701Z","end":"2026-05-07T09:18:43.730919Z","steps":["trace[1341371166] 'agreement among raft nodes before linearized reading' (duration: 356.050222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:43.730965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.375048Z","time spent":"355.907789ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T09:18:43.730965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.374684Z","time spent":"356.274367ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T09:18:43.730961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.223186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-07T09:18:43.730984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.968803ms","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-05-07T09:18:43.731017Z","caller":"traceutil/trace.go:171","msg":"trace[751744734] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1010; }","duration":"356.309198ms","start":"2026-05-07T09:18:43.374695Z","end":"2026-05-07T09:18:43.731004Z","steps":["trace[751744734] 'agreement among raft nodes before linearized reading' (duration: 356.123704ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:43.731021Z","caller":"traceutil/trace.go:171","msg":"trace[328305407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"366.033585ms","start":"2026-05-07T09:18:43.364979Z","end":"2026-05-07T09:18:43.731013Z","steps":["trace[328305407] 'agreement among raft nodes before linearized reading' (duration: 365.951783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:43.731043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.374688Z","time spent":"356.350049ms","remote":"127.0.0.1:52144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-05-07T09:18:43.731044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.364964Z","time spent":"366.076216ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T09:18:44.374311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514991206,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:18:44.904529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.73189Z","time spent":"1.172632223s","remote":"127.0.0.1:52144","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T09:18:45.682202Z","caller":"traceutil/trace.go:171","msg":"trace[301483305] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.947671667s","start":"2026-05-07T09:18:43.734505Z","end":"2026-05-07T09:18:45.682177Z","steps":["trace[301483305] 'process raft request' (duration: 1.947410831s)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:45.682319Z","caller":"traceutil/trace.go:171","msg":"trace[93853937] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.947712868s","start":"2026-05-07T09:18:43.734579Z","end":"2026-05-07T09:18:45.682291Z","steps":["trace[93853937] 'process raft request' (duration: 1.947506993s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:45.682347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.734496Z","time spent":"1.947771819s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:18:45.682456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.734573Z","time spent":"1.947810711s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:18:45.682978Z","caller":"traceutil/trace.go:171","msg":"trace[212990837] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1049; }","duration":"1.809150344s","start":"2026-05-07T09:18:43.873812Z","end":"2026-05-07T09:18:45.682962Z","steps":["trace[212990837] 'read index received' (duration: 995.133891ms)","trace[212990837] 'applied index is now lower than readState.Index' (duration: 814.015583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:18:45.683125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.809307308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:18:45.683165Z","caller":"traceutil/trace.go:171","msg":"trace[197202403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"1.80937327s","start":"2026-05-07T09:18:43.87378Z","end":"2026-05-07T09:18:45.683153Z","steps":["trace[197202403] 'agreement among raft nodes before linearized reading' (duration: 1.809242517s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:45.683208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.873757Z","time spent":"1.809444621s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T09:18:45.683446Z","caller":"traceutil/trace.go:171","msg":"trace[1043906168] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.948860365s","start":"2026-05-07T09:18:43.734571Z","end":"2026-05-07T09:18:45.683432Z","steps":["trace[1043906168] 'process raft request' (duration: 1.948110758s)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:45.683561Z","caller":"traceutil/trace.go:171","msg":"trace[513359602] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.948833324s","start":"2026-05-07T09:18:43.734714Z","end":"2026-05-07T09:18:45.683547Z","steps":["trace[513359602] 'process raft request' (duration: 1.948150888s)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:45.683569Z","caller":"traceutil/trace.go:171","msg":"trace[1551396252] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.803194656s","start":"2026-05-07T09:18:43.880341Z","end":"2026-05-07T09:18:45.683536Z","steps":["trace[1551396252] 'process raft request' (duration: 1.802561011s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:45.683614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.73471Z","time spent":"1.948879775s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:18:45.683645Z","caller":"traceutil/trace.go:171","msg":"trace[624085941] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"887.772994ms","start":"2026-05-07T09:18:44.795843Z","end":"2026-05-07T09:18:45.683616Z","steps":["trace[624085941] 'process raft request' (duration: 887.087238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:45.683725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.880327Z","time spent":"1.803316348s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:18:45.683744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:44.795815Z","time spent":"887.872817ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:18:45.684051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:43.734532Z","time spent":"1.948947027s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:18:45.689406Z","caller":"traceutil/trace.go:171","msg":"trace[1534857513] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"783.92174ms","start":"2026-05-07T09:18:44.905472Z","end":"2026-05-07T09:18:45.689393Z","steps":["trace[1534857513] 'process raft request' (duration: 783.814818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:45.689472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:44.905455Z","time spent":"783.981692ms","remote":"127.0.0.1:52144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:18:47.800265Z","caller":"traceutil/trace.go:171","msg":"trace[1740476922] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"112.758432ms","start":"2026-05-07T09:18:47.68749Z","end":"2026-05-07T09:18:47.800248Z","steps":["trace[1740476922] 'read index received' (duration: 112.568057ms)","trace[1740476922] 'applied index is now lower than readState.Index' (duration: 189.445µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:18:47.800311Z","caller":"traceutil/trace.go:171","msg":"trace[1144814638] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"112.951897ms","start":"2026-05-07T09:18:47.687339Z","end":"2026-05-07T09:18:47.800291Z","steps":["trace[1144814638] 'process raft request' (duration: 112.738742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:47.800432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.923066ms","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-05-07T09:18:47.800491Z","caller":"traceutil/trace.go:171","msg":"trace[1900702622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"113.188643ms","start":"2026-05-07T09:18:47.687264Z","end":"2026-05-07T09:18:47.800452Z","steps":["trace[1900702622] 'agreement among raft nodes before linearized reading' (duration: 113.07055ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:48.088301Z","caller":"traceutil/trace.go:171","msg":"trace[126302612] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"396.872805ms","start":"2026-05-07T09:18:47.691408Z","end":"2026-05-07T09:18:48.088281Z","steps":["trace[126302612] 'process raft request' (duration: 396.811033ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:48.088373Z","caller":"traceutil/trace.go:171","msg":"trace[1517778080] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"397.08627ms","start":"2026-05-07T09:18:47.691237Z","end":"2026-05-07T09:18:48.088323Z","steps":["trace[1517778080] 'process raft request' (duration: 396.863855ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:48.088463Z","caller":"traceutil/trace.go:171","msg":"trace[57445597] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"290.282107ms","start":"2026-05-07T09:18:47.798173Z","end":"2026-05-07T09:18:48.088456Z","steps":["trace[57445597] 'process raft request' (duration: 290.072942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:48.08842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:47.69137Z","time spent":"396.991008ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:18:48.088589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.379432ms","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-05-07T09:18:48.088624Z","caller":"traceutil/trace.go:171","msg":"trace[106247549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1022; }","duration":"348.468645ms","start":"2026-05-07T09:18:47.740145Z","end":"2026-05-07T09:18:48.088614Z","steps":["trace[106247549] 'agreement among raft nodes before linearized reading' (duration: 348.342082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:48.088667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:47.74013Z","time spent":"348.531816ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T09:18:48.088692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.52313ms","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":"warn","ts":"2026-05-07T09:18:48.088689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:47.691223Z","time spent":"397.264194ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:18:48.088715Z","caller":"traceutil/trace.go:171","msg":"trace[120888058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1022; }","duration":"400.575831ms","start":"2026-05-07T09:18:47.688132Z","end":"2026-05-07T09:18:48.088708Z","steps":["trace[120888058] 'agreement among raft nodes before linearized reading' (duration: 400.495519ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:48.088457Z","caller":"traceutil/trace.go:171","msg":"trace[596265484] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"288.119657ms","start":"2026-05-07T09:18:47.80032Z","end":"2026-05-07T09:18:48.088439Z","steps":["trace[596265484] 'read index received' (duration: 287.926882ms)","trace[596265484] 'applied index is now lower than readState.Index' (duration: 191.004µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:18:48.088742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:47.688117Z","time spent":"400.621442ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T09:18:48.153989Z","caller":"traceutil/trace.go:171","msg":"trace[2044766689] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"348.939177ms","start":"2026-05-07T09:18:47.805028Z","end":"2026-05-07T09:18:48.153967Z","steps":["trace[2044766689] 'process raft request' (duration: 348.826384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:48.154036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.034327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:18:48.15408Z","caller":"traceutil/trace.go:171","msg":"trace[803437942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"280.15055ms","start":"2026-05-07T09:18:47.87392Z","end":"2026-05-07T09:18:48.15407Z","steps":["trace[803437942] 'agreement among raft nodes before linearized reading' (duration: 280.040787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:48.154116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:18:47.805003Z","time spent":"349.051909ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:18:48.223362Z","caller":"traceutil/trace.go:171","msg":"trace[676632793] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"130.830775ms","start":"2026-05-07T09:18:48.092504Z","end":"2026-05-07T09:18:48.223335Z","steps":["trace[676632793] 'process raft request' (duration: 128.741726ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:48.223472Z","caller":"traceutil/trace.go:171","msg":"trace[565280909] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"130.66984ms","start":"2026-05-07T09:18:48.092723Z","end":"2026-05-07T09:18:48.223393Z","steps":["trace[565280909] 'process raft request' (duration: 130.498696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:18:54.013076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.967128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:18:54.013157Z","caller":"traceutil/trace.go:171","msg":"trace[215440191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"140.095911ms","start":"2026-05-07T09:18:53.873044Z","end":"2026-05-07T09:18:54.01314Z","steps":["trace[215440191] 'range keys from in-memory index tree' (duration: 139.894096ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:56.331671Z","caller":"traceutil/trace.go:171","msg":"trace[1750528502] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"117.673077ms","start":"2026-05-07T09:18:56.21397Z","end":"2026-05-07T09:18:56.331643Z","steps":["trace[1750528502] 'process raft request' (duration: 117.497723ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:18:56.439002Z","caller":"traceutil/trace.go:171","msg":"trace[205531981] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"103.431165ms","start":"2026-05-07T09:18:56.33555Z","end":"2026-05-07T09:18:56.438981Z","steps":["trace[205531981] 'process raft request' (duration: 79.797393ms)","trace[205531981] 'compare' (duration: 23.54049ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:18:58.284126Z","caller":"traceutil/trace.go:171","msg":"trace[71035615] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"148.031625ms","start":"2026-05-07T09:18:58.136075Z","end":"2026-05-07T09:18:58.284107Z","steps":["trace[71035615] 'process raft request' (duration: 147.917913ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:00.046846Z","caller":"traceutil/trace.go:171","msg":"trace[264195373] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"107.353846ms","start":"2026-05-07T09:18:59.939475Z","end":"2026-05-07T09:19:00.046829Z","steps":["trace[264195373] 'process raft request' (duration: 107.242653ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:00.393466Z","caller":"traceutil/trace.go:171","msg":"trace[555582529] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"101.665643ms","start":"2026-05-07T09:19:00.291774Z","end":"2026-05-07T09:19:00.393439Z","steps":["trace[555582529] 'process raft request' (duration: 101.207512ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:06.331141Z","caller":"traceutil/trace.go:171","msg":"trace[2085651526] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"113.14264ms","start":"2026-05-07T09:19:06.217963Z","end":"2026-05-07T09:19:06.331106Z","steps":["trace[2085651526] 'process raft request' (duration: 112.873224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.676936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170463097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T09:19:07.677003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.634894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T09:19:07.677051Z","caller":"traceutil/trace.go:171","msg":"trace[1257720945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"1.17061694s","start":"2026-05-07T09:19:06.506406Z","end":"2026-05-07T09:19:07.677023Z","steps":["trace[1257720945] 'range keys from in-memory index tree' (duration: 1.170164869s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.677035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172493983s","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-05-07T09:19:07.677142Z","caller":"traceutil/trace.go:171","msg":"trace[1446318397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"1.172635567s","start":"2026-05-07T09:19:06.504497Z","end":"2026-05-07T09:19:07.677133Z","steps":["trace[1446318397] 'range keys from in-memory index tree' (duration: 1.172365961s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.677162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:06.505738Z","time spent":"1.171410928s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T09:19:07.67718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:06.504475Z","time spent":"1.172695319s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T09:19:07.677207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112228718s","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-05-07T09:19:07.677101Z","caller":"traceutil/trace.go:171","msg":"trace[1791596104] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1080; }","duration":"997.804238ms","start":"2026-05-07T09:19:06.679274Z","end":"2026-05-07T09:19:07.677078Z","steps":["trace[1791596104] 'count revisions from in-memory index tree' (duration: 997.525022ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:07.677279Z","caller":"traceutil/trace.go:171","msg":"trace[306514754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"1.112914154s","start":"2026-05-07T09:19:06.564347Z","end":"2026-05-07T09:19:07.677261Z","steps":["trace[306514754] 'range keys from in-memory index tree' (duration: 1.112007572s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.677283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:06.679248Z","time spent":"998.021193ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:07.67732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:06.564332Z","time spent":"1.112980056s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T09:19:07.677596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.319141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:07.677627Z","caller":"traceutil/trace.go:171","msg":"trace[1964731072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"801.393053ms","start":"2026-05-07T09:19:06.876224Z","end":"2026-05-07T09:19:07.677617Z","steps":["trace[1964731072] 'range keys from in-memory index tree' (duration: 801.214169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.677652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:06.876195Z","time spent":"801.450385ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T09:19:07.677772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.727093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T09:19:07.677802Z","caller":"traceutil/trace.go:171","msg":"trace[1509090587] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1080; }","duration":"604.800765ms","start":"2026-05-07T09:19:07.072992Z","end":"2026-05-07T09:19:07.677793Z","steps":["trace[1509090587] 'count revisions from in-memory index tree' (duration: 604.623681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.677826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:07.072968Z","time spent":"604.849577ms","remote":"127.0.0.1:52488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:07.678311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.033317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:07.678374Z","caller":"traceutil/trace.go:171","msg":"trace[768004921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1080; }","duration":"475.153569ms","start":"2026-05-07T09:19:07.203205Z","end":"2026-05-07T09:19:07.678358Z","steps":["trace[768004921] 'count revisions from in-memory index tree' (duration: 474.918614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.678424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:07.203171Z","time spent":"475.237321ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:07.678866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258638615s","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-05-07T09:19:07.679712Z","caller":"traceutil/trace.go:171","msg":"trace[1305214475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"1.261423539s","start":"2026-05-07T09:19:06.418231Z","end":"2026-05-07T09:19:07.679655Z","steps":["trace[1305214475] 'range keys from in-memory index tree' (duration: 1.258446941s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.678509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.163513ms","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-05-07T09:19:07.68018Z","caller":"traceutil/trace.go:171","msg":"trace[1147033078] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1080; }","duration":"435.893993ms","start":"2026-05-07T09:19:07.244269Z","end":"2026-05-07T09:19:07.680163Z","steps":["trace[1147033078] 'count revisions from in-memory index tree' (duration: 434.000589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:07.680249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:07.244225Z","time spent":"436.006006ms","remote":"127.0.0.1:52334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:07.680372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:06.418211Z","time spent":"1.261864449s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T09:19:08.002052Z","caller":"traceutil/trace.go:171","msg":"trace[173154614] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"319.707881ms","start":"2026-05-07T09:19:07.68232Z","end":"2026-05-07T09:19:08.002028Z","steps":["trace[173154614] 'process raft request' (duration: 319.568878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:08.002199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:07.682304Z","time spent":"319.821125ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:08.060851Z","caller":"traceutil/trace.go:171","msg":"trace[343366369] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"377.119292ms","start":"2026-05-07T09:19:07.683716Z","end":"2026-05-07T09:19:08.060836Z","steps":["trace[343366369] 'process raft request' (duration: 376.981829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:08.06099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:07.683708Z","time spent":"377.204324ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:08.060838Z","caller":"traceutil/trace.go:171","msg":"trace[1405994967] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"377.387748ms","start":"2026-05-07T09:19:07.683417Z","end":"2026-05-07T09:19:08.060804Z","steps":["trace[1405994967] 'process raft request' (duration: 377.05792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:08.061196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:07.683392Z","time spent":"377.701135ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:08.060597Z","caller":"traceutil/trace.go:171","msg":"trace[883813239] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"378.133315ms","start":"2026-05-07T09:19:07.682411Z","end":"2026-05-07T09:19:08.060544Z","steps":["trace[883813239] 'process raft request' (duration: 377.885429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:08.061466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:07.682382Z","time spent":"378.967175ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:09.554156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.33228ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-07T09:19:09.554241Z","caller":"traceutil/trace.go:171","msg":"trace[1313439306] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"680.496761ms","start":"2026-05-07T09:19:08.873729Z","end":"2026-05-07T09:19:09.554226Z","steps":["trace[1313439306] 'read index received' (duration: 287.010167ms)","trace[1313439306] 'applied index is now lower than readState.Index' (duration: 393.485444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:09.55434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.602415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:09.554363Z","caller":"traceutil/trace.go:171","msg":"trace[1330741879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"680.664506ms","start":"2026-05-07T09:19:08.873692Z","end":"2026-05-07T09:19:09.554357Z","steps":["trace[1330741879] 'agreement among raft nodes before linearized reading' (duration: 680.612394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:09.554385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:08.873672Z","time spent":"680.708017ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T09:19:09.554546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.758903ms","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-05-07T09:19:09.554637Z","caller":"traceutil/trace.go:171","msg":"trace[444854399] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1087; }","duration":"214.900916ms","start":"2026-05-07T09:19:09.339711Z","end":"2026-05-07T09:19:09.554612Z","steps":["trace[444854399] 'agreement among raft nodes before linearized reading' (duration: 214.747693ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:10.184418Z","caller":"traceutil/trace.go:171","msg":"trace[171860608] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1132; }","duration":"120.332839ms","start":"2026-05-07T09:19:10.064064Z","end":"2026-05-07T09:19:10.184397Z","steps":["trace[171860608] 'read index received' (duration: 120.212606ms)","trace[171860608] 'applied index is now lower than readState.Index' (duration: 119.053µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:10.184589Z","caller":"traceutil/trace.go:171","msg":"trace[765638256] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"174.118713ms","start":"2026-05-07T09:19:10.010443Z","end":"2026-05-07T09:19:10.184562Z","steps":["trace[765638256] 'process raft request' (duration: 173.773985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:10.184718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.644405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T09:19:10.18476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.869425ms","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-05-07T09:19:10.184778Z","caller":"traceutil/trace.go:171","msg":"trace[695511409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"120.748838ms","start":"2026-05-07T09:19:10.064012Z","end":"2026-05-07T09:19:10.184761Z","steps":["trace[695511409] 'agreement among raft nodes before linearized reading' (duration: 120.517233ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:10.184811Z","caller":"traceutil/trace.go:171","msg":"trace[1383303156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"118.942866ms","start":"2026-05-07T09:19:10.065856Z","end":"2026-05-07T09:19:10.184799Z","steps":["trace[1383303156] 'agreement among raft nodes before linearized reading' (duration: 118.779422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:10.184954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.031018ms","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-05-07T09:19:10.185057Z","caller":"traceutil/trace.go:171","msg":"trace[1426103999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"119.172622ms","start":"2026-05-07T09:19:10.065863Z","end":"2026-05-07T09:19:10.185035Z","steps":["trace[1426103999] 'agreement among raft nodes before linearized reading' (duration: 118.907495ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:10.387344Z","caller":"traceutil/trace.go:171","msg":"trace[1059064476] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"196.512196ms","start":"2026-05-07T09:19:10.190802Z","end":"2026-05-07T09:19:10.387314Z","steps":["trace[1059064476] 'process raft request' (duration: 196.207329ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:10.387647Z","caller":"traceutil/trace.go:171","msg":"trace[1624170621] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"169.844453ms","start":"2026-05-07T09:19:10.217702Z","end":"2026-05-07T09:19:10.387546Z","steps":["trace[1624170621] 'process raft request' (duration: 169.738651ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:10.387504Z","caller":"traceutil/trace.go:171","msg":"trace[226714989] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1134; }","duration":"113.711164ms","start":"2026-05-07T09:19:10.273771Z","end":"2026-05-07T09:19:10.387482Z","steps":["trace[226714989] 'read index received' (duration: 113.174921ms)","trace[226714989] 'applied index is now lower than readState.Index' (duration: 535.393µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:10.387856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.003701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T09:19:10.387993Z","caller":"traceutil/trace.go:171","msg":"trace[1183331723] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1092; }","duration":"114.248766ms","start":"2026-05-07T09:19:10.27372Z","end":"2026-05-07T09:19:10.387968Z","steps":["trace[1183331723] 'agreement among raft nodes before linearized reading' (duration: 113.96487ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:10.388155Z","caller":"traceutil/trace.go:171","msg":"trace[1121915862] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"196.421724ms","start":"2026-05-07T09:19:10.191122Z","end":"2026-05-07T09:19:10.387543Z","steps":["trace[1121915862] 'process raft request' (duration: 196.030425ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:12.47483Z","caller":"traceutil/trace.go:171","msg":"trace[1175099258] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1138; }","duration":"217.975718ms","start":"2026-05-07T09:19:12.256828Z","end":"2026-05-07T09:19:12.474803Z","steps":["trace[1175099258] 'read index received' (duration: 217.857845ms)","trace[1175099258] 'applied index is now lower than readState.Index' (duration: 116.633µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:12.47495Z","caller":"traceutil/trace.go:171","msg":"trace[1873540233] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"280.785634ms","start":"2026-05-07T09:19:12.194153Z","end":"2026-05-07T09:19:12.474939Z","steps":["trace[1873540233] 'process raft request' (duration: 280.482837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:12.475325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.474729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T09:19:12.475379Z","caller":"traceutil/trace.go:171","msg":"trace[1483799650] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"218.591112ms","start":"2026-05-07T09:19:12.256776Z","end":"2026-05-07T09:19:12.475367Z","steps":["trace[1483799650] 'agreement among raft nodes before linearized reading' (duration: 218.182233ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:12.662258Z","caller":"traceutil/trace.go:171","msg":"trace[751051812] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"187.325022ms","start":"2026-05-07T09:19:12.474907Z","end":"2026-05-07T09:19:12.662232Z","steps":["trace[751051812] 'read index received' (duration: 187.23696ms)","trace[751051812] 'applied index is now lower than readState.Index' (duration: 86.882µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:12.662376Z","caller":"traceutil/trace.go:171","msg":"trace[1013208552] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"267.816941ms","start":"2026-05-07T09:19:12.394527Z","end":"2026-05-07T09:19:12.662344Z","steps":["trace[1013208552] 'process raft request' (duration: 267.554025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:12.662424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.427192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T09:19:12.662461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.96186ms","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-05-07T09:19:12.662481Z","caller":"traceutil/trace.go:171","msg":"trace[2076216652] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1095; }","duration":"321.537344ms","start":"2026-05-07T09:19:12.340926Z","end":"2026-05-07T09:19:12.662463Z","steps":["trace[2076216652] 'agreement among raft nodes before linearized reading' (duration: 321.409541ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:12.662525Z","caller":"traceutil/trace.go:171","msg":"trace[2051104326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"237.054273ms","start":"2026-05-07T09:19:12.425457Z","end":"2026-05-07T09:19:12.662511Z","steps":["trace[2051104326] 'agreement among raft nodes before linearized reading' (duration: 236.790176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:12.662545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:12.340909Z","time spent":"321.620107ms","remote":"127.0.0.1:55722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:12.662679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.634193ms","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-05-07T09:19:12.662762Z","caller":"traceutil/trace.go:171","msg":"trace[869354003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"269.756176ms","start":"2026-05-07T09:19:12.392989Z","end":"2026-05-07T09:19:12.662745Z","steps":["trace[869354003] 'agreement among raft nodes before linearized reading' (duration: 269.465599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:12.66327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.855348ms","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-05-07T09:19:12.663353Z","caller":"traceutil/trace.go:171","msg":"trace[837195237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"269.965021ms","start":"2026-05-07T09:19:12.393373Z","end":"2026-05-07T09:19:12.663338Z","steps":["trace[837195237] 'agreement among raft nodes before linearized reading' (duration: 268.926667ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:12.71303Z","caller":"traceutil/trace.go:171","msg":"trace[215153859] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"233.190952ms","start":"2026-05-07T09:19:12.479819Z","end":"2026-05-07T09:19:12.71301Z","steps":["trace[215153859] 'process raft request' (duration: 233.064979ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:12.823153Z","caller":"traceutil/trace.go:171","msg":"trace[1770148408] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"156.296808ms","start":"2026-05-07T09:19:12.666824Z","end":"2026-05-07T09:19:12.82312Z","steps":["trace[1770148408] 'process raft request' (duration: 139.572618ms)","trace[1770148408] 'compare' (duration: 16.351111ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:12.823541Z","caller":"traceutil/trace.go:171","msg":"trace[1431778353] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"156.295328ms","start":"2026-05-07T09:19:12.667229Z","end":"2026-05-07T09:19:12.823525Z","steps":["trace[1431778353] 'process raft request' (duration: 155.822076ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:12.823458Z","caller":"traceutil/trace.go:171","msg":"trace[2071733682] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"155.92512ms","start":"2026-05-07T09:19:12.667305Z","end":"2026-05-07T09:19:12.82323Z","steps":["trace[2071733682] 'process raft request' (duration: 155.867468ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:16.837305Z","caller":"traceutil/trace.go:171","msg":"trace[2013879524] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"296.106272ms","start":"2026-05-07T09:19:16.54118Z","end":"2026-05-07T09:19:16.837286Z","steps":["trace[2013879524] 'process raft request' (duration: 295.995629ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:16.842819Z","caller":"traceutil/trace.go:171","msg":"trace[73574592] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"117.41371ms","start":"2026-05-07T09:19:16.725388Z","end":"2026-05-07T09:19:16.842801Z","steps":["trace[73574592] 'read index received' (duration: 111.921632ms)","trace[73574592] 'applied index is now lower than readState.Index' (duration: 5.491248ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:16.842919Z","caller":"traceutil/trace.go:171","msg":"trace[938571438] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"168.110994ms","start":"2026-05-07T09:19:16.67479Z","end":"2026-05-07T09:19:16.842901Z","steps":["trace[938571438] 'process raft request' (duration: 167.894599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:16.843019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.599275ms","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-05-07T09:19:16.843046Z","caller":"traceutil/trace.go:171","msg":"trace[1758762913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1109; }","duration":"117.693097ms","start":"2026-05-07T09:19:16.725346Z","end":"2026-05-07T09:19:16.843039Z","steps":["trace[1758762913] 'agreement among raft nodes before linearized reading' (duration: 117.546553ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:17.015002Z","caller":"traceutil/trace.go:171","msg":"trace[546381941] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"168.104344ms","start":"2026-05-07T09:19:16.846868Z","end":"2026-05-07T09:19:17.014973Z","steps":["trace[546381941] 'process raft request' (duration: 94.724361ms)","trace[546381941] 'compare' (duration: 73.25974ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:17.016911Z","caller":"traceutil/trace.go:171","msg":"trace[2145754712] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"169.279262ms","start":"2026-05-07T09:19:16.847609Z","end":"2026-05-07T09:19:17.016888Z","steps":["trace[2145754712] 'process raft request' (duration: 169.116848ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:17.01717Z","caller":"traceutil/trace.go:171","msg":"trace[811033561] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1155; }","duration":"143.708614ms","start":"2026-05-07T09:19:16.873444Z","end":"2026-05-07T09:19:17.017153Z","steps":["trace[811033561] 'read index received' (duration: 68.13134ms)","trace[811033561] 'applied index is now lower than readState.Index' (duration: 75.576304ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:17.017169Z","caller":"traceutil/trace.go:171","msg":"trace[249016312] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"169.135548ms","start":"2026-05-07T09:19:16.848017Z","end":"2026-05-07T09:19:17.017152Z","steps":["trace[249016312] 'process raft request' (duration: 168.835471ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:17.017211Z","caller":"traceutil/trace.go:171","msg":"trace[1604282398] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"168.805901ms","start":"2026-05-07T09:19:16.848377Z","end":"2026-05-07T09:19:17.017183Z","steps":["trace[1604282398] 'process raft request' (duration: 168.705538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:17.017278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.816197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:17.01731Z","caller":"traceutil/trace.go:171","msg":"trace[827330697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"143.898568ms","start":"2026-05-07T09:19:16.873402Z","end":"2026-05-07T09:19:17.017301Z","steps":["trace[827330697] 'agreement among raft nodes before linearized reading' (duration: 143.814326ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:18.733018Z","caller":"traceutil/trace.go:171","msg":"trace[169775026] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"140.625552ms","start":"2026-05-07T09:19:18.592371Z","end":"2026-05-07T09:19:18.732997Z","steps":["trace[169775026] 'process raft request' (duration: 140.475529ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:18.975244Z","caller":"traceutil/trace.go:171","msg":"trace[2129271744] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"127.001114ms","start":"2026-05-07T09:19:18.848219Z","end":"2026-05-07T09:19:18.97522Z","steps":["trace[2129271744] 'process raft request' (duration: 126.638726ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:18.975465Z","caller":"traceutil/trace.go:171","msg":"trace[1323736746] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"124.393444ms","start":"2026-05-07T09:19:18.850789Z","end":"2026-05-07T09:19:18.975182Z","steps":["trace[1323736746] 'read index received' (duration: 124.059696ms)","trace[1323736746] 'applied index is now lower than readState.Index' (duration: 332.777µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:18.975601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.794462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T09:19:18.97568Z","caller":"traceutil/trace.go:171","msg":"trace[1069676638] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1116; }","duration":"124.936126ms","start":"2026-05-07T09:19:18.850726Z","end":"2026-05-07T09:19:18.975663Z","steps":["trace[1069676638] 'agreement among raft nodes before linearized reading' (duration: 124.735391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:18.975656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.632018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:18.97585Z","caller":"traceutil/trace.go:171","msg":"trace[641478444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"100.878315ms","start":"2026-05-07T09:19:18.874961Z","end":"2026-05-07T09:19:18.975839Z","steps":["trace[641478444] 'agreement among raft nodes before linearized reading' (duration: 100.614499ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:19.194999Z","caller":"traceutil/trace.go:171","msg":"trace[1970550386] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"170.11384ms","start":"2026-05-07T09:19:19.024854Z","end":"2026-05-07T09:19:19.194968Z","steps":["trace[1970550386] 'process raft request' (duration: 74.051738ms)","trace[1970550386] 'compare' (duration: 95.934989ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:19.200683Z","caller":"traceutil/trace.go:171","msg":"trace[648160988] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"174.536724ms","start":"2026-05-07T09:19:19.026084Z","end":"2026-05-07T09:19:19.200621Z","steps":["trace[648160988] 'process raft request' (duration: 174.492013ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:19.200677Z","caller":"traceutil/trace.go:171","msg":"trace[1093526263] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"174.77035ms","start":"2026-05-07T09:19:19.025863Z","end":"2026-05-07T09:19:19.200633Z","steps":["trace[1093526263] 'process raft request' (duration: 174.653177ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:19.20067Z","caller":"traceutil/trace.go:171","msg":"trace[1896726863] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"174.853621ms","start":"2026-05-07T09:19:19.02576Z","end":"2026-05-07T09:19:19.200613Z","steps":["trace[1896726863] 'process raft request' (duration: 174.667887ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:24.907471Z","caller":"traceutil/trace.go:171","msg":"trace[2007503143] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"149.083639ms","start":"2026-05-07T09:19:24.758366Z","end":"2026-05-07T09:19:24.90745Z","steps":["trace[2007503143] 'process raft request' (duration: 148.373453ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:25.133285Z","caller":"traceutil/trace.go:171","msg":"trace[669163219] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"144.733358ms","start":"2026-05-07T09:19:24.98853Z","end":"2026-05-07T09:19:25.133263Z","steps":["trace[669163219] 'process raft request' (duration: 144.591835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:25.434362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.662081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T09:19:25.434422Z","caller":"traceutil/trace.go:171","msg":"trace[535917607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"211.749813ms","start":"2026-05-07T09:19:25.222661Z","end":"2026-05-07T09:19:25.434411Z","steps":["trace[535917607] 'range keys from in-memory index tree' (duration: 211.532858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:25.434626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.712792ms","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-05-07T09:19:25.434644Z","caller":"traceutil/trace.go:171","msg":"trace[612668735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1186; }","duration":"220.754113ms","start":"2026-05-07T09:19:25.213885Z","end":"2026-05-07T09:19:25.434639Z","steps":["trace[612668735] 'range keys from in-memory index tree' (duration: 220.6316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:25.434731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.965144ms","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-05-07T09:19:25.434748Z","caller":"traceutil/trace.go:171","msg":"trace[1590069262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1186; }","duration":"214.011295ms","start":"2026-05-07T09:19:25.220732Z","end":"2026-05-07T09:19:25.434743Z","steps":["trace[1590069262] 'range keys from in-memory index tree' (duration: 213.886202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:25.435294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.589502ms","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-05-07T09:19:25.43531Z","caller":"traceutil/trace.go:171","msg":"trace[1536546495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1186; }","duration":"212.612613ms","start":"2026-05-07T09:19:25.222694Z","end":"2026-05-07T09:19:25.435306Z","steps":["trace[1536546495] 'range keys from in-memory index tree' (duration: 212.558082ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:25.551282Z","caller":"traceutil/trace.go:171","msg":"trace[1455657066] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"112.983927ms","start":"2026-05-07T09:19:25.438281Z","end":"2026-05-07T09:19:25.551265Z","steps":["trace[1455657066] 'process raft request' (duration: 112.895195ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:25.55791Z","caller":"traceutil/trace.go:171","msg":"trace[806886132] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"119.358636ms","start":"2026-05-07T09:19:25.438543Z","end":"2026-05-07T09:19:25.557902Z","steps":["trace[806886132] 'process raft request' (duration: 119.288344ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:25.558148Z","caller":"traceutil/trace.go:171","msg":"trace[195347847] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"119.356515ms","start":"2026-05-07T09:19:25.438772Z","end":"2026-05-07T09:19:25.558128Z","steps":["trace[195347847] 'process raft request' (duration: 119.283384ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:25.558182Z","caller":"traceutil/trace.go:171","msg":"trace[2117486781] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"119.54939ms","start":"2026-05-07T09:19:25.438609Z","end":"2026-05-07T09:19:25.558158Z","steps":["trace[2117486781] 'process raft request' (duration: 119.274034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:27.894217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514991645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:19:28.394516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514991645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:19:28.894822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514991645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:19:28.902017Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.762914539s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T09:19:28.943969Z","caller":"traceutil/trace.go:171","msg":"trace[1438212616] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"1.804962381s","start":"2026-05-07T09:19:27.138984Z","end":"2026-05-07T09:19:28.943947Z","steps":["trace[1438212616] 'process raft request' (duration: 1.763274008s)","trace[1438212616] 'compare' (duration: 41.605821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:28.944681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:27.138955Z","time spent":"1.805063444s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:28.994248Z","caller":"traceutil/trace.go:171","msg":"trace[615837071] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1242; }","duration":"1.600250063s","start":"2026-05-07T09:19:27.393979Z","end":"2026-05-07T09:19:28.994229Z","steps":["trace[615837071] 'read index received' (duration: 1.50847357s)","trace[615837071] 'applied index is now lower than readState.Index' (duration: 91.774723ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:28.994379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600374215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:28.994443Z","caller":"traceutil/trace.go:171","msg":"trace[1679469913] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1193; }","duration":"1.600458597s","start":"2026-05-07T09:19:27.393969Z","end":"2026-05-07T09:19:28.994428Z","steps":["trace[1679469913] 'agreement among raft nodes before linearized reading' (duration: 1.600347885s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:28.994491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:27.393918Z","time spent":"1.600561549s","remote":"127.0.0.1:55758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:28.995134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440885893s","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-05-07T09:19:28.995205Z","caller":"traceutil/trace.go:171","msg":"trace[2104162155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"1.440979275s","start":"2026-05-07T09:19:27.554208Z","end":"2026-05-07T09:19:28.995187Z","steps":["trace[2104162155] 'agreement among raft nodes before linearized reading' (duration: 1.440817701s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:28.995263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:27.554191Z","time spent":"1.441057746s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T09:19:28.995507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434103724s","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-05-07T09:19:28.995535Z","caller":"traceutil/trace.go:171","msg":"trace[332419512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"1.434151736s","start":"2026-05-07T09:19:27.561375Z","end":"2026-05-07T09:19:28.995527Z","steps":["trace[332419512] 'agreement among raft nodes before linearized reading' (duration: 1.434069544s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:28.995555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:27.561366Z","time spent":"1.434184266s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T09:19:28.995576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435112728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T09:19:28.995125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120464184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:28.995633Z","caller":"traceutil/trace.go:171","msg":"trace[423610122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"1.43520464s","start":"2026-05-07T09:19:27.560413Z","end":"2026-05-07T09:19:28.995618Z","steps":["trace[423610122] 'agreement among raft nodes before linearized reading' (duration: 1.435068157s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:28.995674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:27.560374Z","time spent":"1.435289992s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T09:19:28.995662Z","caller":"traceutil/trace.go:171","msg":"trace[1473624963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"1.121566149s","start":"2026-05-07T09:19:27.874069Z","end":"2026-05-07T09:19:28.995635Z","steps":["trace[1473624963] 'agreement among raft nodes before linearized reading' (duration: 1.120986376s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:28.995707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434102125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T09:19:28.995743Z","caller":"traceutil/trace.go:171","msg":"trace[1547099475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"1.434150936s","start":"2026-05-07T09:19:27.56158Z","end":"2026-05-07T09:19:28.995731Z","steps":["trace[1547099475] 'agreement among raft nodes before linearized reading' (duration: 1.434052013s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:28.995742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:27.874041Z","time spent":"1.121672842s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T09:19:28.995764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:27.561574Z","time spent":"1.434185296s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T09:19:41.243651Z","caller":"traceutil/trace.go:171","msg":"trace[1636768962] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"187.211599ms","start":"2026-05-07T09:19:41.056422Z","end":"2026-05-07T09:19:41.243633Z","steps":["trace[1636768962] 'read index received' (duration: 187.062156ms)","trace[1636768962] 'applied index is now lower than readState.Index' (duration: 148.563µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:41.243797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.362413ms","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-05-07T09:19:41.243822Z","caller":"traceutil/trace.go:171","msg":"trace[1248715796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1233; }","duration":"187.425864ms","start":"2026-05-07T09:19:41.056388Z","end":"2026-05-07T09:19:41.243814Z","steps":["trace[1248715796] 'agreement among raft nodes before linearized reading' (duration: 187.329851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:41.243844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.299871ms","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-05-07T09:19:41.24389Z","caller":"traceutil/trace.go:171","msg":"trace[1591837399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"178.356762ms","start":"2026-05-07T09:19:41.065521Z","end":"2026-05-07T09:19:41.243878Z","steps":["trace[1591837399] 'agreement among raft nodes before linearized reading' (duration: 178.241619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:41.243951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.614808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-07T09:19:41.243968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.317638ms","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-05-07T09:19:41.244013Z","caller":"traceutil/trace.go:171","msg":"trace[1290529715] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1233; }","duration":"184.69418ms","start":"2026-05-07T09:19:41.059308Z","end":"2026-05-07T09:19:41.244002Z","steps":["trace[1290529715] 'agreement among raft nodes before linearized reading' (duration: 184.517476ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:41.244042Z","caller":"traceutil/trace.go:171","msg":"trace[1827437918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"180.43259ms","start":"2026-05-07T09:19:41.063593Z","end":"2026-05-07T09:19:41.244025Z","steps":["trace[1827437918] 'agreement among raft nodes before linearized reading' (duration: 180.183985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:41.244112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.97553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T09:19:41.244137Z","caller":"traceutil/trace.go:171","msg":"trace[1121466131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1233; }","duration":"186.019411ms","start":"2026-05-07T09:19:41.058111Z","end":"2026-05-07T09:19:41.244131Z","steps":["trace[1121466131] 'agreement among raft nodes before linearized reading' (duration: 185.655752ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:41.244202Z","caller":"traceutil/trace.go:171","msg":"trace[1220306059] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"260.5621ms","start":"2026-05-07T09:19:40.983155Z","end":"2026-05-07T09:19:41.243717Z","steps":["trace[1220306059] 'process raft request' (duration: 260.372756ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:43.539675Z","caller":"traceutil/trace.go:171","msg":"trace[1682218555] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"286.105638ms","start":"2026-05-07T09:19:43.25353Z","end":"2026-05-07T09:19:43.539636Z","steps":["trace[1682218555] 'read index received' (duration: 285.840102ms)","trace[1682218555] 'applied index is now lower than readState.Index' (duration: 264.696µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:43.540219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.282245ms","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-05-07T09:19:43.540292Z","caller":"traceutil/trace.go:171","msg":"trace[688868334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"284.416508ms","start":"2026-05-07T09:19:43.255856Z","end":"2026-05-07T09:19:43.540273Z","steps":["trace[688868334] 'agreement among raft nodes before linearized reading' (duration: 284.179023ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:43.540429Z","caller":"traceutil/trace.go:171","msg":"trace[2137615554] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"292.762844ms","start":"2026-05-07T09:19:43.247656Z","end":"2026-05-07T09:19:43.540419Z","steps":["trace[2137615554] 'process raft request' (duration: 291.859823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:43.540559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.02896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T09:19:43.540584Z","caller":"traceutil/trace.go:171","msg":"trace[1082389604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"287.079581ms","start":"2026-05-07T09:19:43.253497Z","end":"2026-05-07T09:19:43.540576Z","steps":["trace[1082389604] 'agreement among raft nodes before linearized reading' (duration: 286.998999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:43.54068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.443394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T09:19:43.540704Z","caller":"traceutil/trace.go:171","msg":"trace[1840520234] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:1257; }","duration":"163.477026ms","start":"2026-05-07T09:19:43.377221Z","end":"2026-05-07T09:19:43.540698Z","steps":["trace[1840520234] 'agreement among raft nodes before linearized reading' (duration: 163.388844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:43.540744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.814165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T09:19:43.540803Z","caller":"traceutil/trace.go:171","msg":"trace[384919317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"283.889326ms","start":"2026-05-07T09:19:43.256902Z","end":"2026-05-07T09:19:43.540791Z","steps":["trace[384919317] 'agreement among raft nodes before linearized reading' (duration: 283.691022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:43.540825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.299535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-07T09:19:43.540842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.248356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"warn","ts":"2026-05-07T09:19:43.540854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.158833ms","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-05-07T09:19:43.540938Z","caller":"traceutil/trace.go:171","msg":"trace[1643314761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"284.314666ms","start":"2026-05-07T09:19:43.256606Z","end":"2026-05-07T09:19:43.540921Z","steps":["trace[1643314761] 'agreement among raft nodes before linearized reading' (duration: 284.070121ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:43.540879Z","caller":"traceutil/trace.go:171","msg":"trace[1826726254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"284.374838ms","start":"2026-05-07T09:19:43.256491Z","end":"2026-05-07T09:19:43.540866Z","steps":["trace[1826726254] 'agreement among raft nodes before linearized reading' (duration: 284.209414ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:43.540881Z","caller":"traceutil/trace.go:171","msg":"trace[2059101539] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1257; }","duration":"168.315128ms","start":"2026-05-07T09:19:43.372556Z","end":"2026-05-07T09:19:43.540871Z","steps":["trace[2059101539] 'agreement among raft nodes before linearized reading' (duration: 168.222876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:44.027973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.345741ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-05-07T09:19:44.028072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.541851Z","time spent":"486.217069ms","remote":"127.0.0.1:52144","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-07T09:19:44.489388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.254228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T09:19:44.489627Z","caller":"traceutil/trace.go:171","msg":"trace[1896734040] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"460.240132ms","start":"2026-05-07T09:19:44.029372Z","end":"2026-05-07T09:19:44.489612Z","steps":["trace[1896734040] 'process raft request' (duration: 460.212072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:44.48967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:44.029352Z","time spent":"460.297694ms","remote":"127.0.0.1:52144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:44.489847Z","caller":"traceutil/trace.go:171","msg":"trace[1560914412] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"942.899299ms","start":"2026-05-07T09:19:43.546938Z","end":"2026-05-07T09:19:44.489837Z","steps":["trace[1560914412] 'process raft request' (duration: 942.596632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:44.489884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.546919Z","time spent":"942.94638ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:44.489857Z","caller":"traceutil/trace.go:171","msg":"trace[1105577773] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"944.484246ms","start":"2026-05-07T09:19:43.545344Z","end":"2026-05-07T09:19:44.489828Z","steps":["trace[1105577773] 'process raft request' (duration: 652.718405ms)","trace[1105577773] 'compare' (duration: 291.156456ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:44.489885Z","caller":"traceutil/trace.go:171","msg":"trace[574755395] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"944.464985ms","start":"2026-05-07T09:19:43.545393Z","end":"2026-05-07T09:19:44.489858Z","steps":["trace[574755395] 'process raft request' (duration: 944.063626ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:44.489958Z","caller":"traceutil/trace.go:171","msg":"trace[1545044080] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"943.122814ms","start":"2026-05-07T09:19:43.546829Z","end":"2026-05-07T09:19:44.489952Z","steps":["trace[1545044080] 'process raft request' (duration: 942.664234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:44.489987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.546823Z","time spent":"943.147835ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:44.490001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.545389Z","time spent":"944.548547ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:44.489994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.545323Z","time spent":"944.593379ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:44.490098Z","caller":"traceutil/trace.go:171","msg":"trace[403189798] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1311; }","duration":"616.169693ms","start":"2026-05-07T09:19:43.873921Z","end":"2026-05-07T09:19:44.49009Z","steps":["trace[403189798] 'read index received' (duration: 324.154347ms)","trace[403189798] 'applied index is now lower than readState.Index' (duration: 292.014646ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:44.490099Z","caller":"traceutil/trace.go:171","msg":"trace[1490212168] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"940.583394ms","start":"2026-05-07T09:19:43.549491Z","end":"2026-05-07T09:19:44.490074Z","steps":["trace[1490212168] 'process raft request' (duration: 940.064512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:44.490176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.254405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:44.490201Z","caller":"traceutil/trace.go:171","msg":"trace[110535660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"616.315006ms","start":"2026-05-07T09:19:43.87388Z","end":"2026-05-07T09:19:44.490195Z","steps":["trace[110535660] 'agreement among raft nodes before linearized reading' (duration: 616.274095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:44.49022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.873862Z","time spent":"616.353207ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T09:19:44.490237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.549483Z","time spent":"940.674027ms","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:44.49048Z","caller":"traceutil/trace.go:171","msg":"trace[1167580587] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"943.551584ms","start":"2026-05-07T09:19:43.546911Z","end":"2026-05-07T09:19:44.490462Z","steps":["trace[1167580587] 'process raft request' (duration: 942.602012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:44.490567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:43.546905Z","time spent":"943.620286ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:45.93742Z","caller":"traceutil/trace.go:171","msg":"trace[223713070] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"391.624001ms","start":"2026-05-07T09:19:45.545764Z","end":"2026-05-07T09:19:45.937388Z","steps":["trace[223713070] 'process raft request' (duration: 391.353244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:45.937588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:45.54574Z","time spent":"391.750964ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:48.493601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.76356ms","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-05-07T09:19:48.493691Z","caller":"traceutil/trace.go:171","msg":"trace[613695524] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1267; }","duration":"863.901883ms","start":"2026-05-07T09:19:47.62977Z","end":"2026-05-07T09:19:48.493672Z","steps":["trace[613695524] 'count revisions from in-memory index tree' (duration: 863.675829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.493731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:47.629744Z","time spent":"863.976286ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:48.493764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619740887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T09:19:48.493833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.996016119s","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-05-07T09:19:48.493897Z","caller":"traceutil/trace.go:171","msg":"trace[1531918534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"1.996135782s","start":"2026-05-07T09:19:46.497745Z","end":"2026-05-07T09:19:48.493881Z","steps":["trace[1531918534] 'range keys from in-memory index tree' (duration: 1.995844235s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.493945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:46.497722Z","time spent":"1.996214804s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T09:19:48.493904Z","caller":"traceutil/trace.go:171","msg":"trace[682491931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"1.619956241s","start":"2026-05-07T09:19:46.873907Z","end":"2026-05-07T09:19:48.493864Z","steps":["trace[682491931] 'range keys from in-memory index tree' (duration: 1.619656914s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.494139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997708259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-07T09:19:48.494191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:46.873886Z","time spent":"1.620295879s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T09:19:48.494208Z","caller":"traceutil/trace.go:171","msg":"trace[1837239202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"1.997825892s","start":"2026-05-07T09:19:46.496366Z","end":"2026-05-07T09:19:48.494192Z","steps":["trace[1837239202] 'range keys from in-memory index tree' (duration: 1.997613027s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.494246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:46.496346Z","time spent":"1.997891123s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T09:19:48.493904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99606471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-07T09:19:48.494304Z","caller":"traceutil/trace.go:171","msg":"trace[476218626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"1.996597643s","start":"2026-05-07T09:19:46.497696Z","end":"2026-05-07T09:19:48.494294Z","steps":["trace[476218626] 'range keys from in-memory index tree' (duration: 1.995921087s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.494333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:46.497686Z","time spent":"1.996639594s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T09:19:48.494405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997044243s","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-05-07T09:19:48.494431Z","caller":"traceutil/trace.go:171","msg":"trace[245209276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"1.997088544s","start":"2026-05-07T09:19:46.497334Z","end":"2026-05-07T09:19:48.494422Z","steps":["trace[245209276] 'range keys from in-memory index tree' (duration: 1.996827079s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.494455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:46.497323Z","time spent":"1.997125876s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T09:19:48.494575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997153435s","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-05-07T09:19:48.494666Z","caller":"traceutil/trace.go:171","msg":"trace[511397213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1267; }","duration":"1.997259688s","start":"2026-05-07T09:19:46.497398Z","end":"2026-05-07T09:19:48.494658Z","steps":["trace[511397213] 'range keys from in-memory index tree' (duration: 1.996951161s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.494708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:46.497393Z","time spent":"1.997293289s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T09:19:48.597349Z","caller":"traceutil/trace.go:171","msg":"trace[957731285] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"805.741506ms","start":"2026-05-07T09:19:47.791587Z","end":"2026-05-07T09:19:48.597329Z","steps":["trace[957731285] 'process raft request' (duration: 805.613013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:48.59749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:47.791563Z","time spent":"805.838429ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:49.199613Z","caller":"traceutil/trace.go:171","msg":"trace[1134717347] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"1.256131788s","start":"2026-05-07T09:19:47.943448Z","end":"2026-05-07T09:19:49.19958Z","steps":["trace[1134717347] 'process raft request' (duration: 1.255860862s)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:49.199657Z","caller":"traceutil/trace.go:171","msg":"trace[1858966073] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"782.445814ms","start":"2026-05-07T09:19:48.417182Z","end":"2026-05-07T09:19:49.199628Z","steps":["trace[1858966073] 'process raft request' (duration: 782.332641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.199755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:47.943424Z","time spent":"1.256259982s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:49.199844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.417159Z","time spent":"782.592166ms","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:49.200174Z","caller":"traceutil/trace.go:171","msg":"trace[1686999483] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"700.372968ms","start":"2026-05-07T09:19:48.499776Z","end":"2026-05-07T09:19:49.200149Z","steps":["trace[1686999483] 'process raft request' (duration: 699.807274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.20032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.499755Z","time spent":"700.49168ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:49.200458Z","caller":"traceutil/trace.go:171","msg":"trace[22528250] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"700.573683ms","start":"2026-05-07T09:19:48.499869Z","end":"2026-05-07T09:19:49.200443Z","steps":["trace[22528250] 'process raft request' (duration: 700.424379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.200539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.499863Z","time spent":"700.637764ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:49.200506Z","caller":"traceutil/trace.go:171","msg":"trace[1653276717] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"695.630966ms","start":"2026-05-07T09:19:48.504849Z","end":"2026-05-07T09:19:49.20048Z","steps":["trace[1653276717] 'process raft request' (duration: 695.484463ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:49.200517Z","caller":"traceutil/trace.go:171","msg":"trace[1999631408] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"694.814158ms","start":"2026-05-07T09:19:48.505681Z","end":"2026-05-07T09:19:49.200495Z","steps":["trace[1999631408] 'process raft request' (duration: 694.685805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.200628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.504839Z","time spent":"695.74572ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:49.200734Z","caller":"traceutil/trace.go:171","msg":"trace[1636341601] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"875.783063ms","start":"2026-05-07T09:19:48.324616Z","end":"2026-05-07T09:19:49.200399Z","steps":["trace[1636341601] 'read index received' (duration: 273.088135ms)","trace[1636341601] 'applied index is now lower than readState.Index' (duration: 602.693988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:49.200733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.505653Z","time spent":"694.976012ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:49.200742Z","caller":"traceutil/trace.go:171","msg":"trace[951122142] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"700.922771ms","start":"2026-05-07T09:19:48.49979Z","end":"2026-05-07T09:19:49.200712Z","steps":["trace[951122142] 'process raft request' (duration: 700.135672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.200816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.189612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T09:19:49.200881Z","caller":"traceutil/trace.go:171","msg":"trace[1329831628] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1275; }","duration":"876.279843ms","start":"2026-05-07T09:19:48.324582Z","end":"2026-05-07T09:19:49.200862Z","steps":["trace[1329831628] 'agreement among raft nodes before linearized reading' (duration: 876.177891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.200921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.324567Z","time spent":"876.341535ms","remote":"127.0.0.1:58496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:49.200824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.499768Z","time spent":"701.013872ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:49.34029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.101443ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-07T09:19:49.340611Z","caller":"traceutil/trace.go:171","msg":"trace[1293648786] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"139.818774ms","start":"2026-05-07T09:19:49.200765Z","end":"2026-05-07T09:19:49.340584Z","steps":["trace[1293648786] 'read index received' (duration: 13.360362ms)","trace[1293648786] 'applied index is now lower than readState.Index' (duration: 126.455651ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:49.340867Z","caller":"traceutil/trace.go:171","msg":"trace[935677665] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"134.489869ms","start":"2026-05-07T09:19:49.206367Z","end":"2026-05-07T09:19:49.340857Z","steps":["trace[935677665] 'process raft request' (duration: 134.022878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.521043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023527441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T09:19:49.521096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.451398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:49.521143Z","caller":"traceutil/trace.go:171","msg":"trace[1981998396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"1.023721435s","start":"2026-05-07T09:19:48.497401Z","end":"2026-05-07T09:19:49.521123Z","steps":["trace[1981998396] 'agreement among raft nodes before linearized reading' (duration: 843.386185ms)","trace[1981998396] 'range keys from in-memory index tree' (duration: 180.188237ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:49.521176Z","caller":"traceutil/trace.go:171","msg":"trace[1249719918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"158.593062ms","start":"2026-05-07T09:19:49.362563Z","end":"2026-05-07T09:19:49.521156Z","steps":["trace[1249719918] 'range keys from in-memory index tree' (duration: 158.388677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:49.521188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.497387Z","time spent":"1.023791977s","remote":"127.0.0.1:52122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T09:19:49.521232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.424615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T09:19:49.521257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.041564ms","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-05-07T09:19:49.521328Z","caller":"traceutil/trace.go:171","msg":"trace[1073660518] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1276; }","duration":"995.549068ms","start":"2026-05-07T09:19:48.52575Z","end":"2026-05-07T09:19:49.521299Z","steps":["trace[1073660518] 'agreement among raft nodes before linearized reading' (duration: 815.030084ms)","trace[1073660518] 'count revisions from in-memory index tree' (duration: 180.388201ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:49.521349Z","caller":"traceutil/trace.go:171","msg":"trace[306254082] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1276; }","duration":"336.221708ms","start":"2026-05-07T09:19:49.185097Z","end":"2026-05-07T09:19:49.521319Z","steps":["trace[306254082] 'agreement among raft nodes before linearized reading' (duration: 155.665894ms)","trace[306254082] 'count revisions from in-memory index tree' (duration: 180.406891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:49.521394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:48.525741Z","time spent":"995.63975ms","remote":"127.0.0.1:55762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:49.5214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:49.185077Z","time spent":"336.31243ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:49.521755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.596773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-07T09:19:49.521761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.986022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:49.521842Z","caller":"traceutil/trace.go:171","msg":"trace[902087143] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1276; }","duration":"240.108734ms","start":"2026-05-07T09:19:49.28172Z","end":"2026-05-07T09:19:49.521829Z","steps":["trace[902087143] 'agreement among raft nodes before linearized reading' (duration: 59.209522ms)","trace[902087143] 'range keys from in-memory index tree' (duration: 180.7982ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:49.521834Z","caller":"traceutil/trace.go:171","msg":"trace[848400411] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1276; }","duration":"288.499574ms","start":"2026-05-07T09:19:49.233295Z","end":"2026-05-07T09:19:49.521794Z","steps":["trace[848400411] 'agreement among raft nodes before linearized reading' (duration: 107.54837ms)","trace[848400411] 'count revisions from in-memory index tree' (duration: 180.073093ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:49.619106Z","caller":"traceutil/trace.go:171","msg":"trace[1949324574] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"245.49999ms","start":"2026-05-07T09:19:49.373582Z","end":"2026-05-07T09:19:49.619082Z","steps":["trace[1949324574] 'process raft request' (duration: 245.350557ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:49.65155Z","caller":"traceutil/trace.go:171","msg":"trace[1454625923] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"223.869065ms","start":"2026-05-07T09:19:49.427656Z","end":"2026-05-07T09:19:49.651525Z","steps":["trace[1454625923] 'process raft request' (duration: 217.701902ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:49.651636Z","caller":"traceutil/trace.go:171","msg":"trace[443727283] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1331; }","duration":"122.55904ms","start":"2026-05-07T09:19:49.528966Z","end":"2026-05-07T09:19:49.651525Z","steps":["trace[443727283] 'read index received' (duration: 89.97692ms)","trace[443727283] 'applied index is now lower than readState.Index' (duration: 32.58118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:49.651772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.787867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:49.651809Z","caller":"traceutil/trace.go:171","msg":"trace[1970746603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1279; }","duration":"122.867208ms","start":"2026-05-07T09:19:49.528933Z","end":"2026-05-07T09:19:49.6518Z","steps":["trace[1970746603] 'agreement among raft nodes before linearized reading' (duration: 122.769046ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:49.651951Z","caller":"traceutil/trace.go:171","msg":"trace[1355425871] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"125.659933ms","start":"2026-05-07T09:19:49.526279Z","end":"2026-05-07T09:19:49.651939Z","steps":["trace[1355425871] 'process raft request' (duration: 125.185902ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:50.32996Z","caller":"traceutil/trace.go:171","msg":"trace[1683133827] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"227.802047ms","start":"2026-05-07T09:19:50.102135Z","end":"2026-05-07T09:19:50.329937Z","steps":["trace[1683133827] 'read index received' (duration: 227.612652ms)","trace[1683133827] 'applied index is now lower than readState.Index' (duration: 188.475µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:50.330157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.002642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-07T09:19:50.330205Z","caller":"traceutil/trace.go:171","msg":"trace[985854244] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1282; }","duration":"228.067294ms","start":"2026-05-07T09:19:50.102129Z","end":"2026-05-07T09:19:50.330196Z","steps":["trace[985854244] 'agreement among raft nodes before linearized reading' (duration: 227.92025ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:50.330482Z","caller":"traceutil/trace.go:171","msg":"trace[283476724] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"659.673368ms","start":"2026-05-07T09:19:49.670562Z","end":"2026-05-07T09:19:50.330235Z","steps":["trace[283476724] 'process raft request' (duration: 659.230047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:50.330584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:49.670554Z","time spent":"659.976095ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:51.313836Z","caller":"traceutil/trace.go:171","msg":"trace[377596322] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"107.159351ms","start":"2026-05-07T09:19:51.206653Z","end":"2026-05-07T09:19:51.313812Z","steps":["trace[377596322] 'process raft request' (duration: 106.997477ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:51.554024Z","caller":"traceutil/trace.go:171","msg":"trace[682900145] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"345.432223ms","start":"2026-05-07T09:19:51.208563Z","end":"2026-05-07T09:19:51.553996Z","steps":["trace[682900145] 'process raft request' (duration: 345.101856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:51.554185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:51.20855Z","time spent":"345.545026ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:51.554263Z","caller":"traceutil/trace.go:171","msg":"trace[1550326903] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"344.647685ms","start":"2026-05-07T09:19:51.209587Z","end":"2026-05-07T09:19:51.554235Z","steps":["trace[1550326903] 'process raft request' (duration: 344.293077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:51.554418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:51.209574Z","time spent":"344.768657ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:51.554616Z","caller":"traceutil/trace.go:171","msg":"trace[51739753] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"344.590283ms","start":"2026-05-07T09:19:51.21001Z","end":"2026-05-07T09:19:51.5546Z","steps":["trace[51739753] 'process raft request' (duration: 344.552853ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:51.554695Z","caller":"traceutil/trace.go:171","msg":"trace[646012527] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"344.663615ms","start":"2026-05-07T09:19:51.210016Z","end":"2026-05-07T09:19:51.554679Z","steps":["trace[646012527] 'process raft request' (duration: 343.956989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:51.554707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:51.209992Z","time spent":"344.671706ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:51.554783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:51.210005Z","time spent":"344.736427ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:51.554541Z","caller":"traceutil/trace.go:171","msg":"trace[1473230206] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"344.798508ms","start":"2026-05-07T09:19:51.209708Z","end":"2026-05-07T09:19:51.554507Z","steps":["trace[1473230206] 'process raft request' (duration: 344.233195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:51.555031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:51.209688Z","time spent":"345.238959ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:53.424002Z","caller":"traceutil/trace.go:171","msg":"trace[1563892100] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"105.339569ms","start":"2026-05-07T09:19:53.318615Z","end":"2026-05-07T09:19:53.423954Z","steps":["trace[1563892100] 'process raft request' (duration: 105.162025ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:57.738782Z","caller":"traceutil/trace.go:171","msg":"trace[1607577785] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"153.781579ms","start":"2026-05-07T09:19:57.584974Z","end":"2026-05-07T09:19:57.738756Z","steps":["trace[1607577785] 'read index received' (duration: 153.737858ms)","trace[1607577785] 'applied index is now lower than readState.Index' (duration: 31.301µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:19:57.738808Z","caller":"traceutil/trace.go:171","msg":"trace[1920564684] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"304.144879ms","start":"2026-05-07T09:19:57.434617Z","end":"2026-05-07T09:19:57.738762Z","steps":["trace[1920564684] 'process raft request' (duration: 303.951504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:57.739021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.434589Z","time spent":"304.324064ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:57.739119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.696021ms","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-05-07T09:19:57.739176Z","caller":"traceutil/trace.go:171","msg":"trace[1342697537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"148.776442ms","start":"2026-05-07T09:19:57.590385Z","end":"2026-05-07T09:19:57.739162Z","steps":["trace[1342697537] 'agreement among raft nodes before linearized reading' (duration: 148.66378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:57.739223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.898986ms","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":"warn","ts":"2026-05-07T09:19:57.739122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.031445ms","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-05-07T09:19:57.739262Z","caller":"traceutil/trace.go:171","msg":"trace[304920949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"154.246271ms","start":"2026-05-07T09:19:57.585009Z","end":"2026-05-07T09:19:57.739255Z","steps":["trace[304920949] 'agreement among raft nodes before linearized reading' (duration: 153.947994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:57.739257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.725032ms","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":"warn","ts":"2026-05-07T09:19:57.73927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.2285ms","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-05-07T09:19:57.739313Z","caller":"traceutil/trace.go:171","msg":"trace[375173948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"148.825754ms","start":"2026-05-07T09:19:57.590474Z","end":"2026-05-07T09:19:57.7393Z","steps":["trace[375173948] 'agreement among raft nodes before linearized reading' (duration: 148.592338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:57.739346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.466248ms","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-05-07T09:19:57.739357Z","caller":"traceutil/trace.go:171","msg":"trace[2095897034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"154.402683ms","start":"2026-05-07T09:19:57.584932Z","end":"2026-05-07T09:19:57.739334Z","steps":["trace[2095897034] 'agreement among raft nodes before linearized reading' (duration: 153.933982ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:57.739269Z","caller":"traceutil/trace.go:171","msg":"trace[934788690] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1336; }","duration":"151.951376ms","start":"2026-05-07T09:19:57.587302Z","end":"2026-05-07T09:19:57.739253Z","steps":["trace[934788690] 'agreement among raft nodes before linearized reading' (duration: 151.855854ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:57.739379Z","caller":"traceutil/trace.go:171","msg":"trace[1722605839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"152.571931ms","start":"2026-05-07T09:19:57.586798Z","end":"2026-05-07T09:19:57.73937Z","steps":["trace[1722605839] 'agreement among raft nodes before linearized reading' (duration: 152.494019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:58.296302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514991938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:19:58.797101Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410230514991938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T09:19:59.215105Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.47039088s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T09:19:59.215678Z","caller":"traceutil/trace.go:171","msg":"trace[933563891] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"1.471085386s","start":"2026-05-07T09:19:57.744566Z","end":"2026-05-07T09:19:59.215652Z","steps":["trace[933563891] 'process raft request' (duration: 1.470954943s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.215814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.744541Z","time spent":"1.471196659s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:59.222706Z","caller":"traceutil/trace.go:171","msg":"trace[748268114] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.477816963s","start":"2026-05-07T09:19:57.744871Z","end":"2026-05-07T09:19:59.222688Z","steps":["trace[748268114] 'process raft request' (duration: 1.477761012s)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:59.2227Z","caller":"traceutil/trace.go:171","msg":"trace[272089148] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"1.477850414s","start":"2026-05-07T09:19:57.744827Z","end":"2026-05-07T09:19:59.222677Z","steps":["trace[272089148] 'process raft request' (duration: 1.47770453s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.22281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.744842Z","time spent":"1.477903555s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:19:59.222908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.7448Z","time spent":"1.478011718s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:59.222944Z","caller":"traceutil/trace.go:171","msg":"trace[72251919] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.477966787s","start":"2026-05-07T09:19:57.744969Z","end":"2026-05-07T09:19:59.222936Z","steps":["trace[72251919] 'process raft request' (duration: 1.47769214s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.223108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.744944Z","time spent":"1.478130481s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:59.223382Z","caller":"traceutil/trace.go:171","msg":"trace[1853712837] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.478352116s","start":"2026-05-07T09:19:57.745017Z","end":"2026-05-07T09:19:59.223369Z","steps":["trace[1853712837] 'process raft request' (duration: 1.477890645s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.223634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.745Z","time spent":"1.478573191s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:59.223418Z","caller":"traceutil/trace.go:171","msg":"trace[1816390345] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"248.53796ms","start":"2026-05-07T09:19:58.974874Z","end":"2026-05-07T09:19:59.223412Z","steps":["trace[1816390345] 'process raft request' (duration: 248.145491ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:19:59.223399Z","caller":"traceutil/trace.go:171","msg":"trace[1527371344] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.47810399s","start":"2026-05-07T09:19:57.745264Z","end":"2026-05-07T09:19:59.223368Z","steps":["trace[1527371344] 'process raft request' (duration: 1.477734491s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.223788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.745257Z","time spent":"1.478497449s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:19:59.23331Z","caller":"traceutil/trace.go:171","msg":"trace[853339402] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1392; }","duration":"1.437502692s","start":"2026-05-07T09:19:57.795786Z","end":"2026-05-07T09:19:59.233289Z","steps":["trace[853339402] 'read index received' (duration: 1.419674636s)","trace[853339402] 'applied index is now lower than readState.Index' (duration: 17.826856ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:19:59.23351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437717647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-07T09:19:59.233537Z","caller":"traceutil/trace.go:171","msg":"trace[674761855] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1343; }","duration":"1.437759078s","start":"2026-05-07T09:19:57.795768Z","end":"2026-05-07T09:19:59.233528Z","steps":["trace[674761855] 'agreement among raft nodes before linearized reading' (duration: 1.437641195s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.233576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.79569Z","time spent":"1.437879401s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T09:19:59.233732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359630744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:59.233753Z","caller":"traceutil/trace.go:171","msg":"trace[1089849038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"1.359694026s","start":"2026-05-07T09:19:57.874053Z","end":"2026-05-07T09:19:59.233747Z","steps":["trace[1089849038] 'agreement among raft nodes before linearized reading' (duration: 1.359662435s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.23377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:57.874027Z","time spent":"1.359739226s","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T09:19:59.233928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.740028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-07T09:19:59.233946Z","caller":"traceutil/trace.go:171","msg":"trace[212827859] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1343; }","duration":"138.800039ms","start":"2026-05-07T09:19:59.09514Z","end":"2026-05-07T09:19:59.23394Z","steps":["trace[212827859] 'agreement among raft nodes before linearized reading' (duration: 138.738058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.234037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.996172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:19:59.234057Z","caller":"traceutil/trace.go:171","msg":"trace[523253355] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1343; }","duration":"737.046763ms","start":"2026-05-07T09:19:58.497003Z","end":"2026-05-07T09:19:59.23405Z","steps":["trace[523253355] 'agreement among raft nodes before linearized reading' (duration: 737.012332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.234081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:58.496986Z","time spent":"737.089324ms","remote":"127.0.0.1:55642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:59.234202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.048676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T09:19:59.23422Z","caller":"traceutil/trace.go:171","msg":"trace[1456660229] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1343; }","duration":"780.103738ms","start":"2026-05-07T09:19:58.45411Z","end":"2026-05-07T09:19:59.234214Z","steps":["trace[1456660229] 'agreement among raft nodes before linearized reading' (duration: 780.069957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.23424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:58.454084Z","time spent":"780.149658ms","remote":"127.0.0.1:52376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-07T09:19:59.234349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.677099ms","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-05-07T09:19:59.234367Z","caller":"traceutil/trace.go:171","msg":"trace[1916911851] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1343; }","duration":"926.696869ms","start":"2026-05-07T09:19:58.307665Z","end":"2026-05-07T09:19:59.234362Z","steps":["trace[1916911851] 'agreement among raft nodes before linearized reading' (duration: 926.660388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:19:59.234383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:19:58.30761Z","time spent":"926.768881ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-07T09:20:09.407569Z","caller":"traceutil/trace.go:171","msg":"trace[733937833] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"110.602454ms","start":"2026-05-07T09:20:09.296938Z","end":"2026-05-07T09:20:09.407541Z","steps":["trace[733937833] 'process raft request' (duration: 110.356508ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:09.40753Z","caller":"traceutil/trace.go:171","msg":"trace[1837935522] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"110.563413ms","start":"2026-05-07T09:20:09.29694Z","end":"2026-05-07T09:20:09.407503Z","steps":["trace[1837935522] 'process raft request' (duration: 110.486811ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:09.408308Z","caller":"traceutil/trace.go:171","msg":"trace[1742883397] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"110.729306ms","start":"2026-05-07T09:20:09.297543Z","end":"2026-05-07T09:20:09.408272Z","steps":["trace[1742883397] 'process raft request' (duration: 109.921417ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:09.408435Z","caller":"traceutil/trace.go:171","msg":"trace[1929572422] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"110.4506ms","start":"2026-05-07T09:20:09.297971Z","end":"2026-05-07T09:20:09.408421Z","steps":["trace[1929572422] 'process raft request' (duration: 110.215964ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:09.408463Z","caller":"traceutil/trace.go:171","msg":"trace[194970132] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"110.336277ms","start":"2026-05-07T09:20:09.298111Z","end":"2026-05-07T09:20:09.408447Z","steps":["trace[194970132] 'process raft request' (duration: 110.250005ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:23.560789Z","caller":"traceutil/trace.go:171","msg":"trace[1401249998] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1511; }","duration":"142.925152ms","start":"2026-05-07T09:20:23.417795Z","end":"2026-05-07T09:20:23.56072Z","steps":["trace[1401249998] 'read index received' (duration: 85.564446ms)","trace[1401249998] 'applied index is now lower than readState.Index' (duration: 57.357865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:20:23.56164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.800252ms","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-05-07T09:20:23.561769Z","caller":"traceutil/trace.go:171","msg":"trace[934235850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1450; }","duration":"144.000007ms","start":"2026-05-07T09:20:23.41773Z","end":"2026-05-07T09:20:23.56173Z","steps":["trace[934235850] 'agreement among raft nodes before linearized reading' (duration: 143.537826ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:29.84336Z","caller":"traceutil/trace.go:171","msg":"trace[444919754] linearizableReadLoop","detail":"{readStateIndex:1545; appliedIndex:1544; }","duration":"120.23012ms","start":"2026-05-07T09:20:29.723107Z","end":"2026-05-07T09:20:29.843337Z","steps":["trace[444919754] 'read index received' (duration: 119.990144ms)","trace[444919754] 'applied index is now lower than readState.Index' (duration: 239.066µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:29.843579Z","caller":"traceutil/trace.go:171","msg":"trace[2085364296] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"128.130954ms","start":"2026-05-07T09:20:29.715415Z","end":"2026-05-07T09:20:29.843546Z","steps":["trace[2085364296] 'process raft request' (duration: 127.777136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:29.843653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.525546ms","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-05-07T09:20:29.843949Z","caller":"traceutil/trace.go:171","msg":"trace[419454206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1482; }","duration":"120.872185ms","start":"2026-05-07T09:20:29.723063Z","end":"2026-05-07T09:20:29.843935Z","steps":["trace[419454206] 'agreement among raft nodes before linearized reading' (duration: 120.391763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:29.843649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.382899ms","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-05-07T09:20:29.844073Z","caller":"traceutil/trace.go:171","msg":"trace[1497543206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1482; }","duration":"119.854401ms","start":"2026-05-07T09:20:29.724211Z","end":"2026-05-07T09:20:29.844065Z","steps":["trace[1497543206] 'agreement among raft nodes before linearized reading' (duration: 119.274627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:30.055858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.706409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T09:20:30.056452Z","caller":"traceutil/trace.go:171","msg":"trace[438876951] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"208.441999ms","start":"2026-05-07T09:20:29.847988Z","end":"2026-05-07T09:20:30.05643Z","steps":["trace[438876951] 'process raft request' (duration: 96.079324ms)","trace[438876951] 'compare' (duration: 111.578846ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:30.546349Z","caller":"traceutil/trace.go:171","msg":"trace[231825966] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"111.024443ms","start":"2026-05-07T09:20:30.435302Z","end":"2026-05-07T09:20:30.546327Z","steps":["trace[231825966] 'process raft request' (duration: 110.899191ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:34.247488Z","caller":"traceutil/trace.go:171","msg":"trace[985199099] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"134.539525ms","start":"2026-05-07T09:20:34.11291Z","end":"2026-05-07T09:20:34.24745Z","steps":["trace[985199099] 'process raft request' (duration: 60.018927ms)","trace[985199099] 'compare' (duration: 74.226321ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:39.676824Z","caller":"traceutil/trace.go:171","msg":"trace[1357285137] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1587; }","duration":"116.078552ms","start":"2026-05-07T09:20:39.560722Z","end":"2026-05-07T09:20:39.676801Z","steps":["trace[1357285137] 'read index received' (duration: 66.644073ms)","trace[1357285137] 'applied index is now lower than readState.Index' (duration: 49.433479ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:39.676876Z","caller":"traceutil/trace.go:171","msg":"trace[1368606129] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"117.711ms","start":"2026-05-07T09:20:39.559133Z","end":"2026-05-07T09:20:39.676844Z","steps":["trace[1368606129] 'process raft request' (duration: 68.192369ms)","trace[1368606129] 'compare' (duration: 49.294906ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:20:39.677023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.282257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-07T09:20:39.67707Z","caller":"traceutil/trace.go:171","msg":"trace[949449990] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1523; }","duration":"116.373099ms","start":"2026-05-07T09:20:39.560683Z","end":"2026-05-07T09:20:39.677056Z","steps":["trace[949449990] 'agreement among raft nodes before linearized reading' (duration: 116.204485ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:43.994948Z","caller":"traceutil/trace.go:171","msg":"trace[2078690500] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"154.694437ms","start":"2026-05-07T09:20:43.840233Z","end":"2026-05-07T09:20:43.994927Z","steps":["trace[2078690500] 'process raft request' (duration: 93.524483ms)","trace[2078690500] 'compare' (duration: 60.743104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:20:43.995079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.13073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:20:43.995135Z","caller":"traceutil/trace.go:171","msg":"trace[1927688271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1545; }","duration":"121.241403ms","start":"2026-05-07T09:20:43.873881Z","end":"2026-05-07T09:20:43.995123Z","steps":["trace[1927688271] 'agreement among raft nodes before linearized reading' (duration: 121.11978ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:43.995399Z","caller":"traceutil/trace.go:171","msg":"trace[1298774546] linearizableReadLoop","detail":"{readStateIndex:1611; appliedIndex:1610; }","duration":"120.926116ms","start":"2026-05-07T09:20:43.873937Z","end":"2026-05-07T09:20:43.994863Z","steps":["trace[1298774546] 'read index received' (duration: 59.769272ms)","trace[1298774546] 'applied index is now lower than readState.Index' (duration: 61.155764ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:45.806558Z","caller":"traceutil/trace.go:171","msg":"trace[666829761] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"171.531112ms","start":"2026-05-07T09:20:45.635001Z","end":"2026-05-07T09:20:45.806532Z","steps":["trace[666829761] 'process raft request' (duration: 171.477971ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:45.806639Z","caller":"traceutil/trace.go:171","msg":"trace[346828917] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"171.959302ms","start":"2026-05-07T09:20:45.63465Z","end":"2026-05-07T09:20:45.806609Z","steps":["trace[346828917] 'process raft request' (duration: 119.750658ms)","trace[346828917] 'compare' (duration: 51.943828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:20:46.318286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.723994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T09:20:46.31838Z","caller":"traceutil/trace.go:171","msg":"trace[1884079840] linearizableReadLoop","detail":"{readStateIndex:1621; appliedIndex:1620; }","duration":"444.416451ms","start":"2026-05-07T09:20:45.873948Z","end":"2026-05-07T09:20:46.318365Z","steps":["trace[1884079840] 'read index received' (duration: 261.561834ms)","trace[1884079840] 'applied index is now lower than readState.Index' (duration: 182.853117ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:46.318526Z","caller":"traceutil/trace.go:171","msg":"trace[259115999] transaction","detail":"{read_only:false; response_revision:1555; number_of_response:1; }","duration":"474.311133ms","start":"2026-05-07T09:20:45.844191Z","end":"2026-05-07T09:20:46.318502Z","steps":["trace[259115999] 'process raft request' (duration: 291.280411ms)","trace[259115999] 'compare' (duration: 182.544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T09:20:46.318654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:45.844177Z","time spent":"474.405865ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:20:46.318769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.809071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T09:20:46.31884Z","caller":"traceutil/trace.go:171","msg":"trace[2084633372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1555; }","duration":"444.904483ms","start":"2026-05-07T09:20:45.873921Z","end":"2026-05-07T09:20:46.318825Z","steps":["trace[2084633372] 'agreement among raft nodes before linearized reading' (duration: 444.583656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:46.318882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:45.87391Z","time spent":"444.964844ms","remote":"127.0.0.1:52114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T09:20:46.385506Z","caller":"traceutil/trace.go:171","msg":"trace[754552041] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"194.684545ms","start":"2026-05-07T09:20:46.190776Z","end":"2026-05-07T09:20:46.385461Z","steps":["trace[754552041] 'process raft request' (duration: 194.50311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:46.385593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.582066ms","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-05-07T09:20:46.385756Z","caller":"traceutil/trace.go:171","msg":"trace[444875129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1556; }","duration":"363.700818ms","start":"2026-05-07T09:20:46.021947Z","end":"2026-05-07T09:20:46.385648Z","steps":["trace[444875129] 'agreement among raft nodes before linearized reading' (duration: 363.500573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:46.385756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.217116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-07T09:20:46.38582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:46.021937Z","time spent":"363.868693ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T09:20:46.385853Z","caller":"traceutil/trace.go:171","msg":"trace[1722242112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1556; }","duration":"386.344899ms","start":"2026-05-07T09:20:45.999491Z","end":"2026-05-07T09:20:46.385835Z","steps":["trace[1722242112] 'agreement among raft nodes before linearized reading' (duration: 386.085953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:46.385907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:45.99948Z","time spent":"386.413821ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T09:20:46.386067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.455807ms","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:531"} {"level":"info","ts":"2026-05-07T09:20:46.386141Z","caller":"traceutil/trace.go:171","msg":"trace[1951899667] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1556; }","duration":"347.57923ms","start":"2026-05-07T09:20:46.038546Z","end":"2026-05-07T09:20:46.386125Z","steps":["trace[1951899667] 'agreement among raft nodes before linearized reading' (duration: 346.886324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:46.386177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:46.038523Z","time spent":"347.647022ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-07T09:20:46.528941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.872159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T09:20:46.52917Z","caller":"traceutil/trace.go:171","msg":"trace[1974056723] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"138.406086ms","start":"2026-05-07T09:20:46.390733Z","end":"2026-05-07T09:20:46.529139Z","steps":["trace[1974056723] 'process raft request' (duration: 36.22648ms)","trace[1974056723] 'compare' (duration: 101.661384ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:46.529369Z","caller":"traceutil/trace.go:171","msg":"trace[1081860710] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"138.062788ms","start":"2026-05-07T09:20:46.391243Z","end":"2026-05-07T09:20:46.529306Z","steps":["trace[1081860710] 'process raft request' (duration: 137.827262ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:46.529403Z","caller":"traceutil/trace.go:171","msg":"trace[332617117] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"138.012706ms","start":"2026-05-07T09:20:46.391352Z","end":"2026-05-07T09:20:46.529365Z","steps":["trace[332617117] 'process raft request' (duration: 137.789711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:46.766028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.471739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T09:20:46.766132Z","caller":"traceutil/trace.go:171","msg":"trace[1285414776] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"232.025051ms","start":"2026-05-07T09:20:46.534088Z","end":"2026-05-07T09:20:46.766113Z","steps":["trace[1285414776] 'process raft request' (duration: 104.374308ms)","trace[1285414776] 'compare' (duration: 127.311765ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:47.934518Z","caller":"traceutil/trace.go:171","msg":"trace[1332257797] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"117.035564ms","start":"2026-05-07T09:20:47.817454Z","end":"2026-05-07T09:20:47.93449Z","steps":["trace[1332257797] 'process raft request' (duration: 116.808679ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:52.561538Z","caller":"traceutil/trace.go:171","msg":"trace[557201760] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"128.460922ms","start":"2026-05-07T09:20:52.433049Z","end":"2026-05-07T09:20:52.56151Z","steps":["trace[557201760] 'process raft request' (duration: 35.545164ms)","trace[557201760] 'compare' (duration: 92.310964ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:52.980489Z","caller":"traceutil/trace.go:171","msg":"trace[1581540455] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"376.753694ms","start":"2026-05-07T09:20:52.603708Z","end":"2026-05-07T09:20:52.980462Z","steps":["trace[1581540455] 'process raft request' (duration: 376.59033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:52.980694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:52.603695Z","time spent":"376.874336ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T09:20:53.031227Z","caller":"traceutil/trace.go:171","msg":"trace[445431993] linearizableReadLoop","detail":"{readStateIndex:1653; appliedIndex:1650; }","duration":"241.017502ms","start":"2026-05-07T09:20:52.790183Z","end":"2026-05-07T09:20:53.0312Z","steps":["trace[445431993] 'read index received' (duration: 190.096297ms)","trace[445431993] 'applied index is now lower than readState.Index' (duration: 50.920025ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:20:53.031252Z","caller":"traceutil/trace.go:171","msg":"trace[727533843] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"427.466913ms","start":"2026-05-07T09:20:52.603757Z","end":"2026-05-07T09:20:53.031224Z","steps":["trace[727533843] 'process raft request' (duration: 427.159486ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:20:53.03135Z","caller":"traceutil/trace.go:171","msg":"trace[1029384335] transaction","detail":"{read_only:false; response_revision:1586; number_of_response:1; }","duration":"427.021823ms","start":"2026-05-07T09:20:52.604303Z","end":"2026-05-07T09:20:53.031324Z","steps":["trace[1029384335] 'process raft request' (duration: 426.848409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:53.031467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.445377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T09:20:53.031568Z","caller":"traceutil/trace.go:171","msg":"trace[1567436900] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1586; }","duration":"109.59392ms","start":"2026-05-07T09:20:52.92196Z","end":"2026-05-07T09:20:53.031554Z","steps":["trace[1567436900] 'agreement among raft nodes before linearized reading' (duration: 109.419296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T09:20:53.031605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:52.604287Z","time spent":"427.263229ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:20:53.031403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T09:20:52.603743Z","time spent":"427.578416ms","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T09:20:53.031498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.299268ms","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-05-07T09:20:53.031751Z","caller":"traceutil/trace.go:171","msg":"trace[1245745925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1586; }","duration":"241.607195ms","start":"2026-05-07T09:20:52.790132Z","end":"2026-05-07T09:20:53.031739Z","steps":["trace[1245745925] 'agreement among raft nodes before linearized reading' (duration: 241.213336ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:21:06.819448Z","caller":"traceutil/trace.go:171","msg":"trace[227922252] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"130.820308ms","start":"2026-05-07T09:21:06.688576Z","end":"2026-05-07T09:21:06.819396Z","steps":["trace[227922252] 'process raft request' (duration: 111.607477ms)","trace[227922252] 'compare' (duration: 18.811051ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T09:22:56.106514Z","caller":"traceutil/trace.go:171","msg":"trace[1275084441] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"100.987135ms","start":"2026-05-07T09:22:56.005497Z","end":"2026-05-07T09:22:56.106484Z","steps":["trace[1275084441] 'process raft request' (duration: 99.08424ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T09:23:06.296558Z","caller":"traceutil/trace.go:171","msg":"trace[1968103826] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"102.155493ms","start":"2026-05-07T09:23:06.19438Z","end":"2026-05-07T09:23:06.296535Z","steps":["trace[1968103826] 'process raft request' (duration: 98.635321ms)"],"step_count":1}