{"level":"warn","ts":"2026-05-12T20:00:19.912898Z","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-12T20:00:19.912976Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-12T20:00:19.91304Z","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-12T20:00:19.913052Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-12T20:00:19.91307Z","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-12T20:00:19.913708Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-05-12T20:00:19.913777Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-12T20:00:19.918674Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.613476ms"} {"level":"info","ts":"2026-05-12T20:00:19.930785Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-05-12T20:00:19.930898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T20:00:19.930924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-05-12T20:00:19.930936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T20:00:19.930947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-05-12T20:00:19.930992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-05-12T20:00:19.939425Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T20:00:19.940645Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T20:00:19.942697Z","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-12T20:00:19.94541Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T20:00:19.945589Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T20:00:19.945659Z","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-12T20:00:19.945696Z","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-12T20:00:19.945705Z","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-12T20:00:19.949071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-05-12T20:00:19.949622Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-12T20:00:19.950693Z","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-12T20:00:19.950762Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-12T20:00:19.950796Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-12T20:00:19.950955Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T20:00:19.950999Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T20:00:20.731862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T20:00:20.731993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T20:00:20.73209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-05-12T20:00:20.732152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-05-12T20:00:20.732187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-12T20:00:20.732284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-05-12T20:00:20.732327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-12T20:00:20.733449Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:00:20.734147Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T20:00:20.734152Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T20:00:20.734351Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T20:00:20.734375Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T20:00:20.734236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T20:00:20.734745Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:00:20.734965Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:00:20.735076Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T20:00:20.736447Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T20:00:20.746732Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"warn","ts":"2026-05-12T20:00:49.226977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.10663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:00:49.227593Z","caller":"traceutil/trace.go:171","msg":"trace[321231858] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"644.434138ms","start":"2026-05-12T20:00:48.582932Z","end":"2026-05-12T20:00:49.227366Z","steps":["trace[321231858] 'process raft request' (duration: 174.056303ms)","trace[321231858] 'compare' (duration: 468.971397ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:00:49.227614Z","caller":"traceutil/trace.go:171","msg":"trace[1222867039] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"639.608646ms","start":"2026-05-12T20:00:48.587977Z","end":"2026-05-12T20:00:49.227586Z","steps":["trace[1222867039] 'process raft request' (duration: 639.216636ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:49.227685Z","caller":"traceutil/trace.go:171","msg":"trace[642895160] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"638.657431ms","start":"2026-05-12T20:00:48.588977Z","end":"2026-05-12T20:00:49.227634Z","steps":["trace[642895160] 'process raft request' (duration: 638.254751ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:49.227692Z","caller":"traceutil/trace.go:171","msg":"trace[1235916434] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"636.295995ms","start":"2026-05-12T20:00:48.591363Z","end":"2026-05-12T20:00:49.227659Z","steps":["trace[1235916434] 'process raft request' (duration: 635.914986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.227758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.587968Z","time spent":"639.716509ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:00:49.227724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.582908Z","time spent":"644.767026ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:00:49.227794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.58897Z","time spent":"638.764473ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:00:49.227839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.591355Z","time spent":"636.43657ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:00:49.227853Z","caller":"traceutil/trace.go:171","msg":"trace[646456768] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"644.005386ms","start":"2026-05-12T20:00:48.583835Z","end":"2026-05-12T20:00:49.22784Z","steps":["trace[646456768] 'process raft request' (duration: 643.293677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.227946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.583822Z","time spent":"644.101738ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:00:49.228022Z","caller":"traceutil/trace.go:171","msg":"trace[1858664596] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"629.978636ms","start":"2026-05-12T20:00:48.598034Z","end":"2026-05-12T20:00:49.228013Z","steps":["trace[1858664596] 'process raft request' (duration: 629.319918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.228106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.598021Z","time spent":"630.023707ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:00:49.2287Z","caller":"traceutil/trace.go:171","msg":"trace[133673847] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"626.833542ms","start":"2026-05-12T20:00:48.601847Z","end":"2026-05-12T20:00:49.22868Z","steps":["trace[133673847] 'process raft request' (duration: 625.761222ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:49.228778Z","caller":"traceutil/trace.go:171","msg":"trace[1882753141] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"612.218906ms","start":"2026-05-12T20:00:48.616541Z","end":"2026-05-12T20:00:49.22876Z","steps":["trace[1882753141] 'process raft request' (duration: 611.99733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.2288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.601836Z","time spent":"626.921864ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:00:49.228883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.61653Z","time spent":"612.315368ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:00:49.228922Z","caller":"traceutil/trace.go:171","msg":"trace[974591590] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"587.463154ms","start":"2026-05-12T20:00:48.64144Z","end":"2026-05-12T20:00:49.228903Z","steps":["trace[974591590] 'process raft request' (duration: 587.323631ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:49.22901Z","caller":"traceutil/trace.go:171","msg":"trace[1659133403] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"612.39517ms","start":"2026-05-12T20:00:48.616595Z","end":"2026-05-12T20:00:49.22899Z","steps":["trace[1659133403] 'process raft request' (duration: 612.091072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.229017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.641405Z","time spent":"587.572037ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:00:49.2291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.61659Z","time spent":"612.473031ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:00:49.231449Z","caller":"traceutil/trace.go:171","msg":"trace[699021480] transaction","detail":"{read_only:false; number_of_response:1; response_revision:472; }","duration":"564.196445ms","start":"2026-05-12T20:00:48.667242Z","end":"2026-05-12T20:00:49.231438Z","steps":["trace[699021480] 'process raft request' (duration: 564.169125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.231652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.667222Z","time spent":"564.262376ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:00:49.231862Z","caller":"traceutil/trace.go:171","msg":"trace[1732702618] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"567.816103ms","start":"2026-05-12T20:00:48.664037Z","end":"2026-05-12T20:00:49.231853Z","steps":["trace[1732702618] 'process raft request' (duration: 567.239878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.231918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.664021Z","time spent":"567.870244ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:00:49.232085Z","caller":"traceutil/trace.go:171","msg":"trace[2135593632] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"566.800016ms","start":"2026-05-12T20:00:48.665276Z","end":"2026-05-12T20:00:49.232076Z","steps":["trace[2135593632] 'process raft request' (duration: 566.059476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.232129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.665268Z","time spent":"566.843047ms","remote":"127.0.0.1:44064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:00:49.232221Z","caller":"traceutil/trace.go:171","msg":"trace[597906195] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"565.14758ms","start":"2026-05-12T20:00:48.667065Z","end":"2026-05-12T20:00:49.232213Z","steps":["trace[597906195] 'process raft request' (duration: 564.307028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.232269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.667037Z","time spent":"565.208692ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:00:49.241178Z","caller":"traceutil/trace.go:171","msg":"trace[1426095373] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"481.870816ms","start":"2026-05-12T20:00:48.759287Z","end":"2026-05-12T20:00:49.241158Z","steps":["trace[1426095373] 'process raft request' (duration: 480.440678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.241279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.759262Z","time spent":"481.970169ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:00:49.252661Z","caller":"traceutil/trace.go:171","msg":"trace[137005821] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"301.661827ms","start":"2026-05-12T20:00:48.950953Z","end":"2026-05-12T20:00:49.252614Z","steps":["trace[137005821] 'read index received' (duration: 281.715078ms)","trace[137005821] 'applied index is now lower than readState.Index' (duration: 19.944039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:00:49.253222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.199102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:00:49.253256Z","caller":"traceutil/trace.go:171","msg":"trace[1273629440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"302.342845ms","start":"2026-05-12T20:00:48.950904Z","end":"2026-05-12T20:00:49.253247Z","steps":["trace[1273629440] 'agreement among raft nodes before linearized reading' (duration: 301.870162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:49.253321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:48.950886Z","time spent":"302.419338ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:00:53.677568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.168999ms","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-12T20:00:53.677639Z","caller":"traceutil/trace.go:171","msg":"trace[288809447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"374.288633ms","start":"2026-05-12T20:00:53.303335Z","end":"2026-05-12T20:00:53.677624Z","steps":["trace[288809447] 'range keys from in-memory index tree' (duration: 374.036556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:53.677668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:53.303316Z","time spent":"374.344435ms","remote":"127.0.0.1:43776","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-12T20:00:53.677833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.554524ms","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-12T20:00:53.677853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.06758ms","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-12T20:00:53.677929Z","caller":"traceutil/trace.go:171","msg":"trace[717392894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"400.699038ms","start":"2026-05-12T20:00:53.277212Z","end":"2026-05-12T20:00:53.677911Z","steps":["trace[717392894] 'range keys from in-memory index tree' (duration: 400.307147ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:53.677986Z","caller":"traceutil/trace.go:171","msg":"trace[1850661543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"363.233994ms","start":"2026-05-12T20:00:53.314732Z","end":"2026-05-12T20:00:53.677966Z","steps":["trace[1850661543] 'range keys from in-memory index tree' (duration: 362.842413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:53.678048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:53.277192Z","time spent":"400.845221ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T20:00:53.678073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:53.314717Z","time spent":"363.346867ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T20:00:56.061095Z","caller":"traceutil/trace.go:171","msg":"trace[1632537625] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"370.137841ms","start":"2026-05-12T20:00:55.690927Z","end":"2026-05-12T20:00:56.061064Z","steps":["trace[1632537625] 'process raft request' (duration: 333.079248ms)","trace[1632537625] 'compare' (duration: 36.874688ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:00:56.061247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:55.690913Z","time spent":"370.255754ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:00:56.119458Z","caller":"traceutil/trace.go:171","msg":"trace[1537603272] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"427.130024ms","start":"2026-05-12T20:00:55.692295Z","end":"2026-05-12T20:00:56.119425Z","steps":["trace[1537603272] 'process raft request' (duration: 426.98296ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:56.119516Z","caller":"traceutil/trace.go:171","msg":"trace[2054111492] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"405.332874ms","start":"2026-05-12T20:00:55.71416Z","end":"2026-05-12T20:00:56.119493Z","steps":["trace[2054111492] 'process raft request' (duration: 405.157418ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:56.119442Z","caller":"traceutil/trace.go:171","msg":"trace[148786514] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"427.231707ms","start":"2026-05-12T20:00:55.692145Z","end":"2026-05-12T20:00:56.119377Z","steps":["trace[148786514] 'process raft request' (duration: 426.98589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:56.119633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:55.692287Z","time spent":"427.263378ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:00:56.119729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:55.692137Z","time spent":"427.519995ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:00:56.119504Z","caller":"traceutil/trace.go:171","msg":"trace[1184122936] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:590; }","duration":"313.291982ms","start":"2026-05-12T20:00:55.806183Z","end":"2026-05-12T20:00:56.119475Z","steps":["trace[1184122936] 'read index received' (duration: 217.840277ms)","trace[1184122936] 'applied index is now lower than readState.Index' (duration: 95.450104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:00:56.119612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:55.714144Z","time spent":"405.414285ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:00:56.119966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.643531ms","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-12T20:00:56.120015Z","caller":"traceutil/trace.go:171","msg":"trace[1977204832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"313.875248ms","start":"2026-05-12T20:00:55.806127Z","end":"2026-05-12T20:00:56.120003Z","steps":["trace[1977204832] 'agreement among raft nodes before linearized reading' (duration: 313.424436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:56.120082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:55.806105Z","time spent":"313.966829ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T20:00:56.575191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.359548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:00:56.575265Z","caller":"traceutil/trace.go:171","msg":"trace[283474577] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"451.916845ms","start":"2026-05-12T20:00:56.123335Z","end":"2026-05-12T20:00:56.575251Z","steps":["trace[283474577] 'read index received' (duration: 61.392292ms)","trace[283474577] 'applied index is now lower than readState.Index' (duration: 390.523503ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:00:56.57534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.002447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-12T20:00:56.575362Z","caller":"traceutil/trace.go:171","msg":"trace[337371017] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:573; }","duration":"452.038508ms","start":"2026-05-12T20:00:56.123317Z","end":"2026-05-12T20:00:56.575355Z","steps":["trace[337371017] 'agreement among raft nodes before linearized reading' (duration: 451.967876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:56.575385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:56.123307Z","time spent":"452.073809ms","remote":"127.0.0.1:43722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-05-12T20:00:56.575334Z","caller":"traceutil/trace.go:171","msg":"trace[1984574589] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"458.686409ms","start":"2026-05-12T20:00:56.116625Z","end":"2026-05-12T20:00:56.575312Z","steps":["trace[1984574589] 'process raft request' (duration: 68.142835ms)","trace[1984574589] 'compare' (duration: 390.216504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:00:56.575561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:56.116606Z","time spent":"458.818252ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:00:56.671061Z","caller":"traceutil/trace.go:171","msg":"trace[1430232944] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"546.942797ms","start":"2026-05-12T20:00:56.124082Z","end":"2026-05-12T20:00:56.671024Z","steps":["trace[1430232944] 'process raft request' (duration: 546.588268ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:00:56.671314Z","caller":"traceutil/trace.go:171","msg":"trace[763381267] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"515.376123ms","start":"2026-05-12T20:00:56.155913Z","end":"2026-05-12T20:00:56.671289Z","steps":["trace[763381267] 'process raft request' (duration: 515.034053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:00:56.67142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:56.155881Z","time spent":"515.488095ms","remote":"127.0.0.1:43696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:00:56.671428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:00:56.124037Z","time spent":"547.187904ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:00:56.929584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.715534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:00:56.929675Z","caller":"traceutil/trace.go:171","msg":"trace[1308986070] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:575; }","duration":"248.862818ms","start":"2026-05-12T20:00:56.680789Z","end":"2026-05-12T20:00:56.929652Z","steps":["trace[1308986070] 'range keys from in-memory index tree' (duration: 248.636772ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:10.281806Z","caller":"traceutil/trace.go:171","msg":"trace[1270485374] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"156.508558ms","start":"2026-05-12T20:01:10.125273Z","end":"2026-05-12T20:01:10.281781Z","steps":["trace[1270485374] 'process raft request' (duration: 156.26302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:10.28186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.029539ms","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-12T20:01:10.281946Z","caller":"traceutil/trace.go:171","msg":"trace[1120391914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"100.139151ms","start":"2026-05-12T20:01:10.181788Z","end":"2026-05-12T20:01:10.281928Z","steps":["trace[1120391914] 'agreement among raft nodes before linearized reading' (duration: 99.956077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:10.282014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.08478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T20:01:10.282069Z","caller":"traceutil/trace.go:171","msg":"trace[857932080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"100.195543ms","start":"2026-05-12T20:01:10.18186Z","end":"2026-05-12T20:01:10.282055Z","steps":["trace[857932080] 'agreement among raft nodes before linearized reading' (duration: 99.986437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:40.154071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.041366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:01:40.154169Z","caller":"traceutil/trace.go:171","msg":"trace[1566726148] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:811; }","duration":"603.1686ms","start":"2026-05-12T20:01:39.550978Z","end":"2026-05-12T20:01:40.154147Z","steps":["trace[1566726148] 'count revisions from in-memory index tree' (duration: 602.936753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:40.154218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.550909Z","time spent":"603.297274ms","remote":"127.0.0.1:44030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-12T20:01:40.154279Z","caller":"traceutil/trace.go:171","msg":"trace[1214272240] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"211.01402ms","start":"2026-05-12T20:01:39.943236Z","end":"2026-05-12T20:01:40.15425Z","steps":["trace[1214272240] 'read index received' (duration: 209.941299ms)","trace[1214272240] 'applied index is now lower than readState.Index' (duration: 1.071631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:01:40.154428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.180274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:01:40.154484Z","caller":"traceutil/trace.go:171","msg":"trace[298015556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"211.285887ms","start":"2026-05-12T20:01:39.943183Z","end":"2026-05-12T20:01:40.154469Z","steps":["trace[298015556] 'agreement among raft nodes before linearized reading' (duration: 211.159034ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:40.154838Z","caller":"traceutil/trace.go:171","msg":"trace[548814783] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"547.310906ms","start":"2026-05-12T20:01:39.607513Z","end":"2026-05-12T20:01:40.154824Z","steps":["trace[548814783] 'process raft request' (duration: 545.787841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:40.154955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:01:39.607488Z","time spent":"547.394018ms","remote":"127.0.0.1:43756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:01:53.929011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.700183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:01:53.929107Z","caller":"traceutil/trace.go:171","msg":"trace[856296882] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"281.39183ms","start":"2026-05-12T20:01:53.647703Z","end":"2026-05-12T20:01:53.929095Z","steps":["trace[856296882] 'process raft request' (duration: 80.545153ms)","trace[856296882] 'compare' (duration: 200.60492ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:01:53.98926Z","caller":"traceutil/trace.go:171","msg":"trace[1292284327] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"205.262204ms","start":"2026-05-12T20:01:53.783968Z","end":"2026-05-12T20:01:53.98923Z","steps":["trace[1292284327] 'process raft request' (duration: 205.12801ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:54.111432Z","caller":"traceutil/trace.go:171","msg":"trace[1198063144] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"122.20343ms","start":"2026-05-12T20:01:53.989204Z","end":"2026-05-12T20:01:54.111408Z","steps":["trace[1198063144] 'read index received' (duration: 121.964632ms)","trace[1198063144] 'applied index is now lower than readState.Index' (duration: 237.538µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:01:54.111499Z","caller":"traceutil/trace.go:171","msg":"trace[247222994] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"165.108591ms","start":"2026-05-12T20:01:53.946348Z","end":"2026-05-12T20:01:54.111457Z","steps":["trace[247222994] 'process raft request' (duration: 164.895285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:54.111701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.536551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-12T20:01:54.111749Z","caller":"traceutil/trace.go:171","msg":"trace[843998889] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:943; }","duration":"120.638984ms","start":"2026-05-12T20:01:53.991096Z","end":"2026-05-12T20:01:54.111735Z","steps":["trace[843998889] 'agreement among raft nodes before linearized reading' (duration: 120.437078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:01:54.111701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.801804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:01:54.11185Z","caller":"traceutil/trace.go:171","msg":"trace[1585274067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"171.02199ms","start":"2026-05-12T20:01:53.940813Z","end":"2026-05-12T20:01:54.111835Z","steps":["trace[1585274067] 'agreement among raft nodes before linearized reading' (duration: 170.788644ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:01:54.304532Z","caller":"traceutil/trace.go:171","msg":"trace[834763920] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"155.497455ms","start":"2026-05-12T20:01:54.149015Z","end":"2026-05-12T20:01:54.304513Z","steps":["trace[834763920] 'read index received' (duration: 75.014254ms)","trace[834763920] 'applied index is now lower than readState.Index' (duration: 80.482431ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:01:54.304574Z","caller":"traceutil/trace.go:171","msg":"trace[1942942187] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"183.681435ms","start":"2026-05-12T20:01:54.120857Z","end":"2026-05-12T20:01:54.304539Z","steps":["trace[1942942187] 'process raft request' (duration: 103.281426ms)","trace[1942942187] 'compare' (duration: 80.273844ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:01:54.304628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.589957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:01:54.304649Z","caller":"traceutil/trace.go:171","msg":"trace[1359555110] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:944; }","duration":"155.65813ms","start":"2026-05-12T20:01:54.148986Z","end":"2026-05-12T20:01:54.304644Z","steps":["trace[1359555110] 'agreement among raft nodes before linearized reading' (duration: 155.598639ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:05.247799Z","caller":"traceutil/trace.go:171","msg":"trace[1605157618] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"304.016161ms","start":"2026-05-12T20:02:04.943753Z","end":"2026-05-12T20:02:05.247769Z","steps":["trace[1605157618] 'read index received' (duration: 303.759033ms)","trace[1605157618] 'applied index is now lower than readState.Index' (duration: 255.798µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:05.247885Z","caller":"traceutil/trace.go:171","msg":"trace[1802637061] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"413.302699ms","start":"2026-05-12T20:02:04.834551Z","end":"2026-05-12T20:02:05.247854Z","steps":["trace[1802637061] 'process raft request' (duration: 413.058961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:05.247949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.170465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:05.248005Z","caller":"traceutil/trace.go:171","msg":"trace[1172521429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"304.280208ms","start":"2026-05-12T20:02:04.94371Z","end":"2026-05-12T20:02:05.24799Z","steps":["trace[1172521429] 'agreement among raft nodes before linearized reading' (duration: 304.170225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:05.248034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:04.834535Z","time spent":"413.421072ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:05.248112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:04.943686Z","time spent":"304.3497ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:02:05.248121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.892607ms","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-12T20:02:05.248257Z","caller":"traceutil/trace.go:171","msg":"trace[219611543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"187.076562ms","start":"2026-05-12T20:02:05.061157Z","end":"2026-05-12T20:02:05.248234Z","steps":["trace[219611543] 'agreement among raft nodes before linearized reading' (duration: 186.780824ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:09.441904Z","caller":"traceutil/trace.go:171","msg":"trace[100324750] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"102.225956ms","start":"2026-05-12T20:02:09.339649Z","end":"2026-05-12T20:02:09.441875Z","steps":["trace[100324750] 'process raft request' (duration: 99.065955ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:12.758721Z","caller":"traceutil/trace.go:171","msg":"trace[749061731] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"100.131935ms","start":"2026-05-12T20:02:12.658562Z","end":"2026-05-12T20:02:12.758694Z","steps":["trace[749061731] 'process raft request' (duration: 99.878428ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:12.818703Z","caller":"traceutil/trace.go:171","msg":"trace[620500933] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"159.524761ms","start":"2026-05-12T20:02:12.659153Z","end":"2026-05-12T20:02:12.818678Z","steps":["trace[620500933] 'process raft request' (duration: 159.412568ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:15.027632Z","caller":"traceutil/trace.go:171","msg":"trace[886441416] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"372.105005ms","start":"2026-05-12T20:02:14.6555Z","end":"2026-05-12T20:02:15.027605Z","steps":["trace[886441416] 'read index received' (duration: 371.88717ms)","trace[886441416] 'applied index is now lower than readState.Index' (duration: 217.035µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:15.027733Z","caller":"traceutil/trace.go:171","msg":"trace[1333940201] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"443.31929ms","start":"2026-05-12T20:02:14.584378Z","end":"2026-05-12T20:02:15.027698Z","steps":["trace[1333940201] 'process raft request' (duration: 443.055343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:15.027861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.344502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T20:02:15.027895Z","caller":"traceutil/trace.go:171","msg":"trace[80955907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"372.430684ms","start":"2026-05-12T20:02:14.655456Z","end":"2026-05-12T20:02:15.027886Z","steps":["trace[80955907] 'agreement among raft nodes before linearized reading' (duration: 372.238259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:15.027917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:14.655437Z","time spent":"372.475526ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T20:02:15.027912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:14.584344Z","time spent":"443.499475ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:15.028089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.723525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-12T20:02:15.028091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.032248ms","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-12T20:02:15.028175Z","caller":"traceutil/trace.go:171","msg":"trace[1970055091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"205.178002ms","start":"2026-05-12T20:02:14.822979Z","end":"2026-05-12T20:02:15.028157Z","steps":["trace[1970055091] 'agreement among raft nodes before linearized reading' (duration: 204.915753ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:15.028172Z","caller":"traceutil/trace.go:171","msg":"trace[1263976855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"246.854288ms","start":"2026-05-12T20:02:14.781294Z","end":"2026-05-12T20:02:15.028148Z","steps":["trace[1263976855] 'agreement among raft nodes before linearized reading' (duration: 246.618371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:15.028869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.589975ms","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-12T20:02:15.028924Z","caller":"traceutil/trace.go:171","msg":"trace[661605680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"368.671777ms","start":"2026-05-12T20:02:14.660237Z","end":"2026-05-12T20:02:15.028909Z","steps":["trace[661605680] 'agreement among raft nodes before linearized reading' (duration: 367.511203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:15.028977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:14.660222Z","time spent":"368.738128ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T20:02:15.970624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422470900704243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:02:16.277847Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.245038222s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T20:02:16.301422Z","caller":"traceutil/trace.go:171","msg":"trace[791406133] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"1.26867835s","start":"2026-05-12T20:02:15.03272Z","end":"2026-05-12T20:02:16.301398Z","steps":["trace[791406133] 'process raft request' (duration: 1.24534362s)","trace[791406133] 'compare' (duration: 22.95169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:16.301532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:15.032701Z","time spent":"1.268769763s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:16.385734Z","caller":"traceutil/trace.go:171","msg":"trace[244790902] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"1.3529064s","start":"2026-05-12T20:02:15.0328Z","end":"2026-05-12T20:02:16.385706Z","steps":["trace[244790902] 'process raft request' (duration: 1.352697584s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:16.385998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:15.032784Z","time spent":"1.353044213s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:16.386051Z","caller":"traceutil/trace.go:171","msg":"trace[714804626] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1064; }","duration":"916.164657ms","start":"2026-05-12T20:02:15.46985Z","end":"2026-05-12T20:02:16.386015Z","steps":["trace[714804626] 'read index received' (duration: 808.187267ms)","trace[714804626] 'applied index is now lower than readState.Index' (duration: 107.97653ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:16.386175Z","caller":"traceutil/trace.go:171","msg":"trace[101346490] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"1.353317571s","start":"2026-05-12T20:02:15.032846Z","end":"2026-05-12T20:02:16.386164Z","steps":["trace[101346490] 'process raft request' (duration: 1.352809326s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:16.386284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:15.032834Z","time spent":"1.353372703s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:16.386637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.165065ms","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-12T20:02:16.38669Z","caller":"traceutil/trace.go:171","msg":"trace[941841985] 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:1029; }","duration":"599.297709ms","start":"2026-05-12T20:02:15.787378Z","end":"2026-05-12T20:02:16.386676Z","steps":["trace[941841985] 'agreement among raft nodes before linearized reading' (duration: 599.183316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:16.386683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.826337ms","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-12T20:02:16.386984Z","caller":"traceutil/trace.go:171","msg":"trace[1646088610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"916.98071ms","start":"2026-05-12T20:02:15.469811Z","end":"2026-05-12T20:02:16.386792Z","steps":["trace[1646088610] 'agreement among raft nodes before linearized reading' (duration: 916.672562ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:16.38707Z","caller":"traceutil/trace.go:171","msg":"trace[866789362] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.354210086s","start":"2026-05-12T20:02:15.032846Z","end":"2026-05-12T20:02:16.387056Z","steps":["trace[866789362] 'process raft request' (duration: 1.353044953s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:16.387142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:15.032827Z","time spent":"1.354282159s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:16.387246Z","caller":"traceutil/trace.go:171","msg":"trace[818991001] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.033931989s","start":"2026-05-12T20:02:15.353305Z","end":"2026-05-12T20:02:16.387237Z","steps":["trace[818991001] 'process raft request' (duration: 1.032669023s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:16.387301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:15.353279Z","time spent":"1.033995411s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:16.386723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:15.787361Z","time spent":"599.35343ms","remote":"127.0.0.1:41200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"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-12T20:02:16.388796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:15.469797Z","time spent":"917.255209ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T20:02:17.424158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.924417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:02:17.424321Z","caller":"traceutil/trace.go:171","msg":"trace[669814242] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"481.678122ms","start":"2026-05-12T20:02:16.942621Z","end":"2026-05-12T20:02:17.4243Z","steps":["trace[669814242] 'read index received' (duration: 79.352µs)","trace[669814242] 'applied index is now lower than readState.Index' (duration: 481.59489ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:17.424332Z","caller":"traceutil/trace.go:171","msg":"trace[1570724932] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"1.032061806s","start":"2026-05-12T20:02:16.392247Z","end":"2026-05-12T20:02:17.424309Z","steps":["trace[1570724932] 'process raft request' (duration: 229.886182ms)","trace[1570724932] 'compare' (duration: 801.781843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:17.424421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.813005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T20:02:17.42443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:16.392225Z","time spent":"1.032154289s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:17.42447Z","caller":"traceutil/trace.go:171","msg":"trace[734857704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"481.875978ms","start":"2026-05-12T20:02:16.942579Z","end":"2026-05-12T20:02:17.424455Z","steps":["trace[734857704] 'agreement among raft nodes before linearized reading' (duration: 481.785264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:17.42451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:16.942516Z","time spent":"481.983671ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:02:18.022763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.99596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T20:02:18.022844Z","caller":"traceutil/trace.go:171","msg":"trace[1436586603] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1030; }","duration":"217.135774ms","start":"2026-05-12T20:02:17.805693Z","end":"2026-05-12T20:02:18.022829Z","steps":["trace[1436586603] 'count revisions from in-memory index tree' (duration: 216.888568ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:20.549358Z","caller":"traceutil/trace.go:171","msg":"trace[783879867] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"109.35566ms","start":"2026-05-12T20:02:20.439985Z","end":"2026-05-12T20:02:20.549341Z","steps":["trace[783879867] 'process raft request' (duration: 109.241017ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:20.728275Z","caller":"traceutil/trace.go:171","msg":"trace[1043878830] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1078; }","duration":"236.699737ms","start":"2026-05-12T20:02:20.49155Z","end":"2026-05-12T20:02:20.72825Z","steps":["trace[1043878830] 'read index received' (duration: 57.743858ms)","trace[1043878830] 'applied index is now lower than readState.Index' (duration: 178.954539ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:20.728399Z","caller":"traceutil/trace.go:171","msg":"trace[1696188971] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"287.747863ms","start":"2026-05-12T20:02:20.440638Z","end":"2026-05-12T20:02:20.728386Z","steps":["trace[1696188971] 'process raft request' (duration: 211.06268ms)","trace[1696188971] 'compare' (duration: 76.306771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:20.728647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.782369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-12T20:02:20.728681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.922813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T20:02:20.72872Z","caller":"traceutil/trace.go:171","msg":"trace[1040439376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"236.909502ms","start":"2026-05-12T20:02:20.491795Z","end":"2026-05-12T20:02:20.728705Z","steps":["trace[1040439376] 'agreement among raft nodes before linearized reading' (duration: 236.716167ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:20.728777Z","caller":"traceutil/trace.go:171","msg":"trace[1144213506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"237.045476ms","start":"2026-05-12T20:02:20.491708Z","end":"2026-05-12T20:02:20.728753Z","steps":["trace[1144213506] 'agreement among raft nodes before linearized reading' (duration: 236.772428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:20.729452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.89129ms","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-12T20:02:20.729503Z","caller":"traceutil/trace.go:171","msg":"trace[2087092812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"237.976064ms","start":"2026-05-12T20:02:20.491515Z","end":"2026-05-12T20:02:20.729491Z","steps":["trace[2087092812] 'agreement among raft nodes before linearized reading' (duration: 237.015387ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:21.57559Z","caller":"traceutil/trace.go:171","msg":"trace[1829536720] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"103.555264ms","start":"2026-05-12T20:02:21.472016Z","end":"2026-05-12T20:02:21.575572Z","steps":["trace[1829536720] 'process raft request' (duration: 103.458702ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:26.88064Z","caller":"traceutil/trace.go:171","msg":"trace[1336073530] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1100; }","duration":"119.08168ms","start":"2026-05-12T20:02:26.76154Z","end":"2026-05-12T20:02:26.880622Z","steps":["trace[1336073530] 'read index received' (duration: 32.447023ms)","trace[1336073530] 'applied index is now lower than readState.Index' (duration: 86.633897ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:26.880695Z","caller":"traceutil/trace.go:171","msg":"trace[805894871] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"121.623433ms","start":"2026-05-12T20:02:26.75905Z","end":"2026-05-12T20:02:26.880674Z","steps":["trace[805894871] 'process raft request' (duration: 121.20592ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:26.88073Z","caller":"traceutil/trace.go:171","msg":"trace[22995585] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"120.613094ms","start":"2026-05-12T20:02:26.760081Z","end":"2026-05-12T20:02:26.880694Z","steps":["trace[22995585] 'process raft request' (duration: 120.512321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:26.880805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.248045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T20:02:26.880828Z","caller":"traceutil/trace.go:171","msg":"trace[1702660771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"119.297777ms","start":"2026-05-12T20:02:26.761523Z","end":"2026-05-12T20:02:26.880821Z","steps":["trace[1702660771] 'agreement among raft nodes before linearized reading' (duration: 119.166513ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:37.159538Z","caller":"traceutil/trace.go:171","msg":"trace[935591317] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1136; }","duration":"216.927233ms","start":"2026-05-12T20:02:36.942573Z","end":"2026-05-12T20:02:37.1595Z","steps":["trace[935591317] 'read index received' (duration: 53.536372ms)","trace[935591317] 'applied index is now lower than readState.Index' (duration: 163.389851ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:37.15957Z","caller":"traceutil/trace.go:171","msg":"trace[217240098] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"235.927141ms","start":"2026-05-12T20:02:36.923602Z","end":"2026-05-12T20:02:37.159529Z","steps":["trace[217240098] 'process raft request' (duration: 211.989703ms)","trace[217240098] 'compare' (duration: 23.725192ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:37.159616Z","caller":"traceutil/trace.go:171","msg":"trace[13303608] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"233.347514ms","start":"2026-05-12T20:02:36.926247Z","end":"2026-05-12T20:02:37.159594Z","steps":["trace[13303608] 'process raft request' (duration: 233.20476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:37.159657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.068737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:37.159694Z","caller":"traceutil/trace.go:171","msg":"trace[305587599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"217.120308ms","start":"2026-05-12T20:02:36.942564Z","end":"2026-05-12T20:02:37.159684Z","steps":["trace[305587599] 'agreement among raft nodes before linearized reading' (duration: 217.048056ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:39.325589Z","caller":"traceutil/trace.go:171","msg":"trace[251001837] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"158.081971ms","start":"2026-05-12T20:02:39.16748Z","end":"2026-05-12T20:02:39.325562Z","steps":["trace[251001837] 'process raft request' (duration: 106.012407ms)","trace[251001837] 'compare' (duration: 51.971331ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:40.853779Z","caller":"traceutil/trace.go:171","msg":"trace[2034916331] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"112.99454ms","start":"2026-05-12T20:02:40.740763Z","end":"2026-05-12T20:02:40.853758Z","steps":["trace[2034916331] 'process raft request' (duration: 112.881137ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:41.414427Z","caller":"traceutil/trace.go:171","msg":"trace[107441335] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"172.210361ms","start":"2026-05-12T20:02:41.242188Z","end":"2026-05-12T20:02:41.414399Z","steps":["trace[107441335] 'process raft request' (duration: 171.994006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:42.120003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422470900704456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:02:42.347166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.037068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:02:42.347316Z","caller":"traceutil/trace.go:171","msg":"trace[203357628] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"928.628495ms","start":"2026-05-12T20:02:41.418666Z","end":"2026-05-12T20:02:42.347294Z","steps":["trace[203357628] 'process raft request' (duration: 801.349221ms)","trace[203357628] 'compare' (duration: 126.941894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:42.347528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.03532ms","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":"warn","ts":"2026-05-12T20:02:42.347567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:41.418629Z","time spent":"928.885551ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:42.347598Z","caller":"traceutil/trace.go:171","msg":"trace[495674904] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1108; }","duration":"728.147783ms","start":"2026-05-12T20:02:41.619435Z","end":"2026-05-12T20:02:42.347583Z","steps":["trace[495674904] 'agreement among raft nodes before linearized reading' (duration: 727.992159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:42.347698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:41.619415Z","time spent":"728.263286ms","remote":"127.0.0.1:43944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-12T20:02:42.347353Z","caller":"traceutil/trace.go:171","msg":"trace[952755545] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"727.786854ms","start":"2026-05-12T20:02:41.619486Z","end":"2026-05-12T20:02:42.347273Z","steps":["trace[952755545] 'read index received' (duration: 600.539861ms)","trace[952755545] 'applied index is now lower than readState.Index' (duration: 127.245113ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:42.347945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.982757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:42.347997Z","caller":"traceutil/trace.go:171","msg":"trace[1746574718] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1108; }","duration":"437.069099ms","start":"2026-05-12T20:02:41.910915Z","end":"2026-05-12T20:02:42.347984Z","steps":["trace[1746574718] 'agreement among raft nodes before linearized reading' (duration: 436.994987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:42.348007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.031321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-12T20:02:42.348034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:41.910896Z","time spent":"437.126181ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-12T20:02:42.348065Z","caller":"traceutil/trace.go:171","msg":"trace[1423881755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1108; }","duration":"691.123493ms","start":"2026-05-12T20:02:41.656926Z","end":"2026-05-12T20:02:42.348049Z","steps":["trace[1423881755] 'agreement among raft nodes before linearized reading' (duration: 690.96795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:42.34811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:41.656906Z","time spent":"691.192666ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T20:02:43.451671Z","caller":"traceutil/trace.go:171","msg":"trace[888605510] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"319.350896ms","start":"2026-05-12T20:02:43.13228Z","end":"2026-05-12T20:02:43.451631Z","steps":["trace[888605510] 'process raft request' (duration: 315.996448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:43.451862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:43.132265Z","time spent":"319.487219ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:44.159646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.402867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:02:44.159741Z","caller":"traceutil/trace.go:171","msg":"trace[962109264] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"515.653148ms","start":"2026-05-12T20:02:43.644072Z","end":"2026-05-12T20:02:44.159725Z","steps":["trace[962109264] 'read index received' (duration: 15.194838ms)","trace[962109264] 'applied index is now lower than readState.Index' (duration: 500.45721ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:44.159807Z","caller":"traceutil/trace.go:171","msg":"trace[300279927] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"704.260729ms","start":"2026-05-12T20:02:43.455522Z","end":"2026-05-12T20:02:44.159783Z","steps":["trace[300279927] 'process raft request' (duration: 203.636795ms)","trace[300279927] 'compare' (duration: 500.295204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:44.159918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:43.455505Z","time spent":"704.366422ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:02:44.159951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.881673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-12T20:02:44.159981Z","caller":"traceutil/trace.go:171","msg":"trace[543767975] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:1113; }","duration":"515.963526ms","start":"2026-05-12T20:02:43.644008Z","end":"2026-05-12T20:02:44.159972Z","steps":["trace[543767975] 'agreement among raft nodes before linearized reading' (duration: 515.796972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:44.160012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:43.643989Z","time spent":"516.014497ms","remote":"127.0.0.1:43542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-05-12T20:02:44.16011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.13479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:44.160168Z","caller":"traceutil/trace.go:171","msg":"trace[430964827] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1113; }","duration":"503.216342ms","start":"2026-05-12T20:02:43.656936Z","end":"2026-05-12T20:02:44.160152Z","steps":["trace[430964827] 'agreement among raft nodes before linearized reading' (duration: 503.13032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:44.160203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:43.656925Z","time spent":"503.269084ms","remote":"127.0.0.1:41352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-12T20:02:44.455565Z","caller":"traceutil/trace.go:171","msg":"trace[981119332] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"115.66337ms","start":"2026-05-12T20:02:44.339874Z","end":"2026-05-12T20:02:44.455537Z","steps":["trace[981119332] 'read index received' (duration: 60.949197ms)","trace[981119332] 'applied index is now lower than readState.Index' (duration: 54.712573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:44.455732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.835074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:44.455765Z","caller":"traceutil/trace.go:171","msg":"trace[488941458] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1113; }","duration":"115.926626ms","start":"2026-05-12T20:02:44.339829Z","end":"2026-05-12T20:02:44.455755Z","steps":["trace[488941458] 'agreement among raft nodes before linearized reading' (duration: 115.840424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:44.455846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.192174ms","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-12T20:02:44.455911Z","caller":"traceutil/trace.go:171","msg":"trace[2068202092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"103.298547ms","start":"2026-05-12T20:02:44.352597Z","end":"2026-05-12T20:02:44.455895Z","steps":["trace[2068202092] 'agreement among raft nodes before linearized reading' (duration: 103.109821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:44.713873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.231769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:02:44.714026Z","caller":"traceutil/trace.go:171","msg":"trace[977396851] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1160; }","duration":"178.194167ms","start":"2026-05-12T20:02:44.535815Z","end":"2026-05-12T20:02:44.71401Z","steps":["trace[977396851] 'read index received' (duration: 28.741523ms)","trace[977396851] 'applied index is now lower than readState.Index' (duration: 149.451794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:44.714128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.31859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-12T20:02:44.71416Z","caller":"traceutil/trace.go:171","msg":"trace[809825055] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1116; }","duration":"178.381333ms","start":"2026-05-12T20:02:44.53577Z","end":"2026-05-12T20:02:44.714151Z","steps":["trace[809825055] 'agreement among raft nodes before linearized reading' (duration: 178.32512ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:44.714093Z","caller":"traceutil/trace.go:171","msg":"trace[962472407] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"254.829995ms","start":"2026-05-12T20:02:44.459232Z","end":"2026-05-12T20:02:44.714062Z","steps":["trace[962472407] 'process raft request' (duration: 105.334679ms)","trace[962472407] 'compare' (duration: 149.009854ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:44.714558Z","caller":"traceutil/trace.go:171","msg":"trace[798174824] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"253.562772ms","start":"2026-05-12T20:02:44.460484Z","end":"2026-05-12T20:02:44.714047Z","steps":["trace[798174824] 'process raft request' (duration: 253.47586ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:46.286735Z","caller":"traceutil/trace.go:171","msg":"trace[493340956] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"118.209317ms","start":"2026-05-12T20:02:46.1685Z","end":"2026-05-12T20:02:46.286709Z","steps":["trace[493340956] 'process raft request' (duration: 118.043853ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:47.245906Z","caller":"traceutil/trace.go:171","msg":"trace[226101314] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"524.325685ms","start":"2026-05-12T20:02:46.721551Z","end":"2026-05-12T20:02:47.245877Z","steps":["trace[226101314] 'process raft request' (duration: 524.153691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:47.24609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:46.721539Z","time spent":"524.445668ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:47.312657Z","caller":"traceutil/trace.go:171","msg":"trace[210924419] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"589.82174ms","start":"2026-05-12T20:02:46.722813Z","end":"2026-05-12T20:02:47.312634Z","steps":["trace[210924419] 'process raft request' (duration: 589.273725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:47.312774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:46.722793Z","time spent":"589.924853ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:47.312916Z","caller":"traceutil/trace.go:171","msg":"trace[1771524124] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"336.716011ms","start":"2026-05-12T20:02:46.976168Z","end":"2026-05-12T20:02:47.312884Z","steps":["trace[1771524124] 'process raft request' (duration: 336.417572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:47.313034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:46.976145Z","time spent":"336.826993ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:02:47.313177Z","caller":"traceutil/trace.go:171","msg":"trace[275675672] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1167; }","duration":"566.986963ms","start":"2026-05-12T20:02:46.746175Z","end":"2026-05-12T20:02:47.313162Z","steps":["trace[275675672] 'read index received' (duration: 499.592987ms)","trace[275675672] 'applied index is now lower than readState.Index' (duration: 67.391336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:02:47.313288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.113126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T20:02:47.313316Z","caller":"traceutil/trace.go:171","msg":"trace[1797925113] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1124; }","duration":"567.182817ms","start":"2026-05-12T20:02:46.746125Z","end":"2026-05-12T20:02:47.313308Z","steps":["trace[1797925113] 'agreement among raft nodes before linearized reading' (duration: 567.121746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:47.313312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.861505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:47.313336Z","caller":"traceutil/trace.go:171","msg":"trace[261919669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"370.942757ms","start":"2026-05-12T20:02:46.942387Z","end":"2026-05-12T20:02:47.31333Z","steps":["trace[261919669] 'agreement among raft nodes before linearized reading' (duration: 370.901776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:47.313337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:46.746105Z","time spent":"567.226679ms","remote":"127.0.0.1:41324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:02:47.313353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:02:46.942366Z","time spent":"370.983818ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T20:02:57.406432Z","caller":"traceutil/trace.go:171","msg":"trace[717406831] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"113.473913ms","start":"2026-05-12T20:02:57.292926Z","end":"2026-05-12T20:02:57.4064Z","steps":["trace[717406831] 'process raft request' (duration: 113.249997ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:57.834798Z","caller":"traceutil/trace.go:171","msg":"trace[977961471] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"120.040855ms","start":"2026-05-12T20:02:57.714731Z","end":"2026-05-12T20:02:57.834772Z","steps":["trace[977961471] 'process raft request' (duration: 119.913122ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:57.964635Z","caller":"traceutil/trace.go:171","msg":"trace[2032372518] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"124.513112ms","start":"2026-05-12T20:02:57.840097Z","end":"2026-05-12T20:02:57.96461Z","steps":["trace[2032372518] 'process raft request' (duration: 100.298018ms)","trace[2032372518] 'compare' (duration: 24.104701ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:58.204647Z","caller":"traceutil/trace.go:171","msg":"trace[1194505423] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1212; }","duration":"132.836479ms","start":"2026-05-12T20:02:58.071789Z","end":"2026-05-12T20:02:58.204626Z","steps":["trace[1194505423] 'read index received' (duration: 2.201897ms)","trace[1194505423] 'applied index is now lower than readState.Index' (duration: 130.633612ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:58.204667Z","caller":"traceutil/trace.go:171","msg":"trace[1583971913] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"172.619962ms","start":"2026-05-12T20:02:58.032019Z","end":"2026-05-12T20:02:58.204639Z","steps":["trace[1583971913] 'process raft request' (duration: 172.478958ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:58.204749Z","caller":"traceutil/trace.go:171","msg":"trace[795805708] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"172.616121ms","start":"2026-05-12T20:02:58.032088Z","end":"2026-05-12T20:02:58.204705Z","steps":["trace[795805708] 'process raft request' (duration: 172.508059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:58.204886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.064075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T20:02:58.204919Z","caller":"traceutil/trace.go:171","msg":"trace[1015003261] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1166; }","duration":"133.156098ms","start":"2026-05-12T20:02:58.071753Z","end":"2026-05-12T20:02:58.204909Z","steps":["trace[1015003261] 'agreement among raft nodes before linearized reading' (duration: 132.976373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:58.386046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.93834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:02:58.386132Z","caller":"traceutil/trace.go:171","msg":"trace[1391219941] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"176.322919ms","start":"2026-05-12T20:02:58.209795Z","end":"2026-05-12T20:02:58.386118Z","steps":["trace[1391219941] 'process raft request' (duration: 16.254086ms)","trace[1391219941] 'compare' (duration: 159.807256ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:58.614991Z","caller":"traceutil/trace.go:171","msg":"trace[1842847093] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"127.189452ms","start":"2026-05-12T20:02:58.487766Z","end":"2026-05-12T20:02:58.614955Z","steps":["trace[1842847093] 'process raft request' (duration: 37.086691ms)","trace[1842847093] 'compare' (duration: 89.959807ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:58.769269Z","caller":"traceutil/trace.go:171","msg":"trace[1569017830] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1219; }","duration":"258.610495ms","start":"2026-05-12T20:02:58.510633Z","end":"2026-05-12T20:02:58.769243Z","steps":["trace[1569017830] 'read index received' (duration: 14.14306ms)","trace[1569017830] 'applied index is now lower than readState.Index' (duration: 244.466315ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:58.769442Z","caller":"traceutil/trace.go:171","msg":"trace[1943319964] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"280.862917ms","start":"2026-05-12T20:02:58.488548Z","end":"2026-05-12T20:02:58.769411Z","steps":["trace[1943319964] 'process raft request' (duration: 280.623931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:02:58.769566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.903713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:02:58.769647Z","caller":"traceutil/trace.go:171","msg":"trace[877354474] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1173; }","duration":"259.070667ms","start":"2026-05-12T20:02:58.510556Z","end":"2026-05-12T20:02:58.769626Z","steps":["trace[877354474] 'agreement among raft nodes before linearized reading' (duration: 258.878232ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:58.769572Z","caller":"traceutil/trace.go:171","msg":"trace[1795472561] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"281.225027ms","start":"2026-05-12T20:02:58.4881Z","end":"2026-05-12T20:02:58.769325Z","steps":["trace[1795472561] 'process raft request' (duration: 251.752895ms)","trace[1795472561] 'compare' (duration: 29.172684ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:02:59.150631Z","caller":"traceutil/trace.go:171","msg":"trace[538682907] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"166.543514ms","start":"2026-05-12T20:02:58.984039Z","end":"2026-05-12T20:02:59.150582Z","steps":["trace[538682907] 'process raft request' (duration: 166.423571ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:59.229412Z","caller":"traceutil/trace.go:171","msg":"trace[1138588434] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"244.729251ms","start":"2026-05-12T20:02:58.98466Z","end":"2026-05-12T20:02:59.229389Z","steps":["trace[1138588434] 'process raft request' (duration: 244.617648ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:02:59.684915Z","caller":"traceutil/trace.go:171","msg":"trace[1922795737] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"134.216375ms","start":"2026-05-12T20:02:59.550631Z","end":"2026-05-12T20:02:59.684847Z","steps":["trace[1922795737] 'process raft request' (duration: 85.001256ms)","trace[1922795737] 'compare' (duration: 49.100106ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:03.567507Z","caller":"traceutil/trace.go:171","msg":"trace[910053949] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"127.961484ms","start":"2026-05-12T20:03:03.439524Z","end":"2026-05-12T20:03:03.567485Z","steps":["trace[910053949] 'read index received' (duration: 69.052481ms)","trace[910053949] 'applied index is now lower than readState.Index' (duration: 58.908083ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:03.567625Z","caller":"traceutil/trace.go:171","msg":"trace[137067224] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"130.885421ms","start":"2026-05-12T20:03:03.436711Z","end":"2026-05-12T20:03:03.567597Z","steps":["trace[137067224] 'process raft request' (duration: 130.659625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:03.5678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.251483ms","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-12T20:03:03.567892Z","caller":"traceutil/trace.go:171","msg":"trace[1683147596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"128.326695ms","start":"2026-05-12T20:03:03.43952Z","end":"2026-05-12T20:03:03.567847Z","steps":["trace[1683147596] 'agreement among raft nodes before linearized reading' (duration: 128.109868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:03.876169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.534205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:03:03.876287Z","caller":"traceutil/trace.go:171","msg":"trace[1111417787] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"231.034223ms","start":"2026-05-12T20:03:03.645242Z","end":"2026-05-12T20:03:03.876276Z","steps":["trace[1111417787] 'read index received' (duration: 96.167971ms)","trace[1111417787] 'applied index is now lower than readState.Index' (duration: 134.865592ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:03.876338Z","caller":"traceutil/trace.go:171","msg":"trace[936233448] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"304.092955ms","start":"2026-05-12T20:03:03.572238Z","end":"2026-05-12T20:03:03.876331Z","steps":["trace[936233448] 'process raft request' (duration: 169.167321ms)","trace[936233448] 'compare' (duration: 134.374641ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:03.876375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:03.572213Z","time spent":"304.140717ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:03.876726Z","caller":"traceutil/trace.go:171","msg":"trace[2046875112] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"304.21071ms","start":"2026-05-12T20:03:03.572505Z","end":"2026-05-12T20:03:03.876716Z","steps":["trace[2046875112] 'process raft request' (duration: 303.737917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:03.87677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:03.572487Z","time spent":"304.261391ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:03.876891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.638279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-12T20:03:03.876936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.171003ms","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-12T20:03:03.877061Z","caller":"traceutil/trace.go:171","msg":"trace[714831995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"161.337717ms","start":"2026-05-12T20:03:03.715696Z","end":"2026-05-12T20:03:03.877033Z","steps":["trace[714831995] 'agreement among raft nodes before linearized reading' (duration: 161.032869ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:03.876975Z","caller":"traceutil/trace.go:171","msg":"trace[1470862484] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:1237; }","duration":"231.754221ms","start":"2026-05-12T20:03:03.645204Z","end":"2026-05-12T20:03:03.876958Z","steps":["trace[1470862484] 'agreement among raft nodes before linearized reading' (duration: 231.509375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:05.441335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422470900704742,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:03:05.787067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098932679s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T20:03:05.78736Z","caller":"traceutil/trace.go:171","msg":"trace[1132936695] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"846.745041ms","start":"2026-05-12T20:03:04.940594Z","end":"2026-05-12T20:03:05.787339Z","steps":["trace[1132936695] 'read index received' (duration: 846.619628ms)","trace[1132936695] 'applied index is now lower than readState.Index' (duration: 124.513µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:05.787449Z","caller":"traceutil/trace.go:171","msg":"trace[410187104] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"1.09938852s","start":"2026-05-12T20:03:04.688024Z","end":"2026-05-12T20:03:05.787413Z","steps":["trace[410187104] 'process raft request' (duration: 1.099196175s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:05.787481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.875885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:05.787616Z","caller":"traceutil/trace.go:171","msg":"trace[1295442294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"847.04909ms","start":"2026-05-12T20:03:04.940555Z","end":"2026-05-12T20:03:05.787604Z","steps":["trace[1295442294] 'agreement among raft nodes before linearized reading' (duration: 846.881926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:05.787613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:04.687998Z","time spent":"1.099527704s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:05.787657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:04.940539Z","time spent":"847.108822ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:03:06.288418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422470900704745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:03:06.789014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422470900704745,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T20:03:06.790246Z","caller":"traceutil/trace.go:171","msg":"trace[1537735871] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.217542845s","start":"2026-05-12T20:03:05.572648Z","end":"2026-05-12T20:03:06.790191Z","steps":["trace[1537735871] 'process raft request' (duration: 1.217376451s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:06.790423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.572627Z","time spent":"1.217705101s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:06.794264Z","caller":"traceutil/trace.go:171","msg":"trace[1087058856] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"1.006830476s","start":"2026-05-12T20:03:05.787417Z","end":"2026-05-12T20:03:06.794248Z","steps":["trace[1087058856] 'read index received' (duration: 1.003452207s)","trace[1087058856] 'applied index is now lower than readState.Index' (duration: 3.377599ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:06.794316Z","caller":"traceutil/trace.go:171","msg":"trace[1811484805] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"304.589309ms","start":"2026-05-12T20:03:06.489689Z","end":"2026-05-12T20:03:06.794278Z","steps":["trace[1811484805] 'process raft request' (duration: 304.465426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:06.79444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274176459s","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-12T20:03:06.794435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:06.489672Z","time spent":"304.699822ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:06.794462Z","caller":"traceutil/trace.go:171","msg":"trace[690723263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"1.27421557s","start":"2026-05-12T20:03:05.52024Z","end":"2026-05-12T20:03:06.794456Z","steps":["trace[690723263] 'agreement among raft nodes before linearized reading' (duration: 1.274080226s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:06.794529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.321586ms","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-12T20:03:06.794547Z","caller":"traceutil/trace.go:171","msg":"trace[835491202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1242; }","duration":"908.384417ms","start":"2026-05-12T20:03:05.886159Z","end":"2026-05-12T20:03:06.794543Z","steps":["trace[835491202] 'agreement among raft nodes before linearized reading' (duration: 908.323046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:06.79457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.886132Z","time spent":"908.431609ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T20:03:06.794574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.520189Z","time spent":"1.274363865s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T20:03:06.79466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590792443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T20:03:06.794664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.472299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-12T20:03:06.794684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.982287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-12T20:03:06.794724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.547207ms","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-12T20:03:06.794715Z","caller":"traceutil/trace.go:171","msg":"trace[1891648497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1242; }","duration":"908.569592ms","start":"2026-05-12T20:03:05.886128Z","end":"2026-05-12T20:03:06.794698Z","steps":["trace[1891648497] 'agreement among raft nodes before linearized reading' (duration: 908.409288ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:06.794725Z","caller":"traceutil/trace.go:171","msg":"trace[1707551524] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1242; }","duration":"897.07022ms","start":"2026-05-12T20:03:05.89764Z","end":"2026-05-12T20:03:06.794711Z","steps":["trace[1707551524] 'agreement among raft nodes before linearized reading' (duration: 897.005359ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:06.79474Z","caller":"traceutil/trace.go:171","msg":"trace[134799775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1242; }","duration":"914.593429ms","start":"2026-05-12T20:03:05.880143Z","end":"2026-05-12T20:03:06.794736Z","steps":["trace[134799775] 'agreement among raft nodes before linearized reading' (duration: 914.549608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:06.794758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.880126Z","time spent":"914.626599ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-12T20:03:06.794756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.897617Z","time spent":"897.132201ms","remote":"127.0.0.1:44032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:03:06.794757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.886087Z","time spent":"908.644703ms","remote":"127.0.0.1:43776","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-12T20:03:06.794492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006069126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:06.79483Z","caller":"traceutil/trace.go:171","msg":"trace[351715012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1242; }","duration":"1.006447506s","start":"2026-05-12T20:03:05.788362Z","end":"2026-05-12T20:03:06.79481Z","steps":["trace[351715012] 'agreement among raft nodes before linearized reading' (duration: 1.006010784s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:06.794886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.788348Z","time spent":"1.006522738s","remote":"127.0.0.1:43516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T20:03:06.794677Z","caller":"traceutil/trace.go:171","msg":"trace[47117401] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1242; }","duration":"1.590873225s","start":"2026-05-12T20:03:05.2038Z","end":"2026-05-12T20:03:06.794673Z","steps":["trace[47117401] 'agreement among raft nodes before linearized reading' (duration: 1.590842575s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:06.795005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:05.203783Z","time spent":"1.591196474s","remote":"127.0.0.1:41182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-12T20:03:12.937358Z","caller":"traceutil/trace.go:171","msg":"trace[423211798] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"104.888458ms","start":"2026-05-12T20:03:12.832447Z","end":"2026-05-12T20:03:12.937335Z","steps":["trace[423211798] 'process raft request' (duration: 104.776185ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:12.937532Z","caller":"traceutil/trace.go:171","msg":"trace[829749517] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"105.043691ms","start":"2026-05-12T20:03:12.832463Z","end":"2026-05-12T20:03:12.937507Z","steps":["trace[829749517] 'process raft request' (duration: 104.805335ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:12.937594Z","caller":"traceutil/trace.go:171","msg":"trace[6453174] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"105.172045ms","start":"2026-05-12T20:03:12.832412Z","end":"2026-05-12T20:03:12.937585Z","steps":["trace[6453174] 'process raft request' (duration: 104.696243ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:22.104479Z","caller":"traceutil/trace.go:171","msg":"trace[1393232182] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"139.448552ms","start":"2026-05-12T20:03:21.965009Z","end":"2026-05-12T20:03:22.104457Z","steps":["trace[1393232182] 'read index received' (duration: 139.277668ms)","trace[1393232182] 'applied index is now lower than readState.Index' (duration: 169.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:22.104605Z","caller":"traceutil/trace.go:171","msg":"trace[409763337] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"221.394819ms","start":"2026-05-12T20:03:21.883164Z","end":"2026-05-12T20:03:22.104559Z","steps":["trace[409763337] 'process raft request' (duration: 221.165993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:22.104647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.621427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-12T20:03:22.104694Z","caller":"traceutil/trace.go:171","msg":"trace[704111577] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1313; }","duration":"139.683009ms","start":"2026-05-12T20:03:21.964997Z","end":"2026-05-12T20:03:22.10468Z","steps":["trace[704111577] 'agreement among raft nodes before linearized reading' (duration: 139.583446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:22.104793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.225569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122705"} {"level":"info","ts":"2026-05-12T20:03:22.104853Z","caller":"traceutil/trace.go:171","msg":"trace[1370732436] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1313; }","duration":"133.313663ms","start":"2026-05-12T20:03:21.971522Z","end":"2026-05-12T20:03:22.104836Z","steps":["trace[1370732436] 'agreement among raft nodes before linearized reading' (duration: 133.107317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:23.225694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.241853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:03:23.225823Z","caller":"traceutil/trace.go:171","msg":"trace[312013703] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"235.90017ms","start":"2026-05-12T20:03:22.989903Z","end":"2026-05-12T20:03:23.225803Z","steps":["trace[312013703] 'read index received' (duration: 49.061µs)","trace[312013703] 'applied index is now lower than readState.Index' (duration: 235.849589ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:23.225944Z","caller":"traceutil/trace.go:171","msg":"trace[1911019767] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"270.852165ms","start":"2026-05-12T20:03:22.955074Z","end":"2026-05-12T20:03:23.225926Z","steps":["trace[1911019767] 'process raft request' (duration: 17.291513ms)","trace[1911019767] 'compare' (duration: 253.083649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:23.226131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.786197ms","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-12T20:03:23.226231Z","caller":"traceutil/trace.go:171","msg":"trace[1206292573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"232.949101ms","start":"2026-05-12T20:03:22.993261Z","end":"2026-05-12T20:03:23.22621Z","steps":["trace[1206292573] 'agreement among raft nodes before linearized reading' (duration: 232.673184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:23.226357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.150043ms","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":"info","ts":"2026-05-12T20:03:23.226402Z","caller":"traceutil/trace.go:171","msg":"trace[501894638] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1316; }","duration":"203.246555ms","start":"2026-05-12T20:03:23.023145Z","end":"2026-05-12T20:03:23.226391Z","steps":["trace[501894638] 'agreement among raft nodes before linearized reading' (duration: 203.153031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:23.226477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.975884ms","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-12T20:03:23.226506Z","caller":"traceutil/trace.go:171","msg":"trace[1765194695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1316; }","duration":"233.019924ms","start":"2026-05-12T20:03:22.993479Z","end":"2026-05-12T20:03:23.226499Z","steps":["trace[1765194695] 'agreement among raft nodes before linearized reading' (duration: 232.942423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:23.226703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.798213ms","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-12T20:03:23.226886Z","caller":"traceutil/trace.go:171","msg":"trace[1967328733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1316; }","duration":"237.015079ms","start":"2026-05-12T20:03:22.989852Z","end":"2026-05-12T20:03:23.226867Z","steps":["trace[1967328733] 'agreement among raft nodes before linearized reading' (duration: 236.689671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:23.697984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.360766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:03:23.698084Z","caller":"traceutil/trace.go:171","msg":"trace[2131753434] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"467.767494ms","start":"2026-05-12T20:03:23.230298Z","end":"2026-05-12T20:03:23.698065Z","steps":["trace[2131753434] 'process raft request' (duration: 102.957207ms)","trace[2131753434] 'compare' (duration: 363.77631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:23.698156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:23.230274Z","time spent":"467.842976ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:24.265889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.076943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:03:24.266006Z","caller":"traceutil/trace.go:171","msg":"trace[1266896647] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1372; }","duration":"619.216112ms","start":"2026-05-12T20:03:23.646778Z","end":"2026-05-12T20:03:24.265994Z","steps":["trace[1266896647] 'read index received' (duration: 408.956223ms)","trace[1266896647] 'applied index is now lower than readState.Index' (duration: 210.259389ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:24.266167Z","caller":"traceutil/trace.go:171","msg":"trace[228339903] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"1.035716173s","start":"2026-05-12T20:03:23.230444Z","end":"2026-05-12T20:03:24.26616Z","steps":["trace[228339903] 'process raft request' (duration: 825.307681ms)","trace[228339903] 'compare' (duration: 209.940739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:24.266201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:23.230424Z","time spent":"1.035758904s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:24.26635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.673074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:24.266406Z","caller":"traceutil/trace.go:171","msg":"trace[1254492261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"306.758386ms","start":"2026-05-12T20:03:23.959634Z","end":"2026-05-12T20:03:24.266392Z","steps":["trace[1254492261] 'agreement among raft nodes before linearized reading' (duration: 306.663753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:24.266433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:23.95962Z","time spent":"306.807737ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:03:24.266533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.760055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-12T20:03:24.266556Z","caller":"traceutil/trace.go:171","msg":"trace[233666733] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:1319; }","duration":"619.818207ms","start":"2026-05-12T20:03:23.646732Z","end":"2026-05-12T20:03:24.26655Z","steps":["trace[233666733] 'agreement among raft nodes before linearized reading' (duration: 619.730655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:24.266573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:23.646713Z","time spent":"619.856417ms","remote":"127.0.0.1:43542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-05-12T20:03:24.266657Z","caller":"traceutil/trace.go:171","msg":"trace[1253090807] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"1.035590919s","start":"2026-05-12T20:03:23.231057Z","end":"2026-05-12T20:03:24.266648Z","steps":["trace[1253090807] 'process raft request' (duration: 1.034900151s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:24.266682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.946161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-12T20:03:24.266724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:23.231047Z","time spent":"1.035633801s","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:24.266751Z","caller":"traceutil/trace.go:171","msg":"trace[1447595740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"157.066835ms","start":"2026-05-12T20:03:24.109665Z","end":"2026-05-12T20:03:24.266732Z","steps":["trace[1447595740] 'agreement among raft nodes before linearized reading' (duration: 156.835849ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:24.427641Z","caller":"traceutil/trace.go:171","msg":"trace[168840826] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"116.126971ms","start":"2026-05-12T20:03:24.311487Z","end":"2026-05-12T20:03:24.427614Z","steps":["trace[168840826] 'process raft request' (duration: 59.306263ms)","trace[168840826] 'compare' (duration: 56.682125ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:24.944658Z","caller":"traceutil/trace.go:171","msg":"trace[1133551717] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"113.082793ms","start":"2026-05-12T20:03:24.831557Z","end":"2026-05-12T20:03:24.94464Z","steps":["trace[1133551717] 'process raft request' (duration: 112.9762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.457974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.81471Z","time spent":"643.257441ms","remote":"127.0.0.1:53738","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-12T20:03:26.458461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.346848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-12T20:03:26.458524Z","caller":"traceutil/trace.go:171","msg":"trace[908928272] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"698.626512ms","start":"2026-05-12T20:03:25.759882Z","end":"2026-05-12T20:03:26.458509Z","steps":["trace[908928272] 'process raft request' (duration: 243.168701ms)","trace[908928272] 'compare' (duration: 455.071071ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:26.458568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.759862Z","time spent":"698.685853ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":809,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T20:03:26.527463Z","caller":"traceutil/trace.go:171","msg":"trace[1460488538] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1387; }","duration":"734.939883ms","start":"2026-05-12T20:03:25.792504Z","end":"2026-05-12T20:03:26.527444Z","steps":["trace[1460488538] 'read index received' (duration: 210.71569ms)","trace[1460488538] 'applied index is now lower than readState.Index' (duration: 524.223373ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:26.527579Z","caller":"traceutil/trace.go:171","msg":"trace[1998435276] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"767.445644ms","start":"2026-05-12T20:03:25.760126Z","end":"2026-05-12T20:03:26.527571Z","steps":["trace[1998435276] 'process raft request' (duration: 767.109545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.527657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.760116Z","time spent":"767.486395ms","remote":"127.0.0.1:43608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":807,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T20:03:26.527837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.312312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:26.527906Z","caller":"traceutil/trace.go:171","msg":"trace[888534634] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1334; }","duration":"735.407095ms","start":"2026-05-12T20:03:25.792472Z","end":"2026-05-12T20:03:26.527879Z","steps":["trace[888534634] 'agreement among raft nodes before linearized reading' (duration: 735.265602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.527932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.792457Z","time spent":"735.470196ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-12T20:03:26.52797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.754304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:26.528703Z","caller":"traceutil/trace.go:171","msg":"trace[544843554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"644.550335ms","start":"2026-05-12T20:03:25.88413Z","end":"2026-05-12T20:03:26.52868Z","steps":["trace[544843554] 'agreement among raft nodes before linearized reading' (duration: 643.695772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.528764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.884114Z","time spent":"644.636058ms","remote":"127.0.0.1:43504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:03:26.528038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.867406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:26.528953Z","caller":"traceutil/trace.go:171","msg":"trace[1003265212] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1334; }","duration":"450.803619ms","start":"2026-05-12T20:03:26.078134Z","end":"2026-05-12T20:03:26.528937Z","steps":["trace[1003265212] 'agreement among raft nodes before linearized reading' (duration: 449.880395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.528988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:26.07812Z","time spent":"450.858041ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-12T20:03:26.528086Z","caller":"traceutil/trace.go:171","msg":"trace[870220243] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"763.818799ms","start":"2026-05-12T20:03:25.764261Z","end":"2026-05-12T20:03:26.52808Z","steps":["trace[870220243] 'process raft request' (duration: 763.134031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.529141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.764242Z","time spent":"764.846496ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:26.528163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.262293ms","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-12T20:03:26.529237Z","caller":"traceutil/trace.go:171","msg":"trace[291068585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"181.369701ms","start":"2026-05-12T20:03:26.347855Z","end":"2026-05-12T20:03:26.529225Z","steps":["trace[291068585] 'agreement among raft nodes before linearized reading' (duration: 180.201651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.52822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.952461ms","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-12T20:03:26.529313Z","caller":"traceutil/trace.go:171","msg":"trace[1649018795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1334; }","duration":"258.08951ms","start":"2026-05-12T20:03:26.271216Z","end":"2026-05-12T20:03:26.529306Z","steps":["trace[1649018795] 'agreement among raft nodes before linearized reading' (duration: 256.963241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.528233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.734286ms","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-12T20:03:26.5294Z","caller":"traceutil/trace.go:171","msg":"trace[1288417709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1334; }","duration":"257.897797ms","start":"2026-05-12T20:03:26.271494Z","end":"2026-05-12T20:03:26.529391Z","steps":["trace[1288417709] 'agreement among raft nodes before linearized reading' (duration: 256.670644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.528251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.410888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:26.529482Z","caller":"traceutil/trace.go:171","msg":"trace[967570923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"588.650281ms","start":"2026-05-12T20:03:25.940823Z","end":"2026-05-12T20:03:26.529473Z","steps":["trace[967570923] 'agreement among raft nodes before linearized reading' (duration: 587.416458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.529511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.940816Z","time spent":"588.687181ms","remote":"127.0.0.1:43516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:03:26.528267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.063646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:26.529605Z","caller":"traceutil/trace.go:171","msg":"trace[436540818] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1334; }","duration":"560.437572ms","start":"2026-05-12T20:03:25.969159Z","end":"2026-05-12T20:03:26.529597Z","steps":["trace[436540818] 'agreement among raft nodes before linearized reading' (duration: 559.092326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.529632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.969139Z","time spent":"560.483123ms","remote":"127.0.0.1:43638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-12T20:03:26.528282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.051797ms","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-12T20:03:26.529724Z","caller":"traceutil/trace.go:171","msg":"trace[817484431] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1334; }","duration":"591.519206ms","start":"2026-05-12T20:03:25.938199Z","end":"2026-05-12T20:03:26.529719Z","steps":["trace[817484431] 'agreement among raft nodes before linearized reading' (duration: 590.070408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:26.529744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:25.938185Z","time spent":"591.554077ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-12T20:03:31.762101Z","caller":"traceutil/trace.go:171","msg":"trace[1132555314] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"103.962114ms","start":"2026-05-12T20:03:31.658124Z","end":"2026-05-12T20:03:31.762086Z","steps":["trace[1132555314] 'read index received' (duration: 103.838191ms)","trace[1132555314] 'applied index is now lower than readState.Index' (duration: 123.313µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:31.762159Z","caller":"traceutil/trace.go:171","msg":"trace[1514236781] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"105.328519ms","start":"2026-05-12T20:03:31.656809Z","end":"2026-05-12T20:03:31.762137Z","steps":["trace[1514236781] 'process raft request' (duration: 105.181056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:31.762566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.439836ms","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-12T20:03:31.762645Z","caller":"traceutil/trace.go:171","msg":"trace[357920699] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1384; }","duration":"104.56659ms","start":"2026-05-12T20:03:31.658065Z","end":"2026-05-12T20:03:31.762632Z","steps":["trace[357920699] 'agreement among raft nodes before linearized reading' (duration: 104.086537ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:31.914448Z","caller":"traceutil/trace.go:171","msg":"trace[2093733888] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"127.728816ms","start":"2026-05-12T20:03:31.786697Z","end":"2026-05-12T20:03:31.914425Z","steps":["trace[2093733888] 'process raft request' (duration: 127.681965ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:31.914491Z","caller":"traceutil/trace.go:171","msg":"trace[358973998] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"148.535471ms","start":"2026-05-12T20:03:31.765924Z","end":"2026-05-12T20:03:31.91446Z","steps":["trace[358973998] 'process raft request' (duration: 148.326246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:34.30175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422470900705066,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T20:03:34.780783Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.132822636s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T20:03:34.781031Z","caller":"traceutil/trace.go:171","msg":"trace[1715355994] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"979.774418ms","start":"2026-05-12T20:03:33.801239Z","end":"2026-05-12T20:03:34.781013Z","steps":["trace[1715355994] 'read index received' (duration: 979.659295ms)","trace[1715355994] 'applied index is now lower than readState.Index' (duration: 114.233µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:34.781121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.86953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T20:03:34.781102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.647849Z","time spent":"1.133246728s","remote":"127.0.0.1:43542","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-12T20:03:34.781143Z","caller":"traceutil/trace.go:171","msg":"trace[1789947709] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1394; }","duration":"979.948432ms","start":"2026-05-12T20:03:33.801188Z","end":"2026-05-12T20:03:34.781136Z","steps":["trace[1789947709] 'agreement among raft nodes before linearized reading' (duration: 979.884931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:34.78117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.801165Z","time spent":"979.998524ms","remote":"127.0.0.1:43908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-12T20:03:34.781228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.548814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:03:34.781257Z","caller":"traceutil/trace.go:171","msg":"trace[1709852053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1394; }","duration":"839.642786ms","start":"2026-05-12T20:03:33.941606Z","end":"2026-05-12T20:03:34.781249Z","steps":["trace[1709852053] 'agreement among raft nodes before linearized reading' (duration: 839.595385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:34.781275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.941588Z","time spent":"839.683138ms","remote":"127.0.0.1:43516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T20:03:34.781301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.114184ms","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-12T20:03:34.781389Z","caller":"traceutil/trace.go:171","msg":"trace[2001910373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1394; }","duration":"862.248368ms","start":"2026-05-12T20:03:33.919117Z","end":"2026-05-12T20:03:34.781365Z","steps":["trace[2001910373] 'agreement among raft nodes before linearized reading' (duration: 862.017292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:34.781438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:33.919068Z","time spent":"862.359991ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T20:03:35.12076Z","caller":"traceutil/trace.go:171","msg":"trace[1070999724] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1452; }","duration":"335.798037ms","start":"2026-05-12T20:03:34.784939Z","end":"2026-05-12T20:03:35.120738Z","steps":["trace[1070999724] 'read index received' (duration: 270.167287ms)","trace[1070999724] 'applied index is now lower than readState.Index' (duration: 65.629819ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:03:35.120782Z","caller":"traceutil/trace.go:171","msg":"trace[971691176] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"338.681703ms","start":"2026-05-12T20:03:34.78207Z","end":"2026-05-12T20:03:35.120752Z","steps":["trace[971691176] 'process raft request' (duration: 273.026782ms)","trace[971691176] 'compare' (duration: 65.524578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:03:35.121029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:34.782044Z","time spent":"338.915189ms","remote":"127.0.0.1:43542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T20:03:35.12113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.173387ms","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:529"} {"level":"info","ts":"2026-05-12T20:03:35.12118Z","caller":"traceutil/trace.go:171","msg":"trace[301772409] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1395; }","duration":"336.264139ms","start":"2026-05-12T20:03:34.784906Z","end":"2026-05-12T20:03:35.12117Z","steps":["trace[301772409] 'agreement among raft nodes before linearized reading' (duration: 335.92852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:35.121216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:34.78489Z","time spent":"336.3183ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-12T20:03:35.123843Z","caller":"traceutil/trace.go:171","msg":"trace[479120083] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"338.610001ms","start":"2026-05-12T20:03:34.785206Z","end":"2026-05-12T20:03:35.123816Z","steps":["trace[479120083] 'process raft request' (duration: 338.320223ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:35.123895Z","caller":"traceutil/trace.go:171","msg":"trace[306200727] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"338.606221ms","start":"2026-05-12T20:03:34.785271Z","end":"2026-05-12T20:03:35.123877Z","steps":["trace[306200727] 'process raft request' (duration: 338.388425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:35.124022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.095717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T20:03:35.123995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:34.785188Z","time spent":"338.757925ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:35.124073Z","caller":"traceutil/trace.go:171","msg":"trace[177110809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1400; }","duration":"183.19405ms","start":"2026-05-12T20:03:34.940865Z","end":"2026-05-12T20:03:35.124059Z","steps":["trace[177110809] 'agreement among raft nodes before linearized reading' (duration: 183.092037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:35.12418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:34.785261Z","time spent":"338.772395ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:35.123883Z","caller":"traceutil/trace.go:171","msg":"trace[1847605324] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"337.969174ms","start":"2026-05-12T20:03:34.785892Z","end":"2026-05-12T20:03:35.123861Z","steps":["trace[1847605324] 'process raft request' (duration: 337.795229ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:35.123907Z","caller":"traceutil/trace.go:171","msg":"trace[1830521459] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"112.338434ms","start":"2026-05-12T20:03:35.011554Z","end":"2026-05-12T20:03:35.123892Z","steps":["trace[1830521459] 'process raft request' (duration: 112.249291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:35.124297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:34.785883Z","time spent":"338.354274ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:35.124493Z","caller":"traceutil/trace.go:171","msg":"trace[646833101] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"338.636851ms","start":"2026-05-12T20:03:34.785207Z","end":"2026-05-12T20:03:35.123844Z","steps":["trace[646833101] 'process raft request' (duration: 338.420225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:35.124577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T20:03:34.785188Z","time spent":"339.353371ms","remote":"127.0.0.1:43776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T20:03:37.285373Z","caller":"traceutil/trace.go:171","msg":"trace[791249596] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"147.35961ms","start":"2026-05-12T20:03:37.13799Z","end":"2026-05-12T20:03:37.285349Z","steps":["trace[791249596] 'process raft request' (duration: 147.194926ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:37.285626Z","caller":"traceutil/trace.go:171","msg":"trace[1446701794] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"147.389001ms","start":"2026-05-12T20:03:37.138226Z","end":"2026-05-12T20:03:37.285615Z","steps":["trace[1446701794] 'process raft request' (duration: 147.088693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:03:37.285979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.448967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-05-12T20:03:37.286031Z","caller":"traceutil/trace.go:171","msg":"trace[475035520] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1411; }","duration":"138.533029ms","start":"2026-05-12T20:03:37.147484Z","end":"2026-05-12T20:03:37.286017Z","steps":["trace[475035520] 'agreement among raft nodes before linearized reading' (duration: 138.381455ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:37.286174Z","caller":"traceutil/trace.go:171","msg":"trace[1264485098] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"147.675628ms","start":"2026-05-12T20:03:37.138369Z","end":"2026-05-12T20:03:37.286045Z","steps":["trace[1264485098] 'process raft request' (duration: 147.206646ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:03:37.401554Z","caller":"traceutil/trace.go:171","msg":"trace[378178213] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"108.014269ms","start":"2026-05-12T20:03:37.29351Z","end":"2026-05-12T20:03:37.401524Z","steps":["trace[378178213] 'process raft request' (duration: 106.52267ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:04:46.321935Z","caller":"traceutil/trace.go:171","msg":"trace[1656570839] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"156.789111ms","start":"2026-05-12T20:04:46.165117Z","end":"2026-05-12T20:04:46.321906Z","steps":["trace[1656570839] 'process raft request' (duration: 90.303729ms)","trace[1656570839] 'compare' (duration: 66.239846ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:05:18.165143Z","caller":"traceutil/trace.go:171","msg":"trace[283565851] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"204.602116ms","start":"2026-05-12T20:05:17.960506Z","end":"2026-05-12T20:05:18.165109Z","steps":["trace[283565851] 'process raft request' (duration: 110.652026ms)","trace[283565851] 'compare' (duration: 93.759564ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T20:05:18.165367Z","caller":"traceutil/trace.go:171","msg":"trace[314798054] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"204.566804ms","start":"2026-05-12T20:05:17.960774Z","end":"2026-05-12T20:05:18.165341Z","steps":["trace[314798054] 'process raft request' (duration: 204.289517ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:05:18.165606Z","caller":"traceutil/trace.go:171","msg":"trace[881559692] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"204.118582ms","start":"2026-05-12T20:05:17.961438Z","end":"2026-05-12T20:05:18.165556Z","steps":["trace[881559692] 'process raft request' (duration: 203.833735ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:05:18.812975Z","caller":"traceutil/trace.go:171","msg":"trace[895026574] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"169.824189ms","start":"2026-05-12T20:05:18.643111Z","end":"2026-05-12T20:05:18.812935Z","steps":["trace[895026574] 'process raft request' (duration: 107.72981ms)","trace[895026574] 'compare' (duration: 61.933075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:05:18.97131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.480315ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T20:05:54.253596Z","caller":"traceutil/trace.go:171","msg":"trace[1678225118] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"139.569025ms","start":"2026-05-12T20:05:54.114008Z","end":"2026-05-12T20:05:54.253577Z","steps":["trace[1678225118] 'read index received' (duration: 139.435881ms)","trace[1678225118] 'applied index is now lower than readState.Index' (duration: 132.494µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T20:05:54.253729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.715448ms","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-12T20:05:54.253752Z","caller":"traceutil/trace.go:171","msg":"trace[1389515546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2104; }","duration":"139.78868ms","start":"2026-05-12T20:05:54.113957Z","end":"2026-05-12T20:05:54.253746Z","steps":["trace[1389515546] 'agreement among raft nodes before linearized reading' (duration: 139.687878ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:05:54.253827Z","caller":"traceutil/trace.go:171","msg":"trace[673284106] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"216.561324ms","start":"2026-05-12T20:05:54.037259Z","end":"2026-05-12T20:05:54.25382Z","steps":["trace[673284106] 'process raft request' (duration: 216.190444ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:05:54.610725Z","caller":"traceutil/trace.go:171","msg":"trace[1851996473] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"240.976553ms","start":"2026-05-12T20:05:54.369725Z","end":"2026-05-12T20:05:54.610702Z","steps":["trace[1851996473] 'process raft request' (duration: 240.803238ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:05:54.622905Z","caller":"traceutil/trace.go:171","msg":"trace[1874778885] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"253.132879ms","start":"2026-05-12T20:05:54.369757Z","end":"2026-05-12T20:05:54.622889Z","steps":["trace[1874778885] 'process raft request' (duration: 252.910013ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T20:05:54.622899Z","caller":"traceutil/trace.go:171","msg":"trace[308307339] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"252.093252ms","start":"2026-05-12T20:05:54.370776Z","end":"2026-05-12T20:05:54.62287Z","steps":["trace[308307339] 'process raft request' (duration: 252.044401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T20:06:45.161775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.835913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T20:06:45.161875Z","caller":"traceutil/trace.go:171","msg":"trace[614402312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2387; }","duration":"218.985107ms","start":"2026-05-12T20:06:44.942871Z","end":"2026-05-12T20:06:45.161856Z","steps":["trace[614402312] 'range keys from in-memory index tree' (duration: 218.724651ms)"],"step_count":1}