{"level":"warn","ts":"2026-04-19T13:29:14.187844Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:29:14.18794Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.213: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.19.213.213:2380","--initial-cluster=instance=https://199.19.213.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.213: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-04-19T13:29:14.188167Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-19T13:29:14.188194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-04-19T13:29:14.188253Z","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-04-19T13:29:14.188901Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"]} {"level":"info","ts":"2026-04-19T13:29:14.189105Z","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.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.213: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-04-19T13:29:14.193412Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.999475ms"} {"level":"info","ts":"2026-04-19T13:29:14.198671Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3faf6a3dfbe0100","cluster-id":"4c2e50e35fb92a79"} {"level":"info","ts":"2026-04-19T13:29:14.198854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:29:14.198886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 0"} {"level":"info","ts":"2026-04-19T13:29:14.198963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3faf6a3dfbe0100 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:29:14.199027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became follower at term 1"} {"level":"info","ts":"2026-04-19T13:29:14.199107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"warn","ts":"2026-04-19T13:29:14.204471Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:29:14.20609Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:29:14.210408Z","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-04-19T13:29:14.212676Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3faf6a3dfbe0100","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:29:14.212914Z","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-04-19T13:29:14.213091Z","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-04-19T13:29:14.213104Z","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-04-19T13:29:14.212861Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3faf6a3dfbe0100","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:29:14.215056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 switched to configuration voters=(17580635279088025856)"} {"level":"info","ts":"2026-04-19T13:29:14.215171Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","added-peer-id":"f3faf6a3dfbe0100","added-peer-peer-urls":["https://199.19.213.213:2380"]} {"level":"info","ts":"2026-04-19T13:29:14.216778Z","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-04-19T13:29:14.216888Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-04-19T13:29:14.216956Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.213:2380"} {"level":"info","ts":"2026-04-19T13:29:14.21803Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3faf6a3dfbe0100","initial-advertise-peer-urls":["https://199.19.213.213:2380"],"listen-peer-urls":["https://199.19.213.213:2380"],"advertise-client-urls":["https://199.19.213.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:29:14.218059Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:29:15.09985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:29:15.099922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:29:15.099958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgPreVoteResp from f3faf6a3dfbe0100 at term 1"} {"level":"info","ts":"2026-04-19T13:29:15.09998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:29:15.100009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 received MsgVoteResp from f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-04-19T13:29:15.100032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3faf6a3dfbe0100 became leader at term 2"} {"level":"info","ts":"2026-04-19T13:29:15.10005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3faf6a3dfbe0100 elected leader f3faf6a3dfbe0100 at term 2"} {"level":"info","ts":"2026-04-19T13:29:15.101742Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3faf6a3dfbe0100","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.213:2379]}","request-path":"/0/members/f3faf6a3dfbe0100/attributes","cluster-id":"4c2e50e35fb92a79","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:29:15.101793Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:29:15.102246Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:15.102283Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:29:15.103079Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4c2e50e35fb92a79","local-member-id":"f3faf6a3dfbe0100","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:15.103143Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:15.103162Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:29:15.104229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.213:2379"} {"level":"info","ts":"2026-04-19T13:29:15.105429Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T13:29:15.105779Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:29:15.105858Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:29:38.863706Z","caller":"traceutil/trace.go:171","msg":"trace[467881943] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"131.959348ms","start":"2026-04-19T13:29:38.731698Z","end":"2026-04-19T13:29:38.863657Z","steps":["trace[467881943] 'process raft request' (duration: 131.647081ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:44.875074Z","caller":"traceutil/trace.go:171","msg":"trace[926743283] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"358.87096ms","start":"2026-04-19T13:29:44.516183Z","end":"2026-04-19T13:29:44.875054Z","steps":["trace[926743283] 'process raft request' (duration: 358.733827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.875467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.516161Z","time spent":"358.979163ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:29:44.886758Z","caller":"traceutil/trace.go:171","msg":"trace[897742270] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"362.365002ms","start":"2026-04-19T13:29:44.524377Z","end":"2026-04-19T13:29:44.886742Z","steps":["trace[897742270] 'process raft request' (duration: 362.218649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.886847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.524348Z","time spent":"362.456425ms","remote":"127.0.0.1:42304","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-04-19T13:29:44.886955Z","caller":"traceutil/trace.go:171","msg":"trace[934763345] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"355.586493ms","start":"2026-04-19T13:29:44.53135Z","end":"2026-04-19T13:29:44.886936Z","steps":["trace[934763345] 'process raft request' (duration: 355.411579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.887002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.531341Z","time spent":"355.635694ms","remote":"127.0.0.1:42304","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-04-19T13:29:44.886993Z","caller":"traceutil/trace.go:171","msg":"trace[999000380] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"362.521737ms","start":"2026-04-19T13:29:44.524443Z","end":"2026-04-19T13:29:44.886965Z","steps":["trace[999000380] 'process raft request' (duration: 362.25996ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:44.887055Z","caller":"traceutil/trace.go:171","msg":"trace[1213035710] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"349.879018ms","start":"2026-04-19T13:29:44.537168Z","end":"2026-04-19T13:29:44.887047Z","steps":["trace[1213035710] 'process raft request' (duration: 349.676333ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:44.887095Z","caller":"traceutil/trace.go:171","msg":"trace[1731284104] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"352.428668ms","start":"2026-04-19T13:29:44.534659Z","end":"2026-04-19T13:29:44.887088Z","steps":["trace[1731284104] 'process raft request' (duration: 352.136892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.887112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.537159Z","time spent":"349.929868ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-19T13:29:44.887119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.524436Z","time spent":"362.621769ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:29:44.887032Z","caller":"traceutil/trace.go:171","msg":"trace[1073214008] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"358.05555ms","start":"2026-04-19T13:29:44.528944Z","end":"2026-04-19T13:29:44.887Z","steps":["trace[1073214008] 'process raft request' (duration: 357.785414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.887172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.534652Z","time spent":"352.469199ms","remote":"127.0.0.1:42304","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-04-19T13:29:44.887215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.528935Z","time spent":"358.261896ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:29:44.887298Z","caller":"traceutil/trace.go:171","msg":"trace[1017569284] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"347.619254ms","start":"2026-04-19T13:29:44.539672Z","end":"2026-04-19T13:29:44.887291Z","steps":["trace[1017569284] 'process raft request' (duration: 347.198544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.887332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.539654Z","time spent":"347.660215ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:29:44.887451Z","caller":"traceutil/trace.go:171","msg":"trace[1923917135] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"340.629529ms","start":"2026-04-19T13:29:44.546803Z","end":"2026-04-19T13:29:44.887433Z","steps":["trace[1923917135] 'process raft request' (duration: 340.098816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.88762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.54673Z","time spent":"340.753932ms","remote":"127.0.0.1:42304","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-04-19T13:29:44.887739Z","caller":"traceutil/trace.go:171","msg":"trace[754511467] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:486; }","duration":"289.44249ms","start":"2026-04-19T13:29:44.598285Z","end":"2026-04-19T13:29:44.887727Z","steps":["trace[754511467] 'read index received' (duration: 277.027516ms)","trace[754511467] 'applied index is now lower than readState.Index' (duration: 12.414214ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:29:44.887799Z","caller":"traceutil/trace.go:171","msg":"trace[973993178] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"333.759217ms","start":"2026-04-19T13:29:44.554033Z","end":"2026-04-19T13:29:44.887792Z","steps":["trace[973993178] 'process raft request' (duration: 333.533402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.887846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.554018Z","time spent":"333.799138ms","remote":"127.0.0.1:42304","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-04-19T13:29:44.887989Z","caller":"traceutil/trace.go:171","msg":"trace[62070254] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"187.380238ms","start":"2026-04-19T13:29:44.700594Z","end":"2026-04-19T13:29:44.887974Z","steps":["trace[62070254] 'process raft request' (duration: 187.089642ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:44.888094Z","caller":"traceutil/trace.go:171","msg":"trace[117162716] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"317.787969ms","start":"2026-04-19T13:29:44.570294Z","end":"2026-04-19T13:29:44.888082Z","steps":["trace[117162716] 'process raft request' (duration: 317.340818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.888086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.790098ms","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-04-19T13:29:44.888158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:44.570258Z","time spent":"317.871991ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:29:44.888196Z","caller":"traceutil/trace.go:171","msg":"trace[639024494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:480; }","duration":"289.89134ms","start":"2026-04-19T13:29:44.598242Z","end":"2026-04-19T13:29:44.888133Z","steps":["trace[639024494] 'agreement among raft nodes before linearized reading' (duration: 289.765197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.891494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.451086ms","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-04-19T13:29:44.891542Z","caller":"traceutil/trace.go:171","msg":"trace[770665633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"266.512327ms","start":"2026-04-19T13:29:44.625021Z","end":"2026-04-19T13:29:44.891533Z","steps":["trace[770665633] 'agreement among raft nodes before linearized reading' (duration: 266.426055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:44.891774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.860801ms","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-04-19T13:29:44.891857Z","caller":"traceutil/trace.go:171","msg":"trace[894825512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"270.975973ms","start":"2026-04-19T13:29:44.620865Z","end":"2026-04-19T13:29:44.891841Z","steps":["trace[894825512] 'agreement among raft nodes before linearized reading' (duration: 270.86037ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:49.430483Z","caller":"traceutil/trace.go:171","msg":"trace[299314913] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"133.676598ms","start":"2026-04-19T13:29:49.29677Z","end":"2026-04-19T13:29:49.430447Z","steps":["trace[299314913] 'process raft request' (duration: 132.793117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:51.467926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72230930031893451,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-19T13:29:51.693041Z","caller":"traceutil/trace.go:171","msg":"trace[1198017192] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"725.776058ms","start":"2026-04-19T13:29:50.967238Z","end":"2026-04-19T13:29:51.693014Z","steps":["trace[1198017192] 'read index received' (duration: 724.918598ms)","trace[1198017192] 'applied index is now lower than readState.Index' (duration: 856.23µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:29:51.693737Z","caller":"traceutil/trace.go:171","msg":"trace[1735207744] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"790.968749ms","start":"2026-04-19T13:29:50.902234Z","end":"2026-04-19T13:29:51.693202Z","steps":["trace[1735207744] 'process raft request' (duration: 790.199361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:51.694185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:50.902207Z","time spent":"791.591024ms","remote":"127.0.0.1:42358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:29:51.695576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.31351ms","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-04-19T13:29:51.695657Z","caller":"traceutil/trace.go:171","msg":"trace[2022760065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"709.409772ms","start":"2026-04-19T13:29:50.986221Z","end":"2026-04-19T13:29:51.69563Z","steps":["trace[2022760065] 'agreement among raft nodes before linearized reading' (duration: 708.789208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:51.695711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:50.986173Z","time spent":"709.526545ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T13:29:51.69584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.446711ms","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-04-19T13:29:51.695977Z","caller":"traceutil/trace.go:171","msg":"trace[2001586650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"700.686476ms","start":"2026-04-19T13:29:50.995245Z","end":"2026-04-19T13:29:51.695932Z","steps":["trace[2001586650] 'agreement among raft nodes before linearized reading' (duration: 700.384428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:51.696024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:50.99523Z","time spent":"700.782979ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T13:29:51.696104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.953122ms","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-04-19T13:29:51.696238Z","caller":"traceutil/trace.go:171","msg":"trace[2052992617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"709.112056ms","start":"2026-04-19T13:29:50.987073Z","end":"2026-04-19T13:29:51.696185Z","steps":["trace[2052992617] 'agreement among raft nodes before linearized reading' (duration: 708.751107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:51.696312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:50.987027Z","time spent":"709.258859ms","remote":"127.0.0.1:42520","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-04-19T13:29:51.699362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.053187ms","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-04-19T13:29:51.699514Z","caller":"traceutil/trace.go:171","msg":"trace[1084796303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"732.261682ms","start":"2026-04-19T13:29:50.967196Z","end":"2026-04-19T13:29:51.699458Z","steps":["trace[1084796303] 'agreement among raft nodes before linearized reading' (duration: 727.393177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:51.699629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:50.967176Z","time spent":"732.417326ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-19T13:29:51.872003Z","caller":"traceutil/trace.go:171","msg":"trace[1441768437] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"166.645608ms","start":"2026-04-19T13:29:51.705322Z","end":"2026-04-19T13:29:51.871967Z","steps":["trace[1441768437] 'process raft request' (duration: 166.377112ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:51.8721Z","caller":"traceutil/trace.go:171","msg":"trace[682197573] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"166.568216ms","start":"2026-04-19T13:29:51.705496Z","end":"2026-04-19T13:29:51.872064Z","steps":["trace[682197573] 'process raft request' (duration: 166.355351ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:51.872412Z","caller":"traceutil/trace.go:171","msg":"trace[673673394] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"165.012679ms","start":"2026-04-19T13:29:51.707303Z","end":"2026-04-19T13:29:51.872315Z","steps":["trace[673673394] 'process raft request' (duration: 164.62231ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:51.872601Z","caller":"traceutil/trace.go:171","msg":"trace[306212588] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:598; }","duration":"136.494815ms","start":"2026-04-19T13:29:51.735702Z","end":"2026-04-19T13:29:51.872197Z","steps":["trace[306212588] 'read index received' (duration: 136.091526ms)","trace[306212588] 'applied index is now lower than readState.Index' (duration: 402.159µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:29:51.87277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.043188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sgkps\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-19T13:29:51.872887Z","caller":"traceutil/trace.go:171","msg":"trace[1780141514] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sgkps; range_end:; response_count:1; response_revision:580; }","duration":"137.195122ms","start":"2026-04-19T13:29:51.735658Z","end":"2026-04-19T13:29:51.872853Z","steps":["trace[1780141514] 'agreement among raft nodes before linearized reading' (duration: 136.860344ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:51.878731Z","caller":"traceutil/trace.go:171","msg":"trace[1034553088] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"108.31431ms","start":"2026-04-19T13:29:51.770398Z","end":"2026-04-19T13:29:51.878712Z","steps":["trace[1034553088] 'process raft request' (duration: 108.228948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:52.922448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72230930031893461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:29:53.329862Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.440106088s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T13:29:53.330294Z","caller":"traceutil/trace.go:171","msg":"trace[1193658942] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.440646571s","start":"2026-04-19T13:29:51.88962Z","end":"2026-04-19T13:29:53.330267Z","steps":["trace[1193658942] 'process raft request' (duration: 1.440431826s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:53.330457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:51.88959Z","time spent":"1.440780315s","remote":"127.0.0.1:42458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:29:53.345571Z","caller":"traceutil/trace.go:171","msg":"trace[338360936] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.065844905s","start":"2026-04-19T13:29:52.279683Z","end":"2026-04-19T13:29:53.345528Z","steps":["trace[338360936] 'process raft request' (duration: 1.064665437s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:53.345716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:52.279653Z","time spent":"1.065987569s","remote":"127.0.0.1:42358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:29:53.351302Z","caller":"traceutil/trace.go:171","msg":"trace[144720503] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"928.906959ms","start":"2026-04-19T13:29:52.422379Z","end":"2026-04-19T13:29:53.351286Z","steps":["trace[144720503] 'read index received' (duration: 907.865152ms)","trace[144720503] 'applied index is now lower than readState.Index' (duration: 21.040487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:29:53.351377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.997951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:53.351406Z","caller":"traceutil/trace.go:171","msg":"trace[78244204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"929.065973ms","start":"2026-04-19T13:29:52.42233Z","end":"2026-04-19T13:29:53.351396Z","steps":["trace[78244204] 'agreement among raft nodes before linearized reading' (duration: 929.008671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:53.35143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:52.422311Z","time spent":"929.113324ms","remote":"127.0.0.1:42244","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-04-19T13:29:55.290919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.450531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:29:55.291002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.62099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.291007Z","caller":"traceutil/trace.go:171","msg":"trace[1608227083] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:620; }","duration":"362.557964ms","start":"2026-04-19T13:29:54.928431Z","end":"2026-04-19T13:29:55.290989Z","steps":["trace[1608227083] 'range keys from in-memory index tree' (duration: 362.385389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.291011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.0423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:29:55.291011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.346782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.291052Z","caller":"traceutil/trace.go:171","msg":"trace[638287036] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:620; }","duration":"284.667231ms","start":"2026-04-19T13:29:55.006365Z","end":"2026-04-19T13:29:55.291032Z","steps":["trace[638287036] 'range keys from in-memory index tree' (duration: 284.59027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.291042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.485547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:29:55.291096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:54.928415Z","time spent":"362.630535ms","remote":"127.0.0.1:42568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-19T13:29:55.291101Z","caller":"traceutil/trace.go:171","msg":"trace[1371075141] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:620; }","duration":"363.470415ms","start":"2026-04-19T13:29:54.927603Z","end":"2026-04-19T13:29:55.291074Z","steps":["trace[1371075141] 'range keys from in-memory index tree' (duration: 363.26898ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:55.291122Z","caller":"traceutil/trace.go:171","msg":"trace[1176640871] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:620; }","duration":"284.61372ms","start":"2026-04-19T13:29:55.006484Z","end":"2026-04-19T13:29:55.291098Z","steps":["trace[1176640871] 'range keys from in-memory index tree' (duration: 284.383535ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:55.291085Z","caller":"traceutil/trace.go:171","msg":"trace[978086082] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:620; }","duration":"296.139853ms","start":"2026-04-19T13:29:54.994924Z","end":"2026-04-19T13:29:55.291064Z","steps":["trace[978086082] 'range keys from in-memory index tree' (duration: 296.012319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.291165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:54.927586Z","time spent":"363.563308ms","remote":"127.0.0.1:42568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-19T13:29:55.290935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.122868ms","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":"warn","ts":"2026-04-19T13:29:55.291234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.86479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.291299Z","caller":"traceutil/trace.go:171","msg":"trace[300299787] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:620; }","duration":"284.497317ms","start":"2026-04-19T13:29:55.00676Z","end":"2026-04-19T13:29:55.291257Z","steps":["trace[300299787] 'count revisions from in-memory index tree' (duration: 284.055217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.291369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.010924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.291374Z","caller":"traceutil/trace.go:171","msg":"trace[936498196] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:620; }","duration":"297.084995ms","start":"2026-04-19T13:29:54.994258Z","end":"2026-04-19T13:29:55.291343Z","steps":["trace[936498196] 'count revisions from in-memory index tree' (duration: 296.774737ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:55.291429Z","caller":"traceutil/trace.go:171","msg":"trace[454863567] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:620; }","duration":"297.080215ms","start":"2026-04-19T13:29:54.994341Z","end":"2026-04-19T13:29:55.291421Z","steps":["trace[454863567] 'range keys from in-memory index tree' (duration: 296.993192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.291506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.581127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.291632Z","caller":"traceutil/trace.go:171","msg":"trace[1381376973] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:620; }","duration":"363.875824ms","start":"2026-04-19T13:29:54.92773Z","end":"2026-04-19T13:29:55.291606Z","steps":["trace[1381376973] 'count revisions from in-memory index tree' (duration: 363.430654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.291719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:29:54.927671Z","time spent":"364.032319ms","remote":"127.0.0.1:42568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-19T13:29:55.294798Z","caller":"traceutil/trace.go:171","msg":"trace[2063714778] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"261.236047ms","start":"2026-04-19T13:29:55.033547Z","end":"2026-04-19T13:29:55.294783Z","steps":["trace[2063714778] 'process raft request' (duration: 261.094284ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:55.294803Z","caller":"traceutil/trace.go:171","msg":"trace[1692406071] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:643; }","duration":"192.745767ms","start":"2026-04-19T13:29:55.102026Z","end":"2026-04-19T13:29:55.294772Z","steps":["trace[1692406071] 'read index received' (duration: 192.737477ms)","trace[1692406071] 'applied index is now lower than readState.Index' (duration: 6µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:29:55.295159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.828719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.295229Z","caller":"traceutil/trace.go:171","msg":"trace[760136632] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:621; }","duration":"193.197727ms","start":"2026-04-19T13:29:55.102012Z","end":"2026-04-19T13:29:55.295209Z","steps":["trace[760136632] 'agreement among raft nodes before linearized reading' (duration: 192.819309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.296345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.265433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.296383Z","caller":"traceutil/trace.go:171","msg":"trace[1043355617] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"194.330215ms","start":"2026-04-19T13:29:55.102043Z","end":"2026-04-19T13:29:55.296374Z","steps":["trace[1043355617] 'agreement among raft nodes before linearized reading' (duration: 194.236223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.296581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.207096ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:29:55.296615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.607775ms","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-04-19T13:29:55.296643Z","caller":"traceutil/trace.go:171","msg":"trace[542927915] 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:622; }","duration":"170.648755ms","start":"2026-04-19T13:29:55.125987Z","end":"2026-04-19T13:29:55.296635Z","steps":["trace[542927915] 'agreement among raft nodes before linearized reading' (duration: 170.587434ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:55.296672Z","caller":"traceutil/trace.go:171","msg":"trace[320517941] 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:622; }","duration":"170.280887ms","start":"2026-04-19T13:29:55.126349Z","end":"2026-04-19T13:29:55.29663Z","steps":["trace[320517941] 'agreement among raft nodes before linearized reading' (duration: 170.187775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.296866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.552875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-19T13:29:55.296907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.299344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.296954Z","caller":"traceutil/trace.go:171","msg":"trace[1533637750] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:622; }","duration":"194.350676ms","start":"2026-04-19T13:29:55.102596Z","end":"2026-04-19T13:29:55.296947Z","steps":["trace[1533637750] 'agreement among raft nodes before linearized reading' (duration: 194.297584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.296965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.400306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.297009Z","caller":"traceutil/trace.go:171","msg":"trace[2092813077] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:622; }","duration":"125.466677ms","start":"2026-04-19T13:29:55.171536Z","end":"2026-04-19T13:29:55.297003Z","steps":["trace[2092813077] 'agreement among raft nodes before linearized reading' (duration: 125.389335ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:29:55.296912Z","caller":"traceutil/trace.go:171","msg":"trace[1304229435] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:622; }","duration":"124.602807ms","start":"2026-04-19T13:29:55.172302Z","end":"2026-04-19T13:29:55.296905Z","steps":["trace[1304229435] 'agreement among raft nodes before linearized reading' (duration: 124.539955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.297124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.57681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.297185Z","caller":"traceutil/trace.go:171","msg":"trace[1587006273] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:622; }","duration":"125.663282ms","start":"2026-04-19T13:29:55.171515Z","end":"2026-04-19T13:29:55.297179Z","steps":["trace[1587006273] 'agreement among raft nodes before linearized reading' (duration: 125.58935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:29:55.296879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.001814ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:29:55.297315Z","caller":"traceutil/trace.go:171","msg":"trace[168198429] 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:622; }","duration":"171.439964ms","start":"2026-04-19T13:29:55.125867Z","end":"2026-04-19T13:29:55.297307Z","steps":["trace[168198429] 'agreement among raft nodes before linearized reading' (duration: 170.927932ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:03.330767Z","caller":"traceutil/trace.go:171","msg":"trace[171287646] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"106.323565ms","start":"2026-04-19T13:30:03.224423Z","end":"2026-04-19T13:30:03.330746Z","steps":["trace[171287646] 'process raft request' (duration: 106.187081ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:03.914514Z","caller":"traceutil/trace.go:171","msg":"trace[1392375934] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"139.239403ms","start":"2026-04-19T13:30:03.775246Z","end":"2026-04-19T13:30:03.914485Z","steps":["trace[1392375934] 'process raft request' (duration: 139.047048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:12.064661Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.219218425s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T13:30:12.065015Z","caller":"traceutil/trace.go:171","msg":"trace[2120707300] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"108.350452ms","start":"2026-04-19T13:30:11.956645Z","end":"2026-04-19T13:30:12.064996Z","steps":["trace[2120707300] 'read index received' (duration: 108.148537ms)","trace[2120707300] 'applied index is now lower than readState.Index' (duration: 200.995µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:12.065124Z","caller":"traceutil/trace.go:171","msg":"trace[432278138] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.219792088s","start":"2026-04-19T13:30:10.84531Z","end":"2026-04-19T13:30:12.065102Z","steps":["trace[432278138] 'process raft request' (duration: 1.219549303s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:12.065266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:10.845284Z","time spent":"1.21989285s","remote":"127.0.0.1:42458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:30:12.065307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.001828ms","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-04-19T13:30:12.065353Z","caller":"traceutil/trace.go:171","msg":"trace[183229787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"101.0722ms","start":"2026-04-19T13:30:11.964266Z","end":"2026-04-19T13:30:12.065338Z","steps":["trace[183229787] 'agreement among raft nodes before linearized reading' (duration: 100.865225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:12.065903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.261584ms","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-04-19T13:30:12.065978Z","caller":"traceutil/trace.go:171","msg":"trace[866893082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"109.335026ms","start":"2026-04-19T13:30:11.95663Z","end":"2026-04-19T13:30:12.065965Z","steps":["trace[866893082] 'agreement among raft nodes before linearized reading' (duration: 109.189572ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:42.590421Z","caller":"traceutil/trace.go:171","msg":"trace[1974919261] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"207.540049ms","start":"2026-04-19T13:30:42.382852Z","end":"2026-04-19T13:30:42.590392Z","steps":["trace[1974919261] 'process raft request' (duration: 141.247141ms)","trace[1974919261] 'compare' (duration: 65.834107ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:42.605845Z","caller":"traceutil/trace.go:171","msg":"trace[1634430860] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"187.903284ms","start":"2026-04-19T13:30:42.417921Z","end":"2026-04-19T13:30:42.605824Z","steps":["trace[1634430860] 'process raft request' (duration: 187.801792ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:46.632768Z","caller":"traceutil/trace.go:171","msg":"trace[980846935] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"343.210417ms","start":"2026-04-19T13:30:46.289537Z","end":"2026-04-19T13:30:46.632748Z","steps":["trace[980846935] 'process raft request' (duration: 343.084144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:46.632917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:46.289518Z","time spent":"343.32516ms","remote":"127.0.0.1:42520","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-04-19T13:30:46.633242Z","caller":"traceutil/trace.go:171","msg":"trace[941033608] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:996; }","duration":"210.182501ms","start":"2026-04-19T13:30:46.423028Z","end":"2026-04-19T13:30:46.633211Z","steps":["trace[941033608] 'read index received' (duration: 210.13951ms)","trace[941033608] 'applied index is now lower than readState.Index' (duration: 29µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:30:46.633448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.405786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:30:46.633493Z","caller":"traceutil/trace.go:171","msg":"trace[1986661857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"210.523319ms","start":"2026-04-19T13:30:46.422957Z","end":"2026-04-19T13:30:46.63348Z","steps":["trace[1986661857] 'agreement among raft nodes before linearized reading' (duration: 210.411076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:46.633581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.579249ms","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-04-19T13:30:46.63367Z","caller":"traceutil/trace.go:171","msg":"trace[338399027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"204.691701ms","start":"2026-04-19T13:30:46.428956Z","end":"2026-04-19T13:30:46.633648Z","steps":["trace[338399027] 'agreement among raft nodes before linearized reading' (duration: 204.447455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:47.598175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72230930031894310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:30:47.680779Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.04356489s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-19T13:30:48.19473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.498815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:30:48.19487Z","caller":"traceutil/trace.go:171","msg":"trace[360981853] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.557756291s","start":"2026-04-19T13:30:46.637087Z","end":"2026-04-19T13:30:48.194844Z","steps":["trace[360981853] 'process raft request' (duration: 1.043846786s)","trace[360981853] 'compare' (duration: 512.310487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:30:48.194955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:46.637047Z","time spent":"1.557865293s","remote":"127.0.0.1:42520","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-04-19T13:30:48.208668Z","caller":"traceutil/trace.go:171","msg":"trace[1305675889] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:996; }","duration":"1.111029356s","start":"2026-04-19T13:30:47.097617Z","end":"2026-04-19T13:30:48.208647Z","steps":["trace[1305675889] 'read index received' (duration: 583.293215ms)","trace[1305675889] 'applied index is now lower than readState.Index' (duration: 527.735261ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:48.208684Z","caller":"traceutil/trace.go:171","msg":"trace[1084664024] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.570714088s","start":"2026-04-19T13:30:46.637954Z","end":"2026-04-19T13:30:48.208668Z","steps":["trace[1084664024] 'process raft request' (duration: 1.570509723s)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:48.208807Z","caller":"traceutil/trace.go:171","msg":"trace[1321052018] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.107655617s","start":"2026-04-19T13:30:47.101104Z","end":"2026-04-19T13:30:48.20876Z","steps":["trace[1321052018] 'process raft request' (duration: 1.107510754s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:48.208901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:47.101089Z","time spent":"1.107764329s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:30:48.208919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111271732s","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-04-19T13:30:48.20895Z","caller":"traceutil/trace.go:171","msg":"trace[1934095706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"1.111358754s","start":"2026-04-19T13:30:47.097583Z","end":"2026-04-19T13:30:48.208942Z","steps":["trace[1934095706] 'agreement among raft nodes before linearized reading' (duration: 1.11118153s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:48.208942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:46.637935Z","time spent":"1.570932973s","remote":"127.0.0.1:42520","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-04-19T13:30:48.208973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:47.097562Z","time spent":"1.111405435s","remote":"127.0.0.1:42520","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":"info","ts":"2026-04-19T13:30:48.208973Z","caller":"traceutil/trace.go:171","msg":"trace[667175688] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.570916063s","start":"2026-04-19T13:30:46.638049Z","end":"2026-04-19T13:30:48.208965Z","steps":["trace[667175688] 'process raft request' (duration: 1.570540394s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:48.209064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:46.638037Z","time spent":"1.570965924s","remote":"127.0.0.1:42520","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-04-19T13:30:48.221721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.558987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:30:48.221781Z","caller":"traceutil/trace.go:171","msg":"trace[530930848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"798.649399ms","start":"2026-04-19T13:30:47.423117Z","end":"2026-04-19T13:30:48.221766Z","steps":["trace[530930848] 'agreement among raft nodes before linearized reading' (duration: 798.534736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:48.221812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:47.423099Z","time spent":"798.70458ms","remote":"127.0.0.1:42244","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-04-19T13:30:48.222063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.173478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.213\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-19T13:30:48.222085Z","caller":"traceutil/trace.go:171","msg":"trace[1728152609] range","detail":"{range_begin:/registry/masterleases/199.19.213.213; range_end:; response_count:1; response_revision:966; }","duration":"256.23103ms","start":"2026-04-19T13:30:47.965846Z","end":"2026-04-19T13:30:48.222077Z","steps":["trace[1728152609] 'agreement among raft nodes before linearized reading' (duration: 256.122567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:49.773314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72230930031894326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:30:49.882155Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.238181572s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T13:30:49.882596Z","caller":"traceutil/trace.go:171","msg":"trace[1249771921] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"610.110779ms","start":"2026-04-19T13:30:49.272457Z","end":"2026-04-19T13:30:49.882568Z","steps":["trace[1249771921] 'read index received' (duration: 609.893564ms)","trace[1249771921] 'applied index is now lower than readState.Index' (duration: 215.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:49.882668Z","caller":"traceutil/trace.go:171","msg":"trace[1621484270] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.240844914s","start":"2026-04-19T13:30:48.641806Z","end":"2026-04-19T13:30:49.882651Z","steps":["trace[1621484270] 'process raft request' (duration: 1.240576818s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:49.882732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.248412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T13:30:49.882762Z","caller":"traceutil/trace.go:171","msg":"trace[698704321] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:969; }","duration":"610.346345ms","start":"2026-04-19T13:30:49.272407Z","end":"2026-04-19T13:30:49.882754Z","steps":["trace[698704321] 'agreement among raft nodes before linearized reading' (duration: 610.256413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:49.882788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:49.27239Z","time spent":"610.389365ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:30:49.8828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:48.641789Z","time spent":"1.240915996s","remote":"127.0.0.1:42520","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-04-19T13:30:49.882973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.359267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:30:49.883373Z","caller":"traceutil/trace.go:171","msg":"trace[989226079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"460.929721ms","start":"2026-04-19T13:30:49.422421Z","end":"2026-04-19T13:30:49.883351Z","steps":["trace[989226079] 'agreement among raft nodes before linearized reading' (duration: 460.340867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:49.883425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:49.422403Z","time spent":"461.010553ms","remote":"127.0.0.1:42244","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-04-19T13:30:50.37467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.504341ms","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-04-19T13:30:50.374743Z","caller":"traceutil/trace.go:171","msg":"trace[642337808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"175.610953ms","start":"2026-04-19T13:30:50.199113Z","end":"2026-04-19T13:30:50.374724Z","steps":["trace[642337808] 'range keys from in-memory index tree' (duration: 175.374438ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:50.375361Z","caller":"traceutil/trace.go:171","msg":"trace[777407913] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"162.450232ms","start":"2026-04-19T13:30:50.212892Z","end":"2026-04-19T13:30:50.375342Z","steps":["trace[777407913] 'process raft request' (duration: 154.413312ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:50.375354Z","caller":"traceutil/trace.go:171","msg":"trace[1924528833] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"161.697874ms","start":"2026-04-19T13:30:50.213635Z","end":"2026-04-19T13:30:50.375332Z","steps":["trace[1924528833] 'read index received' (duration: 153.625653ms)","trace[1924528833] 'applied index is now lower than readState.Index' (duration: 8.070881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:30:50.375499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.858007ms","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-04-19T13:30:50.375549Z","caller":"traceutil/trace.go:171","msg":"trace[1143756247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"161.93127ms","start":"2026-04-19T13:30:50.213605Z","end":"2026-04-19T13:30:50.375536Z","steps":["trace[1143756247] 'agreement among raft nodes before linearized reading' (duration: 161.783166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:50.375611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.872378ms","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-04-19T13:30:50.375645Z","caller":"traceutil/trace.go:171","msg":"trace[559306948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"161.94862ms","start":"2026-04-19T13:30:50.213685Z","end":"2026-04-19T13:30:50.375634Z","steps":["trace[559306948] 'agreement among raft nodes before linearized reading' (duration: 161.782126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:50.375792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.99839ms","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-04-19T13:30:50.375958Z","caller":"traceutil/trace.go:171","msg":"trace[931460176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"140.202266ms","start":"2026-04-19T13:30:50.23572Z","end":"2026-04-19T13:30:50.375922Z","steps":["trace[931460176] 'agreement among raft nodes before linearized reading' (duration: 139.882768ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:04.098566Z","caller":"traceutil/trace.go:171","msg":"trace[1030031103] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"149.547508ms","start":"2026-04-19T13:31:03.948996Z","end":"2026-04-19T13:31:04.098544Z","steps":["trace[1030031103] 'process raft request' (duration: 149.390074ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:04.555066Z","caller":"traceutil/trace.go:171","msg":"trace[359262272] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"101.73883ms","start":"2026-04-19T13:31:04.453297Z","end":"2026-04-19T13:31:04.555036Z","steps":["trace[359262272] 'process raft request' (duration: 38.856486ms)","trace[359262272] 'compare' (duration: 62.653059ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:04.555312Z","caller":"traceutil/trace.go:171","msg":"trace[1651120247] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"101.948215ms","start":"2026-04-19T13:31:04.453336Z","end":"2026-04-19T13:31:04.555284Z","steps":["trace[1651120247] 'process raft request' (duration: 101.647698ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:04.555316Z","caller":"traceutil/trace.go:171","msg":"trace[380034351] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"100.671376ms","start":"2026-04-19T13:31:04.454632Z","end":"2026-04-19T13:31:04.555303Z","steps":["trace[380034351] 'process raft request' (duration: 100.642335ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:04.555426Z","caller":"traceutil/trace.go:171","msg":"trace[2102643984] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"101.665729ms","start":"2026-04-19T13:31:04.453736Z","end":"2026-04-19T13:31:04.555401Z","steps":["trace[2102643984] 'process raft request' (duration: 101.458024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.076955Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72230930031894497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:31:11.567198Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.102390349s","expected-duration":"1s"} {"level":"info","ts":"2026-04-19T13:31:11.567862Z","caller":"traceutil/trace.go:171","msg":"trace[922344242] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"991.708598ms","start":"2026-04-19T13:31:10.57612Z","end":"2026-04-19T13:31:11.567828Z","steps":["trace[922344242] 'read index received' (duration: 991.373261ms)","trace[922344242] 'applied index is now lower than readState.Index' (duration: 333.727µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:11.568044Z","caller":"traceutil/trace.go:171","msg":"trace[271037710] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.103347581s","start":"2026-04-19T13:31:10.464681Z","end":"2026-04-19T13:31:11.568028Z","steps":["trace[271037710] 'process raft request' (duration: 1.102942682s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.568176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:10.464655Z","time spent":"1.103429844s","remote":"127.0.0.1:42520","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-04-19T13:31:11.568366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.097317ms","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":"warn","ts":"2026-04-19T13:31:11.568381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.253201ms","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-04-19T13:31:11.568419Z","caller":"traceutil/trace.go:171","msg":"trace[1312913599] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1036; }","duration":"811.179918ms","start":"2026-04-19T13:31:10.757222Z","end":"2026-04-19T13:31:11.568402Z","steps":["trace[1312913599] 'agreement among raft nodes before linearized reading' (duration: 811.092176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.568459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:10.757204Z","time spent":"811.24366ms","remote":"127.0.0.1:42798","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-04-19T13:31:11.568556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.737965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:11.568615Z","caller":"traceutil/trace.go:171","msg":"trace[754170670] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1036; }","duration":"339.829988ms","start":"2026-04-19T13:31:11.228773Z","end":"2026-04-19T13:31:11.568603Z","steps":["trace[754170670] 'agreement among raft nodes before linearized reading' (duration: 339.730665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.568689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:11.228754Z","time spent":"339.92451ms","remote":"127.0.0.1:42392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:31:11.568629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.410781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:11.569186Z","caller":"traceutil/trace.go:171","msg":"trace[1691629665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"146.001105ms","start":"2026-04-19T13:31:11.423157Z","end":"2026-04-19T13:31:11.569158Z","steps":["trace[1691629665] 'agreement among raft nodes before linearized reading' (duration: 145.306498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.568932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.644482ms","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-04-19T13:31:11.569429Z","caller":"traceutil/trace.go:171","msg":"trace[1096801291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1036; }","duration":"988.167645ms","start":"2026-04-19T13:31:10.581206Z","end":"2026-04-19T13:31:11.569374Z","steps":["trace[1096801291] 'agreement among raft nodes before linearized reading' (duration: 987.11438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.569543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:10.581193Z","time spent":"988.335749ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T13:31:11.569018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.111439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T13:31:11.569738Z","caller":"traceutil/trace.go:171","msg":"trace[1633779921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"987.845057ms","start":"2026-04-19T13:31:10.581881Z","end":"2026-04-19T13:31:11.569726Z","steps":["trace[1633779921] 'agreement among raft nodes before linearized reading' (duration: 987.006437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.569778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:10.581873Z","time spent":"987.895048ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-19T13:31:11.569039Z","caller":"traceutil/trace.go:171","msg":"trace[115259600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"992.374404ms","start":"2026-04-19T13:31:10.576057Z","end":"2026-04-19T13:31:11.568432Z","steps":["trace[115259600] 'agreement among raft nodes before linearized reading' (duration: 992.165469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.569933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:10.576035Z","time spent":"993.87191ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T13:31:11.569085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.317055ms","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-04-19T13:31:11.570048Z","caller":"traceutil/trace.go:171","msg":"trace[2077014595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1036; }","duration":"988.318039ms","start":"2026-04-19T13:31:10.581716Z","end":"2026-04-19T13:31:11.570034Z","steps":["trace[2077014595] 'agreement among raft nodes before linearized reading' (duration: 987.273974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:11.570086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:10.581701Z","time spent":"988.37699ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-19T13:31:11.689481Z","caller":"traceutil/trace.go:171","msg":"trace[1414537060] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"113.934918ms","start":"2026-04-19T13:31:11.575512Z","end":"2026-04-19T13:31:11.689447Z","steps":["trace[1414537060] 'process raft request' (duration: 113.697452ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:11.689697Z","caller":"traceutil/trace.go:171","msg":"trace[667349694] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"113.1537ms","start":"2026-04-19T13:31:11.576525Z","end":"2026-04-19T13:31:11.689679Z","steps":["trace[667349694] 'process raft request' (duration: 112.868223ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:11.689759Z","caller":"traceutil/trace.go:171","msg":"trace[464470160] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"112.627217ms","start":"2026-04-19T13:31:11.577111Z","end":"2026-04-19T13:31:11.689738Z","steps":["trace[464470160] 'process raft request' (duration: 112.498114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:13.643241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.407086ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-19T13:31:13.64371Z","caller":"traceutil/trace.go:171","msg":"trace[251624246] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1082; }","duration":"221.155348ms","start":"2026-04-19T13:31:13.422534Z","end":"2026-04-19T13:31:13.643689Z","steps":["trace[251624246] 'read index received' (duration: 79.155097ms)","trace[251624246] 'applied index is now lower than readState.Index' (duration: 141.997681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:13.643839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.306022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:13.643938Z","caller":"traceutil/trace.go:171","msg":"trace[1107957236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"221.436345ms","start":"2026-04-19T13:31:13.422479Z","end":"2026-04-19T13:31:13.643915Z","steps":["trace[1107957236] 'agreement among raft nodes before linearized reading' (duration: 221.290902ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:13.813642Z","caller":"traceutil/trace.go:171","msg":"trace[385070680] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"118.304401ms","start":"2026-04-19T13:31:13.69531Z","end":"2026-04-19T13:31:13.813614Z","steps":["trace[385070680] 'read index received' (duration: 118.008904ms)","trace[385070680] 'applied index is now lower than readState.Index' (duration: 294.127µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:13.813874Z","caller":"traceutil/trace.go:171","msg":"trace[1114639932] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"161.584202ms","start":"2026-04-19T13:31:13.652217Z","end":"2026-04-19T13:31:13.813801Z","steps":["trace[1114639932] 'process raft request' (duration: 161.210144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:13.814045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.686141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-19T13:31:13.81402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.66886ms","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-04-19T13:31:13.814095Z","caller":"traceutil/trace.go:171","msg":"trace[1321124883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"118.818653ms","start":"2026-04-19T13:31:13.695264Z","end":"2026-04-19T13:31:13.814083Z","steps":["trace[1321124883] 'agreement among raft nodes before linearized reading' (duration: 118.466575ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:13.814128Z","caller":"traceutil/trace.go:171","msg":"trace[1247204228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"118.819574ms","start":"2026-04-19T13:31:13.695282Z","end":"2026-04-19T13:31:13.814101Z","steps":["trace[1247204228] 'agreement among raft nodes before linearized reading' (duration: 118.359863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:13.814234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.887896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T13:31:13.814343Z","caller":"traceutil/trace.go:171","msg":"trace[1081726164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"119.06447ms","start":"2026-04-19T13:31:13.695253Z","end":"2026-04-19T13:31:13.814318Z","steps":["trace[1081726164] 'agreement among raft nodes before linearized reading' (duration: 118.516727ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:15.799169Z","caller":"traceutil/trace.go:171","msg":"trace[896154213] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"149.268491ms","start":"2026-04-19T13:31:15.649862Z","end":"2026-04-19T13:31:15.799131Z","steps":["trace[896154213] 'process raft request' (duration: 149.131138ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:15.938362Z","caller":"traceutil/trace.go:171","msg":"trace[2073851556] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"101.660448ms","start":"2026-04-19T13:31:15.836682Z","end":"2026-04-19T13:31:15.938342Z","steps":["trace[2073851556] 'read index received' (duration: 83.639573ms)","trace[2073851556] 'applied index is now lower than readState.Index' (duration: 18.019945ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:15.938693Z","caller":"traceutil/trace.go:171","msg":"trace[2059213398] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"117.227926ms","start":"2026-04-19T13:31:15.82143Z","end":"2026-04-19T13:31:15.938658Z","steps":["trace[2059213398] 'process raft request' (duration: 98.865372ms)","trace[2059213398] 'compare' (duration: 17.962464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:15.938815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.074798ms","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-04-19T13:31:15.938896Z","caller":"traceutil/trace.go:171","msg":"trace[2143013591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"102.239492ms","start":"2026-04-19T13:31:15.836643Z","end":"2026-04-19T13:31:15.938883Z","steps":["trace[2143013591] 'agreement among raft nodes before linearized reading' (duration: 101.992176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:26.65041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.780558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:31:26.650487Z","caller":"traceutil/trace.go:171","msg":"trace[2079003424] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"227.553969ms","start":"2026-04-19T13:31:26.42292Z","end":"2026-04-19T13:31:26.650474Z","steps":["trace[2079003424] 'read index received' (duration: 23.639848ms)","trace[2079003424] 'applied index is now lower than readState.Index' (duration: 203.913331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:26.650546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.630851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:26.650565Z","caller":"traceutil/trace.go:171","msg":"trace[1393909127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"227.685452ms","start":"2026-04-19T13:31:26.422873Z","end":"2026-04-19T13:31:26.650558Z","steps":["trace[1393909127] 'agreement among raft nodes before linearized reading' (duration: 227.632571ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:26.650734Z","caller":"traceutil/trace.go:171","msg":"trace[345272562] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"446.204938ms","start":"2026-04-19T13:31:26.204511Z","end":"2026-04-19T13:31:26.650716Z","steps":["trace[345272562] 'process raft request' (duration: 242.049261ms)","trace[345272562] 'compare' (duration: 203.581273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:26.650789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:26.204494Z","time spent":"446.26883ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:31:28.052093Z","caller":"traceutil/trace.go:171","msg":"trace[1679183423] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"171.888746ms","start":"2026-04-19T13:31:27.880188Z","end":"2026-04-19T13:31:28.052077Z","steps":["trace[1679183423] 'process raft request' (duration: 171.608609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:28.612352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72230930031894730,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:31:28.904059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:28.05313Z","time spent":"850.923095ms","remote":"127.0.0.1:42282","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-19T13:31:28.906735Z","caller":"traceutil/trace.go:171","msg":"trace[1177749671] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1184; }","duration":"795.357255ms","start":"2026-04-19T13:31:28.111358Z","end":"2026-04-19T13:31:28.906716Z","steps":["trace[1177749671] 'read index received' (duration: 789.364253ms)","trace[1177749671] 'applied index is now lower than readState.Index' (duration: 5.991942ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:28.906921Z","caller":"traceutil/trace.go:171","msg":"trace[1519134369] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"850.578457ms","start":"2026-04-19T13:31:28.056315Z","end":"2026-04-19T13:31:28.906893Z","steps":["trace[1519134369] 'process raft request' (duration: 850.333891ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:28.906992Z","caller":"traceutil/trace.go:171","msg":"trace[1354260906] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"291.264452ms","start":"2026-04-19T13:31:28.615716Z","end":"2026-04-19T13:31:28.90698Z","steps":["trace[1354260906] 'process raft request' (duration: 290.968315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:28.907059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:28.056283Z","time spent":"850.69074ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:31:28.907232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.857746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-19T13:31:28.907274Z","caller":"traceutil/trace.go:171","msg":"trace[354110227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1142; }","duration":"795.951339ms","start":"2026-04-19T13:31:28.111312Z","end":"2026-04-19T13:31:28.907263Z","steps":["trace[354110227] 'agreement among raft nodes before linearized reading' (duration: 795.824016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:28.907309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:28.111292Z","time spent":"796.009539ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-19T13:31:28.907236Z","caller":"traceutil/trace.go:171","msg":"trace[1064528551] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"851.009338ms","start":"2026-04-19T13:31:28.056214Z","end":"2026-04-19T13:31:28.907223Z","steps":["trace[1064528551] 'process raft request' (duration: 850.27492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:28.907445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:28.056198Z","time spent":"851.197412ms","remote":"127.0.0.1:42520","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-04-19T13:31:28.907493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.527868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-19T13:31:28.907565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.977479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:28.907574Z","caller":"traceutil/trace.go:171","msg":"trace[285324904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1143; }","duration":"795.646311ms","start":"2026-04-19T13:31:28.111909Z","end":"2026-04-19T13:31:28.907555Z","steps":["trace[285324904] 'agreement among raft nodes before linearized reading' (duration: 795.418886ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:28.907599Z","caller":"traceutil/trace.go:171","msg":"trace[1991366519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1143; }","duration":"484.061781ms","start":"2026-04-19T13:31:28.423529Z","end":"2026-04-19T13:31:28.907591Z","steps":["trace[1991366519] 'agreement among raft nodes before linearized reading' (duration: 484.01193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:28.907621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:28.111857Z","time spent":"795.754334ms","remote":"127.0.0.1:42520","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-04-19T13:31:28.90763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:28.423498Z","time spent":"484.127592ms","remote":"127.0.0.1:42244","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-04-19T13:31:28.907731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.712976ms","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":"warn","ts":"2026-04-19T13:31:28.907505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.584033ms","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-04-19T13:31:28.907768Z","caller":"traceutil/trace.go:171","msg":"trace[576804478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1143; }","duration":"562.786368ms","start":"2026-04-19T13:31:28.344969Z","end":"2026-04-19T13:31:28.907756Z","steps":["trace[576804478] 'agreement among raft nodes before linearized reading' (duration: 562.739397ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:28.907773Z","caller":"traceutil/trace.go:171","msg":"trace[22593250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1143; }","duration":"253.89999ms","start":"2026-04-19T13:31:28.653864Z","end":"2026-04-19T13:31:28.907764Z","steps":["trace[22593250] 'agreement among raft nodes before linearized reading' (duration: 253.504421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:28.907796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:28.34495Z","time spent":"562.839059ms","remote":"127.0.0.1:42786","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-04-19T13:31:45.809892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.505349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T13:31:45.810003Z","caller":"traceutil/trace.go:171","msg":"trace[1370751215] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1213; }","duration":"848.652773ms","start":"2026-04-19T13:31:44.96133Z","end":"2026-04-19T13:31:45.809983Z","steps":["trace[1370751215] 'count revisions from in-memory index tree' (duration: 848.441277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.810055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:44.961315Z","time spent":"848.728044ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:31:45.810325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.839372ms","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-04-19T13:31:45.810362Z","caller":"traceutil/trace.go:171","msg":"trace[2042882908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1213; }","duration":"780.875373ms","start":"2026-04-19T13:31:45.029477Z","end":"2026-04-19T13:31:45.810352Z","steps":["trace[2042882908] 'range keys from in-memory index tree' (duration: 780.72495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.810391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.029432Z","time spent":"780.952175ms","remote":"127.0.0.1:42520","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-04-19T13:31:45.810617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.061608ms","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-04-19T13:31:45.810666Z","caller":"traceutil/trace.go:171","msg":"trace[63393576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1213; }","duration":"781.129239ms","start":"2026-04-19T13:31:45.029517Z","end":"2026-04-19T13:31:45.810647Z","steps":["trace[63393576] 'range keys from in-memory index tree' (duration: 780.913784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.810699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.029496Z","time spent":"781.196351ms","remote":"127.0.0.1:42520","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-04-19T13:31:45.810862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.271933ms","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-04-19T13:31:45.810893Z","caller":"traceutil/trace.go:171","msg":"trace[2081508932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"781.347565ms","start":"2026-04-19T13:31:45.029538Z","end":"2026-04-19T13:31:45.810885Z","steps":["trace[2081508932] 'range keys from in-memory index tree' (duration: 781.15749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.810917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.029514Z","time spent":"781.397825ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T13:31:45.811069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.926795ms","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-04-19T13:31:45.811106Z","caller":"traceutil/trace.go:171","msg":"trace[10739638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"780.984286ms","start":"2026-04-19T13:31:45.030113Z","end":"2026-04-19T13:31:45.811098Z","steps":["trace[10739638] 'range keys from in-memory index tree' (duration: 780.832322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.811136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.030104Z","time spent":"781.023108ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-19T13:31:45.811279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.658101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T13:31:45.811308Z","caller":"traceutil/trace.go:171","msg":"trace[1612147083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"778.701443ms","start":"2026-04-19T13:31:45.032599Z","end":"2026-04-19T13:31:45.811301Z","steps":["trace[1612147083] 'range keys from in-memory index tree' (duration: 778.58447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.811333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.032589Z","time spent":"778.738223ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-19T13:31:45.811577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.274958Z","time spent":"536.617ms","remote":"127.0.0.1:37262","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-19T13:31:45.811777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.64133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:45.811812Z","caller":"traceutil/trace.go:171","msg":"trace[1731541226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"388.70088ms","start":"2026-04-19T13:31:45.423103Z","end":"2026-04-19T13:31:45.811804Z","steps":["trace[1731541226] 'range keys from in-memory index tree' (duration: 388.593169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.811843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.423088Z","time spent":"388.748451ms","remote":"127.0.0.1:42244","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-04-19T13:31:45.812123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.208895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:45.812163Z","caller":"traceutil/trace.go:171","msg":"trace[6923292] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1213; }","duration":"320.294957ms","start":"2026-04-19T13:31:45.491857Z","end":"2026-04-19T13:31:45.812152Z","steps":["trace[6923292] 'count revisions from in-memory index tree' (duration: 320.155334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:45.812193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.491843Z","time spent":"320.342347ms","remote":"127.0.0.1:42484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-19T13:31:46.138836Z","caller":"traceutil/trace.go:171","msg":"trace[1021296043] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"319.226741ms","start":"2026-04-19T13:31:45.819586Z","end":"2026-04-19T13:31:46.138813Z","steps":["trace[1021296043] 'read index received' (duration: 319.113188ms)","trace[1021296043] 'applied index is now lower than readState.Index' (duration: 112.183µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:46.139001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.379375ms","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-04-19T13:31:46.138985Z","caller":"traceutil/trace.go:171","msg":"trace[882751990] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"320.024019ms","start":"2026-04-19T13:31:45.818923Z","end":"2026-04-19T13:31:46.138947Z","steps":["trace[882751990] 'process raft request' (duration: 319.735632ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:46.139038Z","caller":"traceutil/trace.go:171","msg":"trace[1115110231] 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:1214; }","duration":"319.456916ms","start":"2026-04-19T13:31:45.819568Z","end":"2026-04-19T13:31:46.139025Z","steps":["trace[1115110231] 'agreement among raft nodes before linearized reading' (duration: 319.340733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:46.139079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.819562Z","time spent":"319.505687ms","remote":"127.0.0.1:42602","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-04-19T13:31:46.139187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.818906Z","time spent":"320.152203ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:31:46.148103Z","caller":"traceutil/trace.go:171","msg":"trace[1685625837] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"328.188472ms","start":"2026-04-19T13:31:45.819899Z","end":"2026-04-19T13:31:46.148087Z","steps":["trace[1685625837] 'process raft request' (duration: 328.159291ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:46.148169Z","caller":"traceutil/trace.go:171","msg":"trace[1186684291] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"328.421048ms","start":"2026-04-19T13:31:45.819741Z","end":"2026-04-19T13:31:46.148162Z","steps":["trace[1186684291] 'process raft request' (duration: 328.295555ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:46.148192Z","caller":"traceutil/trace.go:171","msg":"trace[1364411705] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"328.454189ms","start":"2026-04-19T13:31:45.819715Z","end":"2026-04-19T13:31:46.148169Z","steps":["trace[1364411705] 'process raft request' (duration: 328.296655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:46.14822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.819893Z","time spent":"328.265915ms","remote":"127.0.0.1:42520","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-04-19T13:31:46.14827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.819738Z","time spent":"328.464119ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:31:46.148307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.819711Z","time spent":"328.5264ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:31:46.14811Z","caller":"traceutil/trace.go:171","msg":"trace[1462037035] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"328.406788ms","start":"2026-04-19T13:31:45.819676Z","end":"2026-04-19T13:31:46.148082Z","steps":["trace[1462037035] 'process raft request' (duration: 328.238994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:46.148382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:45.819671Z","time spent":"328.689874ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:31:46.659678Z","caller":"traceutil/trace.go:171","msg":"trace[2100509301] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"178.622045ms","start":"2026-04-19T13:31:46.481032Z","end":"2026-04-19T13:31:46.659654Z","steps":["trace[2100509301] 'process raft request' (duration: 178.472251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:48.289439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.332541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:31:48.289581Z","caller":"traceutil/trace.go:171","msg":"trace[658369527] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"143.175438ms","start":"2026-04-19T13:31:48.146368Z","end":"2026-04-19T13:31:48.289544Z","steps":["trace[658369527] 'process raft request' (duration: 41.643642ms)","trace[658369527] 'compare' (duration: 101.207508ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:48.289663Z","caller":"traceutil/trace.go:171","msg":"trace[1480662512] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"138.441127ms","start":"2026-04-19T13:31:48.151183Z","end":"2026-04-19T13:31:48.289624Z","steps":["trace[1480662512] 'read index received' (duration: 36.839799ms)","trace[1480662512] 'applied index is now lower than readState.Index' (duration: 101.599658ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:48.289611Z","caller":"traceutil/trace.go:171","msg":"trace[1138188478] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"104.055575ms","start":"2026-04-19T13:31:48.185542Z","end":"2026-04-19T13:31:48.289597Z","steps":["trace[1138188478] 'process raft request' (duration: 104.003114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:48.289881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.326203ms","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-04-19T13:31:48.289911Z","caller":"traceutil/trace.go:171","msg":"trace[173779411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1223; }","duration":"138.392115ms","start":"2026-04-19T13:31:48.151511Z","end":"2026-04-19T13:31:48.289903Z","steps":["trace[173779411] 'agreement among raft nodes before linearized reading' (duration: 138.257912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:48.289955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.758124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-19T13:31:48.290008Z","caller":"traceutil/trace.go:171","msg":"trace[206384708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"138.833675ms","start":"2026-04-19T13:31:48.151158Z","end":"2026-04-19T13:31:48.289992Z","steps":["trace[206384708] 'agreement among raft nodes before linearized reading' (duration: 138.59157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:48.290085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.276859ms","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-04-19T13:31:48.290105Z","caller":"traceutil/trace.go:171","msg":"trace[296130284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1223; }","duration":"137.33398ms","start":"2026-04-19T13:31:48.152765Z","end":"2026-04-19T13:31:48.290099Z","steps":["trace[296130284] 'agreement among raft nodes before linearized reading' (duration: 137.276799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:48.290213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.652581ms","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-04-19T13:31:48.290265Z","caller":"traceutil/trace.go:171","msg":"trace[382150519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1223; }","duration":"138.722253ms","start":"2026-04-19T13:31:48.151529Z","end":"2026-04-19T13:31:48.290251Z","steps":["trace[382150519] 'agreement among raft nodes before linearized reading' (duration: 138.598131ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:48.451786Z","caller":"traceutil/trace.go:171","msg":"trace[483720435] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"158.022538ms","start":"2026-04-19T13:31:48.293742Z","end":"2026-04-19T13:31:48.451764Z","steps":["trace[483720435] 'process raft request' (duration: 102.642371ms)","trace[483720435] 'compare' (duration: 55.119801ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:48.452047Z","caller":"traceutil/trace.go:171","msg":"trace[98040640] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"157.499096ms","start":"2026-04-19T13:31:48.294529Z","end":"2026-04-19T13:31:48.452028Z","steps":["trace[98040640] 'process raft request' (duration: 157.167978ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:48.452188Z","caller":"traceutil/trace.go:171","msg":"trace[224755083] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"156.990214ms","start":"2026-04-19T13:31:48.295185Z","end":"2026-04-19T13:31:48.452176Z","steps":["trace[224755083] 'process raft request' (duration: 156.704947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:48.731931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.90164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:31:48.732154Z","caller":"traceutil/trace.go:171","msg":"trace[1399947826] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"271.611449ms","start":"2026-04-19T13:31:48.460508Z","end":"2026-04-19T13:31:48.73212Z","steps":["trace[1399947826] 'process raft request' (duration: 162.424913ms)","trace[1399947826] 'compare' (duration: 108.702344ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:48.957883Z","caller":"traceutil/trace.go:171","msg":"trace[1960289203] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"188.047447ms","start":"2026-04-19T13:31:48.769817Z","end":"2026-04-19T13:31:48.957865Z","steps":["trace[1960289203] 'process raft request' (duration: 188.010606ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:48.958059Z","caller":"traceutil/trace.go:171","msg":"trace[1002760022] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"218.426753ms","start":"2026-04-19T13:31:48.739617Z","end":"2026-04-19T13:31:48.958044Z","steps":["trace[1002760022] 'process raft request' (duration: 218.120496ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:49.599834Z","caller":"traceutil/trace.go:171","msg":"trace[1804819958] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"278.579622ms","start":"2026-04-19T13:31:49.321206Z","end":"2026-04-19T13:31:49.599786Z","steps":["trace[1804819958] 'process raft request' (duration: 278.314936ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:50.559657Z","caller":"traceutil/trace.go:171","msg":"trace[448928597] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"263.519868ms","start":"2026-04-19T13:31:50.296115Z","end":"2026-04-19T13:31:50.559635Z","steps":["trace[448928597] 'process raft request' (duration: 263.402245ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:50.575017Z","caller":"traceutil/trace.go:171","msg":"trace[1590198407] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"250.673574ms","start":"2026-04-19T13:31:50.324326Z","end":"2026-04-19T13:31:50.575Z","steps":["trace[1590198407] 'read index received' (duration: 235.354633ms)","trace[1590198407] 'applied index is now lower than readState.Index' (duration: 15.318221ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:50.575172Z","caller":"traceutil/trace.go:171","msg":"trace[1192235731] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"278.127742ms","start":"2026-04-19T13:31:50.296989Z","end":"2026-04-19T13:31:50.575117Z","steps":["trace[1192235731] 'process raft request' (duration: 277.892176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:50.575213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.655582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:50.575284Z","caller":"traceutil/trace.go:171","msg":"trace[1643520882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"152.782135ms","start":"2026-04-19T13:31:50.422466Z","end":"2026-04-19T13:31:50.575248Z","steps":["trace[1643520882] 'agreement among raft nodes before linearized reading' (duration: 152.676022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:50.575355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.359762ms","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-04-19T13:31:50.575391Z","caller":"traceutil/trace.go:171","msg":"trace[945885261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"118.412424ms","start":"2026-04-19T13:31:50.45697Z","end":"2026-04-19T13:31:50.575382Z","steps":["trace[945885261] 'agreement among raft nodes before linearized reading' (duration: 118.340192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:50.57529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.803173ms","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-04-19T13:31:50.575488Z","caller":"traceutil/trace.go:171","msg":"trace[1371610917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"119.040408ms","start":"2026-04-19T13:31:50.45644Z","end":"2026-04-19T13:31:50.575481Z","steps":["trace[1371610917] 'agreement among raft nodes before linearized reading' (duration: 118.801353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:50.575214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.88794ms","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-04-19T13:31:50.575558Z","caller":"traceutil/trace.go:171","msg":"trace[6917853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"251.265389ms","start":"2026-04-19T13:31:50.324278Z","end":"2026-04-19T13:31:50.575544Z","steps":["trace[6917853] 'agreement among raft nodes before linearized reading' (duration: 250.837729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:50.575707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.337865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-19T13:31:50.575765Z","caller":"traceutil/trace.go:171","msg":"trace[360235694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"119.419758ms","start":"2026-04-19T13:31:50.456336Z","end":"2026-04-19T13:31:50.575756Z","steps":["trace[360235694] 'agreement among raft nodes before linearized reading' (duration: 119.312525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:31:50.70352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.672104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:31:50.703702Z","caller":"traceutil/trace.go:171","msg":"trace[595072207] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"123.095544ms","start":"2026-04-19T13:31:50.580592Z","end":"2026-04-19T13:31:50.703687Z","steps":["trace[595072207] 'process raft request' (duration: 123.030723ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:50.703912Z","caller":"traceutil/trace.go:171","msg":"trace[336264254] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"123.319369ms","start":"2026-04-19T13:31:50.580541Z","end":"2026-04-19T13:31:50.70386Z","steps":["trace[336264254] 'read index received' (duration: 14.223885ms)","trace[336264254] 'applied index is now lower than readState.Index' (duration: 109.092984ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:31:50.703945Z","caller":"traceutil/trace.go:171","msg":"trace[1701685715] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"123.154416ms","start":"2026-04-19T13:31:50.580766Z","end":"2026-04-19T13:31:50.70392Z","steps":["trace[1701685715] 'process raft request' (duration: 123.001452ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:50.704044Z","caller":"traceutil/trace.go:171","msg":"trace[1741625538] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"123.738769ms","start":"2026-04-19T13:31:50.580295Z","end":"2026-04-19T13:31:50.704033Z","steps":["trace[1741625538] 'process raft request' (duration: 14.464131ms)","trace[1741625538] 'compare' (duration: 108.516251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:31:50.704112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.527964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:31:50.704155Z","caller":"traceutil/trace.go:171","msg":"trace[1570931108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"123.627017ms","start":"2026-04-19T13:31:50.580516Z","end":"2026-04-19T13:31:50.704143Z","steps":["trace[1570931108] 'agreement among raft nodes before linearized reading' (duration: 123.460303ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:31:50.704322Z","caller":"traceutil/trace.go:171","msg":"trace[1506524374] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"123.683978ms","start":"2026-04-19T13:31:50.580629Z","end":"2026-04-19T13:31:50.704313Z","steps":["trace[1506524374] 'process raft request' (duration: 123.031843ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:00.128483Z","caller":"traceutil/trace.go:171","msg":"trace[26294859] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"382.054054ms","start":"2026-04-19T13:31:59.74637Z","end":"2026-04-19T13:32:00.128424Z","steps":["trace[26294859] 'process raft request' (duration: 381.853249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:00.128779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:31:59.746352Z","time spent":"382.29069ms","remote":"127.0.0.1:42520","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-04-19T13:32:01.015366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":72230930031895084,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-19T13:32:02.720475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.661187719s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-19T13:32:02.798828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.739504953s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:32:02.798978Z","caller":"traceutil/trace.go:171","msg":"trace[953037694] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1359; }","duration":"2.283952139s","start":"2026-04-19T13:32:00.515013Z","end":"2026-04-19T13:32:02.798965Z","steps":["trace[953037694] 'read index received' (duration: 544.260491ms)","trace[953037694] 'applied index is now lower than readState.Index' (duration: 1.739690908s)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:02.799162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.284152523s","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-04-19T13:32:02.799191Z","caller":"traceutil/trace.go:171","msg":"trace[2084921555] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1309; }","duration":"2.284213295s","start":"2026-04-19T13:32:00.514968Z","end":"2026-04-19T13:32:02.799182Z","steps":["trace[2084921555] 'agreement among raft nodes before linearized reading' (duration: 2.284168694s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.799215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.51493Z","time spent":"2.284278306s","remote":"127.0.0.1:41840","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-04-19T13:32:02.799176Z","caller":"traceutil/trace.go:171","msg":"trace[1016368525] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"2.2106732s","start":"2026-04-19T13:32:00.588475Z","end":"2026-04-19T13:32:02.799148Z","steps":["trace[1016368525] 'process raft request' (duration: 2.210435444s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.799418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.58845Z","time spent":"2.210880224s","remote":"127.0.0.1:42520","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-04-19T13:32:02.799482Z","caller":"traceutil/trace.go:171","msg":"trace[894922052] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"2.648665723s","start":"2026-04-19T13:32:00.15077Z","end":"2026-04-19T13:32:02.799435Z","steps":["trace[894922052] 'process raft request' (duration: 908.485254ms)","trace[894922052] 'compare' (duration: 1.739026981s)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:02.79965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.150737Z","time spent":"2.648830787s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:32:02.803759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508448262s","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":"warn","ts":"2026-04-19T13:32:02.803776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.190205797s","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-04-19T13:32:02.80381Z","caller":"traceutil/trace.go:171","msg":"trace[445378115] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1310; }","duration":"1.508534184s","start":"2026-04-19T13:32:01.295264Z","end":"2026-04-19T13:32:02.803798Z","steps":["trace[445378115] 'agreement among raft nodes before linearized reading' (duration: 1.508430071s)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:02.803821Z","caller":"traceutil/trace.go:171","msg":"trace[902433518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"2.190293869s","start":"2026-04-19T13:32:00.613515Z","end":"2026-04-19T13:32:02.803809Z","steps":["trace[902433518] 'agreement among raft nodes before linearized reading' (duration: 2.190116375s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.80384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:01.295255Z","time spent":"1.508577404s","remote":"127.0.0.1:42688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:32:02.803849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.613495Z","time spent":"2.190347561s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-19T13:32:02.803845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86865409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:32:02.803907Z","caller":"traceutil/trace.go:171","msg":"trace[411829139] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1310; }","duration":"1.868745613s","start":"2026-04-19T13:32:00.935139Z","end":"2026-04-19T13:32:02.803885Z","steps":["trace[411829139] 'agreement among raft nodes before linearized reading' (duration: 1.868670491s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.80393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.935119Z","time spent":"1.868804964s","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:32:02.803932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.573549ms","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-04-19T13:32:02.803972Z","caller":"traceutil/trace.go:171","msg":"trace[578722798] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1310; }","duration":"319.642239ms","start":"2026-04-19T13:32:02.484318Z","end":"2026-04-19T13:32:02.80396Z","steps":["trace[578722798] 'agreement among raft nodes before linearized reading' (duration: 319.513166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.803998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:02.484299Z","time spent":"319.692391ms","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-19T13:32:02.804025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.575066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:32:02.804058Z","caller":"traceutil/trace.go:171","msg":"trace[993162657] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1310; }","duration":"885.608327ms","start":"2026-04-19T13:32:01.918439Z","end":"2026-04-19T13:32:02.804047Z","steps":["trace[993162657] 'agreement among raft nodes before linearized reading' (duration: 885.556475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.804086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:01.91838Z","time spent":"885.699879ms","remote":"127.0.0.1:42824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-04-19T13:32:02.804115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056886391s","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-04-19T13:32:02.804134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380993244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:32:02.804157Z","caller":"traceutil/trace.go:171","msg":"trace[1934744431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"1.381039196s","start":"2026-04-19T13:32:01.423111Z","end":"2026-04-19T13:32:02.804151Z","steps":["trace[1934744431] 'agreement among raft nodes before linearized reading' (duration: 1.381004035s)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:02.804155Z","caller":"traceutil/trace.go:171","msg":"trace[655452379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"2.056965303s","start":"2026-04-19T13:32:00.74718Z","end":"2026-04-19T13:32:02.804145Z","steps":["trace[655452379] 'agreement among raft nodes before linearized reading' (duration: 2.05684193s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.804175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:01.4231Z","time spent":"1.381070886s","remote":"127.0.0.1:42244","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-04-19T13:32:02.804182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.747159Z","time spent":"2.057017025s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-19T13:32:02.804215Z","caller":"traceutil/trace.go:171","msg":"trace[1387806103] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"278.509751ms","start":"2026-04-19T13:32:02.525693Z","end":"2026-04-19T13:32:02.804202Z","steps":["trace[1387806103] 'process raft request' (duration: 277.831765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.804346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.060050746s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T13:32:02.804397Z","caller":"traceutil/trace.go:171","msg":"trace[1447598446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"2.060119747s","start":"2026-04-19T13:32:00.744266Z","end":"2026-04-19T13:32:02.804386Z","steps":["trace[1447598446] 'agreement among raft nodes before linearized reading' (duration: 2.059892462s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.804427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.744235Z","time spent":"2.060183139s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-19T13:32:02.804509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.056176204s","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-04-19T13:32:02.804552Z","caller":"traceutil/trace.go:171","msg":"trace[468181035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"2.056219875s","start":"2026-04-19T13:32:00.748321Z","end":"2026-04-19T13:32:02.804541Z","steps":["trace[468181035] 'agreement among raft nodes before linearized reading' (duration: 2.055418026s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.804577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.748286Z","time spent":"2.056285347s","remote":"127.0.0.1:42520","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-04-19T13:32:02.804662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523566829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-19T13:32:02.805865Z","caller":"traceutil/trace.go:171","msg":"trace[1374269650] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1310; }","duration":"1.524964372s","start":"2026-04-19T13:32:01.280888Z","end":"2026-04-19T13:32:02.805852Z","steps":["trace[1374269650] 'agreement among raft nodes before linearized reading' (duration: 1.522900723s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.805919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:01.280867Z","time spent":"1.525041553s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-19T13:32:02.804811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.060898226s","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-04-19T13:32:02.806034Z","caller":"traceutil/trace.go:171","msg":"trace[548386859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"2.062201356s","start":"2026-04-19T13:32:00.743824Z","end":"2026-04-19T13:32:02.806026Z","steps":["trace[548386859] 'agreement among raft nodes before linearized reading' (duration: 2.060271781s)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:02.806057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:00.743803Z","time spent":"2.062246838s","remote":"127.0.0.1:42520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-19T13:32:12.990328Z","caller":"traceutil/trace.go:171","msg":"trace[1005788891] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"102.588657ms","start":"2026-04-19T13:32:12.887697Z","end":"2026-04-19T13:32:12.990286Z","steps":["trace[1005788891] 'process raft request' (duration: 21.690771ms)","trace[1005788891] 'compare' (duration: 80.529667ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:27.190743Z","caller":"traceutil/trace.go:171","msg":"trace[1531984856] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"107.652096ms","start":"2026-04-19T13:32:27.083068Z","end":"2026-04-19T13:32:27.19072Z","steps":["trace[1531984856] 'process raft request' (duration: 107.580804ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:27.190801Z","caller":"traceutil/trace.go:171","msg":"trace[1423953825] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"107.755518ms","start":"2026-04-19T13:32:27.083011Z","end":"2026-04-19T13:32:27.190766Z","steps":["trace[1423953825] 'process raft request' (duration: 93.526843ms)","trace[1423953825] 'compare' (duration: 13.982129ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:27.190937Z","caller":"traceutil/trace.go:171","msg":"trace[1651878341] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"107.459802ms","start":"2026-04-19T13:32:27.083464Z","end":"2026-04-19T13:32:27.190924Z","steps":["trace[1651878341] 'process raft request' (duration: 107.223946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:29.565331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.171639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:32:29.565436Z","caller":"traceutil/trace.go:171","msg":"trace[1379827059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1438; }","duration":"142.348053ms","start":"2026-04-19T13:32:29.423065Z","end":"2026-04-19T13:32:29.565413Z","steps":["trace[1379827059] 'range keys from in-memory index tree' (duration: 142.090977ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:39.372648Z","caller":"traceutil/trace.go:171","msg":"trace[85254817] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"149.754258ms","start":"2026-04-19T13:32:39.222873Z","end":"2026-04-19T13:32:39.372627Z","steps":["trace[85254817] 'process raft request' (duration: 149.634545ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:39.373109Z","caller":"traceutil/trace.go:171","msg":"trace[1153373653] linearizableReadLoop","detail":"{readStateIndex:1539; appliedIndex:1539; }","duration":"113.955194ms","start":"2026-04-19T13:32:39.259127Z","end":"2026-04-19T13:32:39.373082Z","steps":["trace[1153373653] 'read index received' (duration: 113.948484ms)","trace[1153373653] 'applied index is now lower than readState.Index' (duration: 4.97µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:39.373342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.556475ms","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-04-19T13:32:39.373404Z","caller":"traceutil/trace.go:171","msg":"trace[714430980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1479; }","duration":"113.662138ms","start":"2026-04-19T13:32:39.259727Z","end":"2026-04-19T13:32:39.37339Z","steps":["trace[714430980] 'agreement among raft nodes before linearized reading' (duration: 113.478033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:39.373584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.453646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-19T13:32:39.373617Z","caller":"traceutil/trace.go:171","msg":"trace[424062405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1479; }","duration":"114.511588ms","start":"2026-04-19T13:32:39.259097Z","end":"2026-04-19T13:32:39.373609Z","steps":["trace[424062405] 'agreement among raft nodes before linearized reading' (duration: 114.426646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:39.37386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.75382ms","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-04-19T13:32:39.373934Z","caller":"traceutil/trace.go:171","msg":"trace[283841726] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1479; }","duration":"113.857432ms","start":"2026-04-19T13:32:39.260058Z","end":"2026-04-19T13:32:39.373916Z","steps":["trace[283841726] 'agreement among raft nodes before linearized reading' (duration: 113.652137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:39.534435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.39919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:32:39.534637Z","caller":"traceutil/trace.go:171","msg":"trace[1235372316] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"156.029375ms","start":"2026-04-19T13:32:39.378572Z","end":"2026-04-19T13:32:39.534602Z","steps":["trace[1235372316] 'process raft request' (duration: 45.383219ms)","trace[1235372316] 'compare' (duration: 110.199215ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:39.534714Z","caller":"traceutil/trace.go:171","msg":"trace[1000005822] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1539; }","duration":"113.977305ms","start":"2026-04-19T13:32:39.42072Z","end":"2026-04-19T13:32:39.534698Z","steps":["trace[1000005822] 'read index received' (duration: 3.254447ms)","trace[1000005822] 'applied index is now lower than readState.Index' (duration: 110.721508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:39.534806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.083358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:32:39.534838Z","caller":"traceutil/trace.go:171","msg":"trace[1877328282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1484; }","duration":"114.135788ms","start":"2026-04-19T13:32:39.420692Z","end":"2026-04-19T13:32:39.534828Z","steps":["trace[1877328282] 'agreement among raft nodes before linearized reading' (duration: 114.061557ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:39.534859Z","caller":"traceutil/trace.go:171","msg":"trace[570684053] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"154.846268ms","start":"2026-04-19T13:32:39.379999Z","end":"2026-04-19T13:32:39.534845Z","steps":["trace[570684053] 'process raft request' (duration: 154.600662ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:39.534917Z","caller":"traceutil/trace.go:171","msg":"trace[306696505] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"154.364376ms","start":"2026-04-19T13:32:39.380535Z","end":"2026-04-19T13:32:39.5349Z","steps":["trace[306696505] 'process raft request' (duration: 154.12548ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:39.534939Z","caller":"traceutil/trace.go:171","msg":"trace[74078490] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"154.887689ms","start":"2026-04-19T13:32:39.380014Z","end":"2026-04-19T13:32:39.534902Z","steps":["trace[74078490] 'process raft request' (duration: 154.614993ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:39.535044Z","caller":"traceutil/trace.go:171","msg":"trace[1202357233] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"155.079283ms","start":"2026-04-19T13:32:39.379956Z","end":"2026-04-19T13:32:39.535035Z","steps":["trace[1202357233] 'process raft request' (duration: 154.583532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:51.799273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.822679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-19T13:32:51.799328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.452736ms","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-04-19T13:32:51.79952Z","caller":"traceutil/trace.go:171","msg":"trace[1829623981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1533; }","duration":"125.695741ms","start":"2026-04-19T13:32:51.67377Z","end":"2026-04-19T13:32:51.799466Z","steps":["trace[1829623981] 'range keys from in-memory index tree' (duration: 125.197259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:51.799548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.746341ms","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-04-19T13:32:51.799519Z","caller":"traceutil/trace.go:171","msg":"trace[2123161625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1533; }","duration":"130.088825ms","start":"2026-04-19T13:32:51.669355Z","end":"2026-04-19T13:32:51.799443Z","steps":["trace[2123161625] 'range keys from in-memory index tree' (duration: 129.589222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:51.799356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.63726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-19T13:32:51.799725Z","caller":"traceutil/trace.go:171","msg":"trace[1132910178] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1533; }","duration":"126.027969ms","start":"2026-04-19T13:32:51.673674Z","end":"2026-04-19T13:32:51.799702Z","steps":["trace[1132910178] 'range keys from in-memory index tree' (duration: 125.491656ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:51.799604Z","caller":"traceutil/trace.go:171","msg":"trace[1527883298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1533; }","duration":"181.857914ms","start":"2026-04-19T13:32:51.617728Z","end":"2026-04-19T13:32:51.799585Z","steps":["trace[1527883298] 'range keys from in-memory index tree' (duration: 181.529436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:51.799397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.636666ms","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-04-19T13:32:51.79991Z","caller":"traceutil/trace.go:171","msg":"trace[171409697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1533; }","duration":"125.142528ms","start":"2026-04-19T13:32:51.674718Z","end":"2026-04-19T13:32:51.79986Z","steps":["trace[171409697] 'range keys from in-memory index tree' (duration: 124.516803ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:57.674921Z","caller":"traceutil/trace.go:171","msg":"trace[1045150084] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"108.193677ms","start":"2026-04-19T13:32:57.566697Z","end":"2026-04-19T13:32:57.674891Z","steps":["trace[1045150084] 'process raft request' (duration: 108.043654ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:00.053407Z","caller":"traceutil/trace.go:171","msg":"trace[1859900887] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"168.286744ms","start":"2026-04-19T13:32:59.885066Z","end":"2026-04-19T13:33:00.053352Z","steps":["trace[1859900887] 'process raft request' (duration: 80.940407ms)","trace[1859900887] 'compare' (duration: 87.238975ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:00.118418Z","caller":"traceutil/trace.go:171","msg":"trace[2037118866] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"232.807204ms","start":"2026-04-19T13:32:59.885588Z","end":"2026-04-19T13:33:00.118395Z","steps":["trace[2037118866] 'process raft request' (duration: 232.660221ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:00.118656Z","caller":"traceutil/trace.go:171","msg":"trace[769711679] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"231.78975ms","start":"2026-04-19T13:32:59.886842Z","end":"2026-04-19T13:33:00.118632Z","steps":["trace[769711679] 'process raft request' (duration: 231.679257ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:00.118646Z","caller":"traceutil/trace.go:171","msg":"trace[1333418404] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"231.917593ms","start":"2026-04-19T13:32:59.886709Z","end":"2026-04-19T13:33:00.118627Z","steps":["trace[1333418404] 'process raft request' (duration: 231.634396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:04.17948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.909175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-19T13:33:04.179567Z","caller":"traceutil/trace.go:171","msg":"trace[1951325711] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1590; }","duration":"140.031339ms","start":"2026-04-19T13:33:04.03951Z","end":"2026-04-19T13:33:04.179541Z","steps":["trace[1951325711] 'range keys from in-memory index tree' (duration: 139.760332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:04.17956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.707518ms","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-04-19T13:33:04.179632Z","caller":"traceutil/trace.go:171","msg":"trace[1599663891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1590; }","duration":"113.806411ms","start":"2026-04-19T13:33:04.065804Z","end":"2026-04-19T13:33:04.179611Z","steps":["trace[1599663891] 'range keys from in-memory index tree' (duration: 113.602256ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:04.294687Z","caller":"traceutil/trace.go:171","msg":"trace[1929862362] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"110.80273ms","start":"2026-04-19T13:33:04.183853Z","end":"2026-04-19T13:33:04.294656Z","steps":["trace[1929862362] 'process raft request' (duration: 110.687478ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:04.294787Z","caller":"traceutil/trace.go:171","msg":"trace[463057776] transaction","detail":"{read_only:false; response_revision:1594; number_of_response:1; }","duration":"110.012322ms","start":"2026-04-19T13:33:04.184739Z","end":"2026-04-19T13:33:04.294751Z","steps":["trace[463057776] 'process raft request' (duration: 109.831447ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:04.29486Z","caller":"traceutil/trace.go:171","msg":"trace[1938098632] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"110.008651ms","start":"2026-04-19T13:33:04.184825Z","end":"2026-04-19T13:33:04.294834Z","steps":["trace[1938098632] 'process raft request' (duration: 109.767586ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:04.295012Z","caller":"traceutil/trace.go:171","msg":"trace[970885004] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"111.21352ms","start":"2026-04-19T13:33:04.183778Z","end":"2026-04-19T13:33:04.294992Z","steps":["trace[970885004] 'process raft request' (duration: 109.428418ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:08.367583Z","caller":"traceutil/trace.go:171","msg":"trace[378752680] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"155.355079ms","start":"2026-04-19T13:33:08.21218Z","end":"2026-04-19T13:33:08.367535Z","steps":["trace[378752680] 'process raft request' (duration: 155.087603ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:13.521605Z","caller":"traceutil/trace.go:171","msg":"trace[1667408599] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"103.088578ms","start":"2026-04-19T13:33:13.41848Z","end":"2026-04-19T13:33:13.521569Z","steps":["trace[1667408599] 'process raft request' (duration: 102.784961ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:18.59414Z","caller":"traceutil/trace.go:171","msg":"trace[1300905106] linearizableReadLoop","detail":"{readStateIndex:1724; appliedIndex:1723; }","duration":"108.738491ms","start":"2026-04-19T13:33:18.485374Z","end":"2026-04-19T13:33:18.594112Z","steps":["trace[1300905106] 'read index received' (duration: 108.442424ms)","trace[1300905106] 'applied index is now lower than readState.Index' (duration: 294.907µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:18.59422Z","caller":"traceutil/trace.go:171","msg":"trace[713550549] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"168.772475ms","start":"2026-04-19T13:33:18.425407Z","end":"2026-04-19T13:33:18.59418Z","steps":["trace[713550549] 'process raft request' (duration: 168.518549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:18.594382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.988257ms","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-04-19T13:33:18.594427Z","caller":"traceutil/trace.go:171","msg":"trace[1522606494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1656; }","duration":"109.093339ms","start":"2026-04-19T13:33:18.485321Z","end":"2026-04-19T13:33:18.594415Z","steps":["trace[1522606494] 'agreement among raft nodes before linearized reading' (duration: 108.926865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:18.594512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.42209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-19T13:33:18.594595Z","caller":"traceutil/trace.go:171","msg":"trace[688420845] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1656; }","duration":"107.537393ms","start":"2026-04-19T13:33:18.487036Z","end":"2026-04-19T13:33:18.594573Z","steps":["trace[688420845] 'agreement among raft nodes before linearized reading' (duration: 107.283157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:33:18.594606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.529462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-19T13:33:18.59471Z","caller":"traceutil/trace.go:171","msg":"trace[1433118761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1656; }","duration":"104.661955ms","start":"2026-04-19T13:33:18.49003Z","end":"2026-04-19T13:33:18.594692Z","steps":["trace[1433118761] 'agreement among raft nodes before linearized reading' (duration: 104.329737ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:19.529512Z","caller":"traceutil/trace.go:171","msg":"trace[1430656798] linearizableReadLoop","detail":"{readStateIndex:1729; appliedIndex:1728; }","duration":"106.97347ms","start":"2026-04-19T13:33:19.422505Z","end":"2026-04-19T13:33:19.529479Z","steps":["trace[1430656798] 'read index received' (duration: 11.507021ms)","trace[1430656798] 'applied index is now lower than readState.Index' (duration: 95.465209ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:19.529584Z","caller":"traceutil/trace.go:171","msg":"trace[550528944] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"172.037033ms","start":"2026-04-19T13:33:19.357517Z","end":"2026-04-19T13:33:19.529554Z","steps":["trace[550528944] 'process raft request' (duration: 76.530702ms)","trace[550528944] 'compare' (duration: 95.222434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:19.529794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.130964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:33:19.529979Z","caller":"traceutil/trace.go:171","msg":"trace[1577482174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1661; }","duration":"107.457582ms","start":"2026-04-19T13:33:19.422468Z","end":"2026-04-19T13:33:19.529926Z","steps":["trace[1577482174] 'agreement among raft nodes before linearized reading' (duration: 107.132144ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:22.104168Z","caller":"traceutil/trace.go:171","msg":"trace[1129088651] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"130.700468ms","start":"2026-04-19T13:33:21.973436Z","end":"2026-04-19T13:33:22.104136Z","steps":["trace[1129088651] 'process raft request' (duration: 123.322794ms)"],"step_count":1}