{"level":"warn","ts":"2026-05-09T05:10:15.532855Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:10:15.532956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T05:10:15.533036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:10:15.533097Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-05-09T05:10:15.533125Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:10:15.534099Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-05-09T05:10:15.534287Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T05:10:15.66019Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"125.583436ms"} {"level":"info","ts":"2026-05-09T05:10:15.740568Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-05-09T05:10:15.740763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T05:10:15.740831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-05-09T05:10:15.740855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T05:10:15.740879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-05-09T05:10:15.740997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-05-09T05:10:15.825878Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T05:10:15.828331Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T05:10:15.830047Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T05:10:15.833918Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T05:10:15.836612Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:10:15.836756Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:10:15.836832Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:10:15.837095Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T05:10:15.837512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-05-09T05:10:15.837688Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-05-09T05:10:15.840976Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:10:15.841129Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-05-09T05:10:15.841154Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-05-09T05:10:15.841485Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T05:10:15.841514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T05:10:16.441826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T05:10:16.441931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T05:10:16.441977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-05-09T05:10:16.442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-05-09T05:10:16.442016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-05-09T05:10:16.442036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-05-09T05:10:16.442058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-05-09T05:10:16.444348Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T05:10:16.444639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:10:16.444753Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:10:16.44678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:10:16.446902Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:10:16.44695Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:10:16.447832Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T05:10:16.448192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:10:16.449501Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"info","ts":"2026-05-09T05:10:16.456609Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T05:10:16.456698Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-05-09T05:10:45.457523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.526927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:10:45.458449Z","caller":"traceutil/trace.go:171","msg":"trace[1631582641] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"576.308726ms","start":"2026-05-09T05:10:44.881929Z","end":"2026-05-09T05:10:45.458238Z","steps":["trace[1631582641] 'compare' (duration: 565.366154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.458756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.881915Z","time spent":"576.637304ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:10:45.458596Z","caller":"traceutil/trace.go:171","msg":"trace[701495703] transaction","detail":"{read_only:false; number_of_response:0; response_revision:529; }","duration":"576.517401ms","start":"2026-05-09T05:10:44.882008Z","end":"2026-05-09T05:10:45.458525Z","steps":["trace[701495703] 'process raft request' (duration: 576.055819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.46003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.882002Z","time spent":"577.363701ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:10:45.460936Z","caller":"traceutil/trace.go:171","msg":"trace[749689541] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"578.90292ms","start":"2026-05-09T05:10:44.882001Z","end":"2026-05-09T05:10:45.460904Z","steps":["trace[749689541] 'process raft request' (duration: 576.634264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.461254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.881991Z","time spent":"578.995222ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:10:45.461644Z","caller":"traceutil/trace.go:171","msg":"trace[579433999] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"579.570817ms","start":"2026-05-09T05:10:44.882059Z","end":"2026-05-09T05:10:45.46163Z","steps":["trace[579433999] 'process raft request' (duration: 578.778978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.46174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.882039Z","time spent":"579.652749ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:10:45.461797Z","caller":"traceutil/trace.go:171","msg":"trace[1265568195] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:544; }","duration":"575.394622ms","start":"2026-05-09T05:10:44.886352Z","end":"2026-05-09T05:10:45.461747Z","steps":["trace[1265568195] 'read index received' (duration: 3.466035ms)","trace[1265568195] 'applied index is now lower than readState.Index' (duration: 571.913827ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:10:45.461821Z","caller":"traceutil/trace.go:171","msg":"trace[1045800311] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"576.779598ms","start":"2026-05-09T05:10:44.885003Z","end":"2026-05-09T05:10:45.461782Z","steps":["trace[1045800311] 'process raft request' (duration: 576.245095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.461982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.884997Z","time spent":"576.88811ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:10:45.462922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.4926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-05-09T05:10:45.463026Z","caller":"traceutil/trace.go:171","msg":"trace[262441713] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:533; }","duration":"576.640203ms","start":"2026-05-09T05:10:44.886349Z","end":"2026-05-09T05:10:45.462989Z","steps":["trace[262441713] 'agreement among raft nodes before linearized reading' (duration: 575.554717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.463086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.886341Z","time spent":"576.730166ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1042,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-05-09T05:10:45.464632Z","caller":"traceutil/trace.go:171","msg":"trace[1886528537] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"575.428473ms","start":"2026-05-09T05:10:44.886813Z","end":"2026-05-09T05:10:45.462241Z","steps":["trace[1886528537] 'process raft request' (duration: 574.761907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.464716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.886804Z","time spent":"577.871174ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:10:45.471588Z","caller":"traceutil/trace.go:171","msg":"trace[171253650] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"463.202703ms","start":"2026-05-09T05:10:45.008361Z","end":"2026-05-09T05:10:45.471564Z","steps":["trace[171253650] 'process raft request' (duration: 462.943245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.471722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:45.008344Z","time spent":"463.332265ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:10:45.471863Z","caller":"traceutil/trace.go:171","msg":"trace[182481552] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"581.25817ms","start":"2026-05-09T05:10:44.890597Z","end":"2026-05-09T05:10:45.471855Z","steps":["trace[182481552] 'process raft request' (duration: 580.453459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.471915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.805148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:10:45.47195Z","caller":"traceutil/trace.go:171","msg":"trace[1891504366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"303.88298ms","start":"2026-05-09T05:10:45.168058Z","end":"2026-05-09T05:10:45.471941Z","steps":["trace[1891504366] 'agreement among raft nodes before linearized reading' (duration: 303.830799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.47197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:45.168037Z","time spent":"303.929ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:10:45.472025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.890589Z","time spent":"581.348692ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:10:45.472122Z","caller":"traceutil/trace.go:171","msg":"trace[967431800] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"574.848149ms","start":"2026-05-09T05:10:44.897265Z","end":"2026-05-09T05:10:45.472113Z","steps":["trace[967431800] 'process raft request' (duration: 573.855504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.472241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.897256Z","time spent":"574.957431ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:10:45.472339Z","caller":"traceutil/trace.go:171","msg":"trace[1936438343] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"572.931072ms","start":"2026-05-09T05:10:44.899399Z","end":"2026-05-09T05:10:45.472331Z","steps":["trace[1936438343] 'process raft request' (duration: 571.775413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:45.472404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:44.899382Z","time spent":"572.999014ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:10:46.885844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.808798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:10:46.885946Z","caller":"traceutil/trace.go:171","msg":"trace[1927718902] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"183.646917ms","start":"2026-05-09T05:10:46.702284Z","end":"2026-05-09T05:10:46.885931Z","steps":["trace[1927718902] 'read index received' (duration: 43.684355ms)","trace[1927718902] 'applied index is now lower than readState.Index' (duration: 139.961422ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:10:46.886334Z","caller":"traceutil/trace.go:171","msg":"trace[364802938] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"192.14211ms","start":"2026-05-09T05:10:46.694178Z","end":"2026-05-09T05:10:46.88632Z","steps":["trace[364802938] 'process raft request' (duration: 51.781687ms)","trace[364802938] 'compare' (duration: 139.618123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:10:46.886579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.289015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-05-09T05:10:46.886617Z","caller":"traceutil/trace.go:171","msg":"trace[1093103131] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"184.329256ms","start":"2026-05-09T05:10:46.702278Z","end":"2026-05-09T05:10:46.886607Z","steps":["trace[1093103131] 'agreement among raft nodes before linearized reading' (duration: 184.200023ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:10:46.967438Z","caller":"traceutil/trace.go:171","msg":"trace[1093594555] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"233.254353ms","start":"2026-05-09T05:10:46.734156Z","end":"2026-05-09T05:10:46.96741Z","steps":["trace[1093594555] 'process raft request' (duration: 233.065927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:46.967795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.932574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:10:46.967858Z","caller":"traceutil/trace.go:171","msg":"trace[702232243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"208.046516ms","start":"2026-05-09T05:10:46.759794Z","end":"2026-05-09T05:10:46.967841Z","steps":["trace[702232243] 'agreement among raft nodes before linearized reading' (duration: 207.821631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:47.848312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.765652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:10:47.848415Z","caller":"traceutil/trace.go:171","msg":"trace[2137767774] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"871.871137ms","start":"2026-05-09T05:10:46.976527Z","end":"2026-05-09T05:10:47.848399Z","steps":["trace[2137767774] 'read index received' (duration: 450.033633ms)","trace[2137767774] 'applied index is now lower than readState.Index' (duration: 421.836444ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:10:47.848563Z","caller":"traceutil/trace.go:171","msg":"trace[203082680] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"875.268022ms","start":"2026-05-09T05:10:46.973257Z","end":"2026-05-09T05:10:47.848525Z","steps":["trace[203082680] 'process raft request' (duration: 453.197923ms)","trace[203082680] 'compare' (duration: 421.374292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:10:47.848704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:46.973231Z","time spent":"875.397355ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:10:47.848686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.137534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-05-09T05:10:47.848753Z","caller":"traceutil/trace.go:171","msg":"trace[1476497227] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:586; }","duration":"872.234816ms","start":"2026-05-09T05:10:46.976502Z","end":"2026-05-09T05:10:47.848736Z","steps":["trace[1476497227] 'agreement among raft nodes before linearized reading' (duration: 872.010801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:47.848786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:46.976491Z","time spent":"872.287947ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":1,"response size":23104,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-05-09T05:10:47.887802Z","caller":"traceutil/trace.go:171","msg":"trace[1761502841] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"873.687284ms","start":"2026-05-09T05:10:47.01409Z","end":"2026-05-09T05:10:47.887778Z","steps":["trace[1761502841] 'process raft request' (duration: 873.409177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:47.88791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:47.014064Z","time spent":"873.792215ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:10:47.888203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.758724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:10:47.8883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.03771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:10:47.888315Z","caller":"traceutil/trace.go:171","msg":"trace[383935463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"719.916238ms","start":"2026-05-09T05:10:47.16838Z","end":"2026-05-09T05:10:47.888297Z","steps":["trace[383935463] 'agreement among raft nodes before linearized reading' (duration: 719.736114ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:10:47.888365Z","caller":"traceutil/trace.go:171","msg":"trace[1542963343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"402.166704ms","start":"2026-05-09T05:10:47.486183Z","end":"2026-05-09T05:10:47.88835Z","steps":["trace[1542963343] 'agreement among raft nodes before linearized reading' (duration: 401.967099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:47.888382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:47.168362Z","time spent":"720.00037ms","remote":"127.0.0.1:58712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:10:47.888394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:47.486164Z","time spent":"402.224765ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T05:10:47.888122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.660901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:10:47.889344Z","caller":"traceutil/trace.go:171","msg":"trace[1720472756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"402.876471ms","start":"2026-05-09T05:10:47.486438Z","end":"2026-05-09T05:10:47.889314Z","steps":["trace[1720472756] 'agreement among raft nodes before linearized reading' (duration: 401.589179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:47.889405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:47.486432Z","time spent":"402.947924ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T05:10:52.226211Z","caller":"traceutil/trace.go:171","msg":"trace[124606952] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"301.063139ms","start":"2026-05-09T05:10:51.925107Z","end":"2026-05-09T05:10:52.22617Z","steps":["trace[124606952] 'process raft request' (duration: 287.339298ms)","trace[124606952] 'compare' (duration: 13.581678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:10:52.226477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:51.925086Z","time spent":"301.245384ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:10:52.267167Z","caller":"traceutil/trace.go:171","msg":"trace[1459227678] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"258.161361ms","start":"2026-05-09T05:10:52.008956Z","end":"2026-05-09T05:10:52.267118Z","steps":["trace[1459227678] 'process raft request' (duration: 257.232979ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:10:52.948205Z","caller":"traceutil/trace.go:171","msg":"trace[499228030] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"409.147608ms","start":"2026-05-09T05:10:52.539028Z","end":"2026-05-09T05:10:52.948175Z","steps":["trace[499228030] 'read index received' (duration: 408.137773ms)","trace[499228030] 'applied index is now lower than readState.Index' (duration: 993.934µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:10:52.948454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.407265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tdt7f\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-09T05:10:52.948369Z","caller":"traceutil/trace.go:171","msg":"trace[405458278] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"596.789246ms","start":"2026-05-09T05:10:52.351465Z","end":"2026-05-09T05:10:52.948254Z","steps":["trace[405458278] 'process raft request' (duration: 595.680999ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:10:52.948524Z","caller":"traceutil/trace.go:171","msg":"trace[972352639] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tdt7f; range_end:; response_count:1; response_revision:618; }","duration":"409.497717ms","start":"2026-05-09T05:10:52.538985Z","end":"2026-05-09T05:10:52.948483Z","steps":["trace[972352639] 'agreement among raft nodes before linearized reading' (duration: 409.299231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:52.948587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:52.538963Z","time spent":"409.614431ms","remote":"127.0.0.1:52854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-tdt7f\" "} {"level":"warn","ts":"2026-05-09T05:10:52.948748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:52.351435Z","time spent":"597.128144ms","remote":"127.0.0.1:53018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:10:53.416386Z","caller":"traceutil/trace.go:171","msg":"trace[791495983] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"247.455386ms","start":"2026-05-09T05:10:53.168855Z","end":"2026-05-09T05:10:53.416311Z","steps":["trace[791495983] 'read index received' (duration: 241.084177ms)","trace[791495983] 'applied index is now lower than readState.Index' (duration: 6.369939ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:10:53.416397Z","caller":"traceutil/trace.go:171","msg":"trace[200294506] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"453.644794ms","start":"2026-05-09T05:10:52.962694Z","end":"2026-05-09T05:10:53.416339Z","steps":["trace[200294506] 'process raft request' (duration: 447.05127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:53.416556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.678642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:10:53.416664Z","caller":"traceutil/trace.go:171","msg":"trace[738446980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"247.766994ms","start":"2026-05-09T05:10:53.168847Z","end":"2026-05-09T05:10:53.416614Z","steps":["trace[738446980] 'agreement among raft nodes before linearized reading' (duration: 247.62647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:10:53.416751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:10:52.962672Z","time spent":"453.835929ms","remote":"127.0.0.1:52854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:10:54.948986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.058305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:10:54.949096Z","caller":"traceutil/trace.go:171","msg":"trace[1730230961] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"272.036267ms","start":"2026-05-09T05:10:54.67704Z","end":"2026-05-09T05:10:54.949076Z","steps":["trace[1730230961] 'process raft request' (duration: 12.815488ms)","trace[1730230961] 'compare' (duration: 258.937172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:11:07.526657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.080792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:07.526772Z","caller":"traceutil/trace.go:171","msg":"trace[1946455847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"359.242017ms","start":"2026-05-09T05:11:07.167506Z","end":"2026-05-09T05:11:07.526749Z","steps":["trace[1946455847] 'range keys from in-memory index tree' (duration: 359.02087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:07.526832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:07.167492Z","time spent":"359.329539ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:11:07.526868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.08438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-09T05:11:07.527209Z","caller":"traceutil/trace.go:171","msg":"trace[1540153359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"451.340416ms","start":"2026-05-09T05:11:07.075708Z","end":"2026-05-09T05:11:07.527048Z","steps":["trace[1540153359] 'range keys from in-memory index tree' (duration: 450.813843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:07.527425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:07.075691Z","time spent":"451.582603ms","remote":"127.0.0.1:52928","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-05-09T05:11:32.329232Z","caller":"traceutil/trace.go:171","msg":"trace[1607669489] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"108.1865ms","start":"2026-05-09T05:11:32.221021Z","end":"2026-05-09T05:11:32.329208Z","steps":["trace[1607669489] 'process raft request' (duration: 71.062057ms)","trace[1607669489] 'compare' (duration: 37.00543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:11:32.576998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.224908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:11:32.577315Z","caller":"traceutil/trace.go:171","msg":"trace[1642888201] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"100.303756ms","start":"2026-05-09T05:11:32.4768Z","end":"2026-05-09T05:11:32.577104Z","steps":["trace[1642888201] 'read index received' (duration: 24.091µs)","trace[1642888201] 'applied index is now lower than readState.Index' (duration: 100.278185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:11:32.57745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.631504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:11:32.577545Z","caller":"traceutil/trace.go:171","msg":"trace[1299840798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"100.755617ms","start":"2026-05-09T05:11:32.476773Z","end":"2026-05-09T05:11:32.577528Z","steps":["trace[1299840798] 'agreement among raft nodes before linearized reading' (duration: 100.50079ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:32.577788Z","caller":"traceutil/trace.go:171","msg":"trace[1470817043] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"160.818661ms","start":"2026-05-09T05:11:32.416392Z","end":"2026-05-09T05:11:32.57721Z","steps":["trace[1470817043] 'process raft request' (duration: 49.275336ms)","trace[1470817043] 'compare' (duration: 110.968991ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:33.350024Z","caller":"traceutil/trace.go:171","msg":"trace[1597714469] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"139.271255ms","start":"2026-05-09T05:11:33.210728Z","end":"2026-05-09T05:11:33.349999Z","steps":["trace[1597714469] 'process raft request' (duration: 139.109501ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:34.106897Z","caller":"traceutil/trace.go:171","msg":"trace[1720734725] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"128.731993ms","start":"2026-05-09T05:11:33.97814Z","end":"2026-05-09T05:11:34.106872Z","steps":["trace[1720734725] 'process raft request' (duration: 128.563438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:34.454129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.963045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T05:11:34.454189Z","caller":"traceutil/trace.go:171","msg":"trace[2000362460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"120.071957ms","start":"2026-05-09T05:11:34.334101Z","end":"2026-05-09T05:11:34.454173Z","steps":["trace[2000362460] 'range keys from in-memory index tree' (duration: 119.806141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:34.454238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.280944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T05:11:34.454306Z","caller":"traceutil/trace.go:171","msg":"trace[2029822445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:965; }","duration":"233.377886ms","start":"2026-05-09T05:11:34.220913Z","end":"2026-05-09T05:11:34.45429Z","steps":["trace[2029822445] 'range keys from in-memory index tree' (duration: 233.137619ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:34.60869Z","caller":"traceutil/trace.go:171","msg":"trace[126660301] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"150.085904ms","start":"2026-05-09T05:11:34.458585Z","end":"2026-05-09T05:11:34.608671Z","steps":["trace[126660301] 'process raft request' (duration: 149.961251ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:34.701004Z","caller":"traceutil/trace.go:171","msg":"trace[320821587] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"118.966939ms","start":"2026-05-09T05:11:34.582014Z","end":"2026-05-09T05:11:34.700981Z","steps":["trace[320821587] 'read index received' (duration: 26.851918ms)","trace[320821587] 'applied index is now lower than readState.Index' (duration: 92.113971ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:34.701098Z","caller":"traceutil/trace.go:171","msg":"trace[1776918771] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"242.293247ms","start":"2026-05-09T05:11:34.458776Z","end":"2026-05-09T05:11:34.701069Z","steps":["trace[1776918771] 'process raft request' (duration: 203.683217ms)","trace[1776918771] 'compare' (duration: 38.365354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:11:34.701244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.201336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:11:34.70148Z","caller":"traceutil/trace.go:171","msg":"trace[905481900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"119.337839ms","start":"2026-05-09T05:11:34.581963Z","end":"2026-05-09T05:11:34.701301Z","steps":["trace[905481900] 'agreement among raft nodes before linearized reading' (duration: 119.126034ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:42.240797Z","caller":"traceutil/trace.go:171","msg":"trace[424235214] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"285.796134ms","start":"2026-05-09T05:11:41.954976Z","end":"2026-05-09T05:11:42.240773Z","steps":["trace[424235214] 'process raft request' (duration: 285.63838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:43.137819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156343754671410,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T05:11:43.296339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055765329s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T05:11:43.296699Z","caller":"traceutil/trace.go:171","msg":"trace[768487743] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"659.676793ms","start":"2026-05-09T05:11:42.637005Z","end":"2026-05-09T05:11:43.296681Z","steps":["trace[768487743] 'read index received' (duration: 659.496968ms)","trace[768487743] 'applied index is now lower than readState.Index' (duration: 179.215µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:43.296792Z","caller":"traceutil/trace.go:171","msg":"trace[1769235562] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.163655057s","start":"2026-05-09T05:11:42.133104Z","end":"2026-05-09T05:11:43.29676Z","steps":["trace[1769235562] 'process raft request' (duration: 1.163441812s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:43.296882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.433772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:43.29692Z","caller":"traceutil/trace.go:171","msg":"trace[1008333806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"130.501714ms","start":"2026-05-09T05:11:43.166409Z","end":"2026-05-09T05:11:43.296911Z","steps":["trace[1008333806] 'agreement among raft nodes before linearized reading' (duration: 130.453213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:43.296916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.777313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:11:43.296954Z","caller":"traceutil/trace.go:171","msg":"trace[1542812281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"532.873896ms","start":"2026-05-09T05:11:42.764071Z","end":"2026-05-09T05:11:43.296945Z","steps":["trace[1542812281] 'agreement among raft nodes before linearized reading' (duration: 532.774043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:43.296957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:42.133071Z","time spent":"1.16380636s","remote":"127.0.0.1:52928","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-05-09T05:11:43.296982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:42.764049Z","time spent":"532.923178ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T05:11:43.296832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.819157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:11:43.297097Z","caller":"traceutil/trace.go:171","msg":"trace[558034098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"660.122705ms","start":"2026-05-09T05:11:42.636963Z","end":"2026-05-09T05:11:43.297086Z","steps":["trace[558034098] 'agreement among raft nodes before linearized reading' (duration: 659.781546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:43.297131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:42.636948Z","time spent":"660.173806ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:11:43.297125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.47418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T05:11:43.297152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.1005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:11:43.297211Z","caller":"traceutil/trace.go:171","msg":"trace[1280061311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:995; }","duration":"539.595503ms","start":"2026-05-09T05:11:42.757593Z","end":"2026-05-09T05:11:43.297188Z","steps":["trace[1280061311] 'agreement among raft nodes before linearized reading' (duration: 539.324226ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:43.297253Z","caller":"traceutil/trace.go:171","msg":"trace[611066789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"550.258644ms","start":"2026-05-09T05:11:42.74697Z","end":"2026-05-09T05:11:43.297229Z","steps":["trace[611066789] 'agreement among raft nodes before linearized reading' (duration: 549.857034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:11:43.29726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:42.757582Z","time spent":"539.669605ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:11:43.297315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:11:42.746945Z","time spent":"550.355057ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T05:11:43.554791Z","caller":"traceutil/trace.go:171","msg":"trace[698400760] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"253.748888ms","start":"2026-05-09T05:11:43.301016Z","end":"2026-05-09T05:11:43.554765Z","steps":["trace[698400760] 'process raft request' (duration: 242.418399ms)","trace[698400760] 'compare' (duration: 11.00168ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:11:43.555049Z","caller":"traceutil/trace.go:171","msg":"trace[958551508] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1027; }","duration":"226.18511ms","start":"2026-05-09T05:11:43.32881Z","end":"2026-05-09T05:11:43.554995Z","steps":["trace[958551508] 'read index received' (duration: 214.709187ms)","trace[958551508] 'applied index is now lower than readState.Index' (duration: 11.474893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:11:43.555205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.383505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:11:43.555207Z","caller":"traceutil/trace.go:171","msg":"trace[387638641] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"254.134978ms","start":"2026-05-09T05:11:43.30106Z","end":"2026-05-09T05:11:43.555195Z","steps":["trace[387638641] 'process raft request' (duration: 253.634795ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:43.555218Z","caller":"traceutil/trace.go:171","msg":"trace[1391562644] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"253.721427ms","start":"2026-05-09T05:11:43.301479Z","end":"2026-05-09T05:11:43.555201Z","steps":["trace[1391562644] 'process raft request' (duration: 253.405889ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:43.555242Z","caller":"traceutil/trace.go:171","msg":"trace[1279507419] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:999; }","duration":"226.435938ms","start":"2026-05-09T05:11:43.328795Z","end":"2026-05-09T05:11:43.555231Z","steps":["trace[1279507419] 'agreement among raft nodes before linearized reading' (duration: 226.323494ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:43.555262Z","caller":"traceutil/trace.go:171","msg":"trace[1244319346] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"253.694698ms","start":"2026-05-09T05:11:43.301535Z","end":"2026-05-09T05:11:43.555229Z","steps":["trace[1244319346] 'process raft request' (duration: 253.42175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:49.715943Z","caller":"traceutil/trace.go:171","msg":"trace[1917818161] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"136.844462ms","start":"2026-05-09T05:11:49.579074Z","end":"2026-05-09T05:11:49.715918Z","steps":["trace[1917818161] 'process raft request' (duration: 136.808501ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:49.715961Z","caller":"traceutil/trace.go:171","msg":"trace[1424605798] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"137.87621ms","start":"2026-05-09T05:11:49.578053Z","end":"2026-05-09T05:11:49.71593Z","steps":["trace[1424605798] 'process raft request' (duration: 137.670725ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:49.71627Z","caller":"traceutil/trace.go:171","msg":"trace[1948073439] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"137.406267ms","start":"2026-05-09T05:11:49.578853Z","end":"2026-05-09T05:11:49.716259Z","steps":["trace[1948073439] 'process raft request' (duration: 136.995846ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:11:55.716107Z","caller":"traceutil/trace.go:171","msg":"trace[202928692] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"100.962456ms","start":"2026-05-09T05:11:55.615125Z","end":"2026-05-09T05:11:55.716087Z","steps":["trace[202928692] 'process raft request' (duration: 100.826392ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:07.85302Z","caller":"traceutil/trace.go:171","msg":"trace[436341445] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"409.15532ms","start":"2026-05-09T05:12:07.443841Z","end":"2026-05-09T05:12:07.852997Z","steps":["trace[436341445] 'process raft request' (duration: 408.979856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:07.853183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:07.443817Z","time spent":"409.277034ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:08.384937Z","caller":"traceutil/trace.go:171","msg":"trace[1959932573] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"527.532046ms","start":"2026-05-09T05:12:07.857376Z","end":"2026-05-09T05:12:08.384908Z","steps":["trace[1959932573] 'process raft request' (duration: 507.572199ms)","trace[1959932573] 'compare' (duration: 19.800072ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:08.385066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:07.857361Z","time spent":"527.637488ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:09.351587Z","caller":"traceutil/trace.go:171","msg":"trace[777507426] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"1.493801582s","start":"2026-05-09T05:12:07.857756Z","end":"2026-05-09T05:12:09.351558Z","steps":["trace[777507426] 'process raft request' (duration: 1.493571386s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.351812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:07.857664Z","time spent":"1.494023818s","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:09.351986Z","caller":"traceutil/trace.go:171","msg":"trace[488042815] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"1.493779692s","start":"2026-05-09T05:12:07.858191Z","end":"2026-05-09T05:12:09.35197Z","steps":["trace[488042815] 'process raft request' (duration: 1.49331148s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:09.352032Z","caller":"traceutil/trace.go:171","msg":"trace[1298965198] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"1.493460353s","start":"2026-05-09T05:12:07.858532Z","end":"2026-05-09T05:12:09.351992Z","steps":["trace[1298965198] 'process raft request' (duration: 1.493244708s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.352074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:07.858161Z","time spent":"1.493870273s","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:09.351954Z","caller":"traceutil/trace.go:171","msg":"trace[437560130] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1111; }","duration":"1.135504953s","start":"2026-05-09T05:12:08.216426Z","end":"2026-05-09T05:12:09.351931Z","steps":["trace[437560130] 'read index received' (duration: 148.489579ms)","trace[437560130] 'applied index is now lower than readState.Index' (duration: 987.013354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:09.352231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:07.858518Z","time spent":"1.493603857s","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:09.352326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.237086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T05:12:09.352328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.879874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:09.352381Z","caller":"traceutil/trace.go:171","msg":"trace[588437347] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1078; }","duration":"985.325619ms","start":"2026-05-09T05:12:08.367043Z","end":"2026-05-09T05:12:09.352369Z","steps":["trace[588437347] 'agreement among raft nodes before linearized reading' (duration: 985.196316ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:09.352399Z","caller":"traceutil/trace.go:171","msg":"trace[995627580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"183.990397ms","start":"2026-05-09T05:12:09.16839Z","end":"2026-05-09T05:12:09.352381Z","steps":["trace[995627580] 'agreement among raft nodes before linearized reading' (duration: 183.882834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.352373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135935915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:12:09.352432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.151615ms","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":"warn","ts":"2026-05-09T05:12:09.352486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.879002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:09.352507Z","caller":"traceutil/trace.go:171","msg":"trace[1945224063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"830.908413ms","start":"2026-05-09T05:12:08.521589Z","end":"2026-05-09T05:12:09.352498Z","steps":["trace[1945224063] 'agreement among raft nodes before linearized reading' (duration: 830.886032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.352554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:08.521582Z","time spent":"830.967785ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:12:09.352544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.467857ms","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-05-09T05:12:09.352584Z","caller":"traceutil/trace.go:171","msg":"trace[1784876338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"848.556929ms","start":"2026-05-09T05:12:08.504014Z","end":"2026-05-09T05:12:09.352571Z","steps":["trace[1784876338] 'agreement among raft nodes before linearized reading' (duration: 848.385135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.352416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:08.36703Z","time spent":"985.379121ms","remote":"127.0.0.1:53002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:12:09.352623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:08.504004Z","time spent":"848.612151ms","remote":"127.0.0.1:52928","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-05-09T05:12:09.352498Z","caller":"traceutil/trace.go:171","msg":"trace[627884037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1078; }","duration":"1.136105089s","start":"2026-05-09T05:12:08.216372Z","end":"2026-05-09T05:12:09.352477Z","steps":["trace[627884037] 'agreement among raft nodes before linearized reading' (duration: 1.135652827s)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:09.352506Z","caller":"traceutil/trace.go:171","msg":"trace[258847851] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1078; }","duration":"155.253907ms","start":"2026-05-09T05:12:09.197237Z","end":"2026-05-09T05:12:09.352491Z","steps":["trace[258847851] 'agreement among raft nodes before linearized reading' (duration: 155.144995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.352695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:08.216352Z","time spent":"1.136320054s","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:12:09.352454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.623095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:09.352785Z","caller":"traceutil/trace.go:171","msg":"trace[1994582371] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1078; }","duration":"955.982115ms","start":"2026-05-09T05:12:08.396795Z","end":"2026-05-09T05:12:09.352777Z","steps":["trace[1994582371] 'agreement among raft nodes before linearized reading' (duration: 955.639716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:09.35281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:08.396781Z","time spent":"956.021215ms","remote":"127.0.0.1:40496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-05-09T05:12:09.642188Z","caller":"traceutil/trace.go:171","msg":"trace[1822492007] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"108.228487ms","start":"2026-05-09T05:12:09.533932Z","end":"2026-05-09T05:12:09.64216Z","steps":["trace[1822492007] 'read index received' (duration: 80.964547ms)","trace[1822492007] 'applied index is now lower than readState.Index' (duration: 27.26282ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:09.64222Z","caller":"traceutil/trace.go:171","msg":"trace[2112850909] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"119.339841ms","start":"2026-05-09T05:12:09.522832Z","end":"2026-05-09T05:12:09.642172Z","steps":["trace[2112850909] 'process raft request' (duration: 92.131042ms)","trace[2112850909] 'compare' (duration: 27.048934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:09.642379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.420682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:09.642445Z","caller":"traceutil/trace.go:171","msg":"trace[697166892] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1080; }","duration":"108.541115ms","start":"2026-05-09T05:12:09.533889Z","end":"2026-05-09T05:12:09.64243Z","steps":["trace[697166892] 'agreement among raft nodes before linearized reading' (duration: 108.369561ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:11.507441Z","caller":"traceutil/trace.go:171","msg":"trace[199053977] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"145.216333ms","start":"2026-05-09T05:12:11.3622Z","end":"2026-05-09T05:12:11.507416Z","steps":["trace[199053977] 'process raft request' (duration: 144.997716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:12.284815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.330289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:12:12.285051Z","caller":"traceutil/trace.go:171","msg":"trace[598549470] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1121; }","duration":"916.079841ms","start":"2026-05-09T05:12:11.368955Z","end":"2026-05-09T05:12:12.285035Z","steps":["trace[598549470] 'read index received' (duration: 138.30249ms)","trace[598549470] 'applied index is now lower than readState.Index' (duration: 777.776431ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:12.285163Z","caller":"traceutil/trace.go:171","msg":"trace[1510514289] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"922.514561ms","start":"2026-05-09T05:12:11.362638Z","end":"2026-05-09T05:12:12.285153Z","steps":["trace[1510514289] 'process raft request' (duration: 922.269675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:12.285232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:11.362624Z","time spent":"922.572663ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:12.285446Z","caller":"traceutil/trace.go:171","msg":"trace[1742948965] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"325.283857ms","start":"2026-05-09T05:12:11.960145Z","end":"2026-05-09T05:12:12.285429Z","steps":["trace[1742948965] 'process raft request' (duration: 324.852345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:12.285546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.590555ms","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-05-09T05:12:12.285585Z","caller":"traceutil/trace.go:171","msg":"trace[1934127794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"916.642516ms","start":"2026-05-09T05:12:11.368932Z","end":"2026-05-09T05:12:12.285575Z","steps":["trace[1934127794] 'agreement among raft nodes before linearized reading' (duration: 916.492712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:12.285594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:11.960122Z","time spent":"325.389899ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:12.285622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:11.368923Z","time spent":"916.692697ms","remote":"127.0.0.1:52928","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-05-09T05:12:12.28562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.123252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:12.285727Z","caller":"traceutil/trace.go:171","msg":"trace[567101998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"117.274296ms","start":"2026-05-09T05:12:12.168435Z","end":"2026-05-09T05:12:12.28571Z","steps":["trace[567101998] 'agreement among raft nodes before linearized reading' (duration: 117.113812ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:12.28517Z","caller":"traceutil/trace.go:171","msg":"trace[1127582525] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"922.619434ms","start":"2026-05-09T05:12:11.362517Z","end":"2026-05-09T05:12:12.285136Z","steps":["trace[1127582525] 'process raft request' (duration: 735.867654ms)","trace[1127582525] 'compare' (duration: 186.039301ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:12.285922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:11.362496Z","time spent":"923.381684ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:14.14423Z","caller":"traceutil/trace.go:171","msg":"trace[1646683735] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"152.399244ms","start":"2026-05-09T05:12:13.991808Z","end":"2026-05-09T05:12:14.144207Z","steps":["trace[1646683735] 'read index received' (duration: 152.170598ms)","trace[1646683735] 'applied index is now lower than readState.Index' (duration: 227.846µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:14.144355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.522967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:14.144402Z","caller":"traceutil/trace.go:171","msg":"trace[304173645] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1091; }","duration":"152.608699ms","start":"2026-05-09T05:12:13.991775Z","end":"2026-05-09T05:12:14.144383Z","steps":["trace[304173645] 'agreement among raft nodes before linearized reading' (duration: 152.522937ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:14.144504Z","caller":"traceutil/trace.go:171","msg":"trace[74111648] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"218.082907ms","start":"2026-05-09T05:12:13.926179Z","end":"2026-05-09T05:12:14.144262Z","steps":["trace[74111648] 'process raft request' (duration: 217.859121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.751558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.443871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:14.751635Z","caller":"traceutil/trace.go:171","msg":"trace[1262829780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"583.560854ms","start":"2026-05-09T05:12:14.168057Z","end":"2026-05-09T05:12:14.751618Z","steps":["trace[1262829780] 'range keys from in-memory index tree' (duration: 583.37019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.751666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.168041Z","time spent":"583.619117ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:12:14.751895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.823701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:12:14.751936Z","caller":"traceutil/trace.go:171","msg":"trace[1097119178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"461.906193ms","start":"2026-05-09T05:12:14.290022Z","end":"2026-05-09T05:12:14.751929Z","steps":["trace[1097119178] 'range keys from in-memory index tree' (duration: 461.688477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.751962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.29Z","time spent":"461.955154ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:14.752101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.653936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:12:14.752178Z","caller":"traceutil/trace.go:171","msg":"trace[669114500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"461.761229ms","start":"2026-05-09T05:12:14.290399Z","end":"2026-05-09T05:12:14.75216Z","steps":["trace[669114500] 'range keys from in-memory index tree' (duration: 461.513103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.752225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.290391Z","time spent":"461.824331ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:12:14.752281Z","caller":"traceutil/trace.go:171","msg":"trace[1168704526] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"461.380059ms","start":"2026-05-09T05:12:14.290878Z","end":"2026-05-09T05:12:14.752258Z","steps":["trace[1168704526] 'process raft request' (duration: 452.478005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.752336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.408834ms","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-05-09T05:12:14.752378Z","caller":"traceutil/trace.go:171","msg":"trace[1292416353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"371.492667ms","start":"2026-05-09T05:12:14.380876Z","end":"2026-05-09T05:12:14.752368Z","steps":["trace[1292416353] 'agreement among raft nodes before linearized reading' (duration: 371.372694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:14.752412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.380852Z","time spent":"371.552478ms","remote":"127.0.0.1:52928","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-05-09T05:12:14.752228Z","caller":"traceutil/trace.go:171","msg":"trace[1397963933] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"371.293441ms","start":"2026-05-09T05:12:14.380924Z","end":"2026-05-09T05:12:14.752217Z","steps":["trace[1397963933] 'read index received' (duration: 362.451518ms)","trace[1397963933] 'applied index is now lower than readState.Index' (duration: 8.841113ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:14.752449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.290865Z","time spent":"461.504782ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:15.993762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132942586s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T05:12:15.993858Z","caller":"traceutil/trace.go:171","msg":"trace[325634438] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"1.241362138s","start":"2026-05-09T05:12:14.752478Z","end":"2026-05-09T05:12:15.99384Z","steps":["trace[325634438] 'read index received' (duration: 108.414292ms)","trace[325634438] 'applied index is now lower than readState.Index' (duration: 1.132946516s)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:15.994321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533445588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:12:15.994393Z","caller":"traceutil/trace.go:171","msg":"trace[1396436861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"1.533551251s","start":"2026-05-09T05:12:14.460825Z","end":"2026-05-09T05:12:15.994377Z","steps":["trace[1396436861] 'agreement among raft nodes before linearized reading' (duration: 1.533338356s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:15.99443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.460811Z","time spent":"1.533611222s","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:12:16.014668Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153725844s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T05:12:16.015102Z","caller":"traceutil/trace.go:171","msg":"trace[753270704] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"1.258826271s","start":"2026-05-09T05:12:14.75626Z","end":"2026-05-09T05:12:16.015086Z","steps":["trace[753270704] 'process raft request' (duration: 1.258647246s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:16.015207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.756239Z","time spent":"1.258909332s","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:16.015432Z","caller":"traceutil/trace.go:171","msg":"trace[1752033709] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"1.258779129s","start":"2026-05-09T05:12:14.756643Z","end":"2026-05-09T05:12:16.015422Z","steps":["trace[1752033709] 'process raft request' (duration: 1.258388249s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:16.015489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.756632Z","time spent":"1.25882634s","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:16.015481Z","caller":"traceutil/trace.go:171","msg":"trace[1664465578] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"1.258655216s","start":"2026-05-09T05:12:14.756797Z","end":"2026-05-09T05:12:16.015453Z","steps":["trace[1664465578] 'process raft request' (duration: 1.258507002s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:16.01554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262475117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:16.015622Z","caller":"traceutil/trace.go:171","msg":"trace[431369430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"1.262597069s","start":"2026-05-09T05:12:14.753004Z","end":"2026-05-09T05:12:16.015601Z","steps":["trace[431369430] 'agreement among raft nodes before linearized reading' (duration: 1.262426895s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:16.015679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.328172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T05:12:16.015676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.752988Z","time spent":"1.262675282s","remote":"127.0.0.1:58712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:12:16.015656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:14.756789Z","time spent":"1.258770699s","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:16.015708Z","caller":"traceutil/trace.go:171","msg":"trace[1683922271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"482.402584ms","start":"2026-05-09T05:12:15.533297Z","end":"2026-05-09T05:12:16.015699Z","steps":["trace[1683922271] 'agreement among raft nodes before linearized reading' (duration: 482.299191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:16.015737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:15.533273Z","time spent":"482.457495ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:12:16.382807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.026884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:12:16.383006Z","caller":"traceutil/trace.go:171","msg":"trace[1048752045] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"358.893624ms","start":"2026-05-09T05:12:16.024095Z","end":"2026-05-09T05:12:16.382988Z","steps":["trace[1048752045] 'process raft request' (duration: 358.839843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:16.383079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:16.024053Z","time spent":"358.993917ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:16.383106Z","caller":"traceutil/trace.go:171","msg":"trace[538702148] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"359.555581ms","start":"2026-05-09T05:12:16.023532Z","end":"2026-05-09T05:12:16.383087Z","steps":["trace[538702148] 'read index received' (duration: 124.180987ms)","trace[538702148] 'applied index is now lower than readState.Index' (duration: 235.371844ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:16.383092Z","caller":"traceutil/trace.go:171","msg":"trace[1744565018] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"384.406357ms","start":"2026-05-09T05:12:15.998655Z","end":"2026-05-09T05:12:16.383061Z","steps":["trace[1744565018] 'process raft request' (duration: 149.038054ms)","trace[1744565018] 'compare' (duration: 234.924822ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:16.383206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.648444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:16.38325Z","caller":"traceutil/trace.go:171","msg":"trace[1487131329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"359.731546ms","start":"2026-05-09T05:12:16.023506Z","end":"2026-05-09T05:12:16.383237Z","steps":["trace[1487131329] 'agreement among raft nodes before linearized reading' (duration: 359.638763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:16.38325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:15.998634Z","time spent":"384.526591ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:16.383288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:16.023492Z","time spent":"359.788468ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T05:12:22.548818Z","caller":"traceutil/trace.go:171","msg":"trace[1360741673] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"139.288028ms","start":"2026-05-09T05:12:22.409501Z","end":"2026-05-09T05:12:22.548789Z","steps":["trace[1360741673] 'process raft request' (duration: 138.036734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.482709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.989136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:12:28.482885Z","caller":"traceutil/trace.go:171","msg":"trace[2092939854] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"307.866897ms","start":"2026-05-09T05:12:28.174999Z","end":"2026-05-09T05:12:28.482866Z","steps":["trace[2092939854] 'process raft request' (duration: 95.628074ms)","trace[2092939854] 'compare' (duration: 211.867953ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:28.482899Z","caller":"traceutil/trace.go:171","msg":"trace[2086641450] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"306.935212ms","start":"2026-05-09T05:12:28.175947Z","end":"2026-05-09T05:12:28.482882Z","steps":["trace[2086641450] 'process raft request' (duration: 306.879101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:28.482969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:28.175932Z","time spent":"307.004563ms","remote":"127.0.0.1:52928","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-05-09T05:12:28.483029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:28.174989Z","time spent":"307.938889ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:30.002451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:29.454103Z","time spent":"548.345405ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-09T05:12:42.367571Z","caller":"traceutil/trace.go:171","msg":"trace[389911370] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"114.638257ms","start":"2026-05-09T05:12:42.252905Z","end":"2026-05-09T05:12:42.367544Z","steps":["trace[389911370] 'process raft request' (duration: 114.456961ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:50.044021Z","caller":"traceutil/trace.go:171","msg":"trace[1915993699] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"141.792837ms","start":"2026-05-09T05:12:49.902194Z","end":"2026-05-09T05:12:50.043987Z","steps":["trace[1915993699] 'read index received' (duration: 93.775403ms)","trace[1915993699] 'applied index is now lower than readState.Index' (duration: 48.014264ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:12:50.044018Z","caller":"traceutil/trace.go:171","msg":"trace[1448721409] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"534.978841ms","start":"2026-05-09T05:12:49.50901Z","end":"2026-05-09T05:12:50.043989Z","steps":["trace[1448721409] 'process raft request' (duration: 486.953036ms)","trace[1448721409] 'compare' (duration: 47.892541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:50.044178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.974151ms","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":"warn","ts":"2026-05-09T05:12:50.044196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:49.508991Z","time spent":"535.120195ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:50.044247Z","caller":"traceutil/trace.go:171","msg":"trace[150725353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1290; }","duration":"142.087324ms","start":"2026-05-09T05:12:49.902145Z","end":"2026-05-09T05:12:50.044232Z","steps":["trace[150725353] 'agreement among raft nodes before linearized reading' (duration: 141.93762ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:50.846497Z","caller":"traceutil/trace.go:171","msg":"trace[945226110] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"355.355613ms","start":"2026-05-09T05:12:50.491097Z","end":"2026-05-09T05:12:50.846452Z","steps":["trace[945226110] 'process raft request' (duration: 355.141017ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:50.846505Z","caller":"traceutil/trace.go:171","msg":"trace[1121144155] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"238.447929ms","start":"2026-05-09T05:12:50.60799Z","end":"2026-05-09T05:12:50.846438Z","steps":["trace[1121144155] 'read index received' (duration: 238.179163ms)","trace[1121144155] 'applied index is now lower than readState.Index' (duration: 262.476µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:50.846677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:50.491056Z","time spent":"355.527507ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:51.028699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.681532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:12:51.028795Z","caller":"traceutil/trace.go:171","msg":"trace[2072989446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1292; }","duration":"420.832266ms","start":"2026-05-09T05:12:50.607937Z","end":"2026-05-09T05:12:51.028769Z","steps":["trace[2072989446] 'agreement among raft nodes before linearized reading' (duration: 238.615643ms)","trace[2072989446] 'range keys from in-memory index tree' (duration: 181.959706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:51.028847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:50.607913Z","time spent":"420.924809ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T05:12:51.028846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.218268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-09T05:12:51.028925Z","caller":"traceutil/trace.go:171","msg":"trace[1978600940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1292; }","duration":"387.33516ms","start":"2026-05-09T05:12:50.641567Z","end":"2026-05-09T05:12:51.028902Z","steps":["trace[1978600940] 'agreement among raft nodes before linearized reading' (duration: 205.204409ms)","trace[1978600940] 'range keys from in-memory index tree' (duration: 181.922295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:51.028931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.279894ms","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-05-09T05:12:51.028979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:50.641552Z","time spent":"387.418403ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T05:12:51.029028Z","caller":"traceutil/trace.go:171","msg":"trace[168357566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"416.399747ms","start":"2026-05-09T05:12:50.6126Z","end":"2026-05-09T05:12:51.028999Z","steps":["trace[168357566] 'agreement among raft nodes before linearized reading' (duration: 234.138553ms)","trace[168357566] 'range keys from in-memory index tree' (duration: 182.003648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:51.029103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:50.612591Z","time spent":"416.495089ms","remote":"127.0.0.1:52928","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-05-09T05:12:51.029079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.150583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:12:51.029192Z","caller":"traceutil/trace.go:171","msg":"trace[1369028163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"238.304566ms","start":"2026-05-09T05:12:50.790859Z","end":"2026-05-09T05:12:51.029164Z","steps":["trace[1369028163] 'agreement among raft nodes before linearized reading' (duration: 55.904588ms)","trace[1369028163] 'range keys from in-memory index tree' (duration: 182.140722ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:51.535606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156343754672118,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T05:12:51.537754Z","caller":"traceutil/trace.go:171","msg":"trace[285227517] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"573.979573ms","start":"2026-05-09T05:12:50.963751Z","end":"2026-05-09T05:12:51.53773Z","steps":["trace[285227517] 'process raft request' (duration: 573.808759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.537908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:50.963727Z","time spent":"574.098386ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:51.845945Z","caller":"traceutil/trace.go:171","msg":"trace[2132365896] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"812.198237ms","start":"2026-05-09T05:12:51.033719Z","end":"2026-05-09T05:12:51.845917Z","steps":["trace[2132365896] 'process raft request' (duration: 812.009972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.846102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.033662Z","time spent":"812.36495ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:51.846288Z","caller":"traceutil/trace.go:171","msg":"trace[1911383375] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"812.503364ms","start":"2026-05-09T05:12:51.033762Z","end":"2026-05-09T05:12:51.846265Z","steps":["trace[1911383375] 'process raft request' (duration: 812.110605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.846415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.033726Z","time spent":"812.624616ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:51.846515Z","caller":"traceutil/trace.go:171","msg":"trace[178049775] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"812.33743ms","start":"2026-05-09T05:12:51.03417Z","end":"2026-05-09T05:12:51.846508Z","steps":["trace[178049775] 'process raft request' (duration: 812.026833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.84655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.034159Z","time spent":"812.372861ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:51.846632Z","caller":"traceutil/trace.go:171","msg":"trace[1675542224] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"812.35433ms","start":"2026-05-09T05:12:51.034246Z","end":"2026-05-09T05:12:51.8466Z","steps":["trace[1675542224] 'process raft request' (duration: 812.154326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.846799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.034239Z","time spent":"812.469323ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:12:51.847045Z","caller":"traceutil/trace.go:171","msg":"trace[751632700] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"332.900118ms","start":"2026-05-09T05:12:51.51413Z","end":"2026-05-09T05:12:51.84703Z","steps":["trace[751632700] 'process raft request' (duration: 332.391925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.847131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.514109Z","time spent":"332.974469ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:12:51.847242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.231856ms","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-05-09T05:12:51.847294Z","caller":"traceutil/trace.go:171","msg":"trace[1669305722] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1298; }","duration":"812.321199ms","start":"2026-05-09T05:12:51.034956Z","end":"2026-05-09T05:12:51.847277Z","steps":["trace[1669305722] 'agreement among raft nodes before linearized reading' (duration: 812.228117ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:12:51.847265Z","caller":"traceutil/trace.go:171","msg":"trace[426935596] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1340; }","duration":"811.992121ms","start":"2026-05-09T05:12:51.034992Z","end":"2026-05-09T05:12:51.846985Z","steps":["trace[426935596] 'read index received' (duration: 502.548551ms)","trace[426935596] 'applied index is now lower than readState.Index' (duration: 309.44238ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:12:51.847331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.034948Z","time spent":"812.374161ms","remote":"127.0.0.1:40406","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-05-09T05:12:51.847553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.335953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:51.847609Z","caller":"traceutil/trace.go:171","msg":"trace[1760584476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"673.434286ms","start":"2026-05-09T05:12:51.174159Z","end":"2026-05-09T05:12:51.847593Z","steps":["trace[1760584476] 'agreement among raft nodes before linearized reading' (duration: 673.351994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.847631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.99282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-09T05:12:51.847662Z","caller":"traceutil/trace.go:171","msg":"trace[1793076913] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1298; }","duration":"191.070092ms","start":"2026-05-09T05:12:51.656582Z","end":"2026-05-09T05:12:51.847652Z","steps":["trace[1793076913] 'agreement among raft nodes before linearized reading' (duration: 190.951179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.847683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.572788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:12:51.847721Z","caller":"traceutil/trace.go:171","msg":"trace[497945484] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1298; }","duration":"325.64392ms","start":"2026-05-09T05:12:51.522068Z","end":"2026-05-09T05:12:51.847712Z","steps":["trace[497945484] 'agreement among raft nodes before linearized reading' (duration: 325.568029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:51.847714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.174136Z","time spent":"673.503048ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:12:51.847755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:51.522062Z","time spent":"325.681171ms","remote":"127.0.0.1:53024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-05-09T05:12:53.259139Z","caller":"traceutil/trace.go:171","msg":"trace[1167629152] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"280.906185ms","start":"2026-05-09T05:12:52.978197Z","end":"2026-05-09T05:12:53.259103Z","steps":["trace[1167629152] 'process raft request' (duration: 273.740699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:56.560963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.044676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:12:56.561082Z","caller":"traceutil/trace.go:171","msg":"trace[304780119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1339; }","duration":"392.21203ms","start":"2026-05-09T05:12:56.168846Z","end":"2026-05-09T05:12:56.561058Z","steps":["trace[304780119] 'range keys from in-memory index tree' (duration: 391.953365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:12:56.561164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:56.168823Z","time spent":"392.325564ms","remote":"127.0.0.1:58700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T05:12:59.954238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088156343754672225,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T05:13:00.164889Z","caller":"traceutil/trace.go:171","msg":"trace[79666771] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"733.961627ms","start":"2026-05-09T05:12:59.430902Z","end":"2026-05-09T05:13:00.164864Z","steps":["trace[79666771] 'process raft request' (duration: 733.807994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:00.16515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:59.430878Z","time spent":"734.08483ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:00.166091Z","caller":"traceutil/trace.go:171","msg":"trace[1674054629] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1400; }","duration":"712.878237ms","start":"2026-05-09T05:12:59.453195Z","end":"2026-05-09T05:13:00.166073Z","steps":["trace[1674054629] 'read index received' (duration: 711.502403ms)","trace[1674054629] 'applied index is now lower than readState.Index' (duration: 1.374934ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:00.166214Z","caller":"traceutil/trace.go:171","msg":"trace[1246900086] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"582.656927ms","start":"2026-05-09T05:12:59.583542Z","end":"2026-05-09T05:13:00.166199Z","steps":["trace[1246900086] 'process raft request' (duration: 582.449782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:00.166306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:59.583517Z","time spent":"582.733768ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:00.166874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.451189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-09T05:13:00.166882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.49674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T05:13:00.166953Z","caller":"traceutil/trace.go:171","msg":"trace[1172617932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1353; }","duration":"285.550901ms","start":"2026-05-09T05:12:59.881383Z","end":"2026-05-09T05:13:00.166934Z","steps":["trace[1172617932] 'agreement among raft nodes before linearized reading' (duration: 285.290524ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:00.166976Z","caller":"traceutil/trace.go:171","msg":"trace[1314197000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"285.614902ms","start":"2026-05-09T05:12:59.881341Z","end":"2026-05-09T05:13:00.166956Z","steps":["trace[1314197000] 'agreement among raft nodes before linearized reading' (duration: 285.416427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:00.166876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.367836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-09T05:13:00.16707Z","caller":"traceutil/trace.go:171","msg":"trace[621151845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1353; }","duration":"285.597512ms","start":"2026-05-09T05:12:59.881441Z","end":"2026-05-09T05:13:00.167038Z","steps":["trace[621151845] 'agreement among raft nodes before linearized reading' (duration: 285.240963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:00.166572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.380049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T05:13:00.16776Z","caller":"traceutil/trace.go:171","msg":"trace[735149959] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:1353; }","duration":"714.59564ms","start":"2026-05-09T05:12:59.453135Z","end":"2026-05-09T05:13:00.167731Z","steps":["trace[735149959] 'agreement among raft nodes before linearized reading' (duration: 713.304108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:00.167861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:12:59.453106Z","time spent":"714.715032ms","remote":"127.0.0.1:52724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"warn","ts":"2026-05-09T05:13:00.168094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.700468ms","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-05-09T05:13:00.168187Z","caller":"traceutil/trace.go:171","msg":"trace[289082229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1353; }","duration":"290.089163ms","start":"2026-05-09T05:12:59.878048Z","end":"2026-05-09T05:13:00.168137Z","steps":["trace[289082229] 'agreement among raft nodes before linearized reading' (duration: 288.615886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:00.455988Z","caller":"traceutil/trace.go:171","msg":"trace[173566395] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"279.373248ms","start":"2026-05-09T05:13:00.176575Z","end":"2026-05-09T05:13:00.455948Z","steps":["trace[173566395] 'process raft request' (duration: 241.516535ms)","trace[173566395] 'compare' (duration: 37.450093ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:01.150685Z","caller":"traceutil/trace.go:171","msg":"trace[950492027] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"176.569404ms","start":"2026-05-09T05:13:00.974099Z","end":"2026-05-09T05:13:01.150669Z","steps":["trace[950492027] 'read index received' (duration: 176.338158ms)","trace[950492027] 'applied index is now lower than readState.Index' (duration: 230.636µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:01.150981Z","caller":"traceutil/trace.go:171","msg":"trace[866798253] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"476.969081ms","start":"2026-05-09T05:13:00.674004Z","end":"2026-05-09T05:13:01.150973Z","steps":["trace[866798253] 'process raft request' (duration: 476.511169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:01.15105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:00.673984Z","time spent":"477.022353ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:13:01.150906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.79735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:13:01.151861Z","caller":"traceutil/trace.go:171","msg":"trace[1828256406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1359; }","duration":"177.5865ms","start":"2026-05-09T05:13:00.974063Z","end":"2026-05-09T05:13:01.151649Z","steps":["trace[1828256406] 'agreement among raft nodes before linearized reading' (duration: 176.748979ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:01.323154Z","caller":"traceutil/trace.go:171","msg":"trace[235841399] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"155.642548ms","start":"2026-05-09T05:13:01.167489Z","end":"2026-05-09T05:13:01.323131Z","steps":["trace[235841399] 'read index received' (duration: 152.426389ms)","trace[235841399] 'applied index is now lower than readState.Index' (duration: 3.215449ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:01.323417Z","caller":"traceutil/trace.go:171","msg":"trace[1332019017] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"166.472014ms","start":"2026-05-09T05:13:01.156894Z","end":"2026-05-09T05:13:01.323366Z","steps":["trace[1332019017] 'process raft request' (duration: 163.096401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:01.323493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.851753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:13:01.323674Z","caller":"traceutil/trace.go:171","msg":"trace[1596492601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"156.272563ms","start":"2026-05-09T05:13:01.167389Z","end":"2026-05-09T05:13:01.323661Z","steps":["trace[1596492601] 'agreement among raft nodes before linearized reading' (duration: 155.840733ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:15.658471Z","caller":"traceutil/trace.go:171","msg":"trace[208683278] linearizableReadLoop","detail":"{readStateIndex:1475; appliedIndex:1474; }","duration":"447.298108ms","start":"2026-05-09T05:13:15.21114Z","end":"2026-05-09T05:13:15.658439Z","steps":["trace[208683278] 'read index received' (duration: 447.150844ms)","trace[208683278] 'applied index is now lower than readState.Index' (duration: 146.344µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:15.65852Z","caller":"traceutil/trace.go:171","msg":"trace[756004741] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"464.635046ms","start":"2026-05-09T05:13:15.193856Z","end":"2026-05-09T05:13:15.658491Z","steps":["trace[756004741] 'process raft request' (duration: 464.441481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:15.658731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.570485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-09T05:13:15.65878Z","caller":"traceutil/trace.go:171","msg":"trace[486514785] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1423; }","duration":"447.657367ms","start":"2026-05-09T05:13:15.211111Z","end":"2026-05-09T05:13:15.658769Z","steps":["trace[486514785] 'agreement among raft nodes before linearized reading' (duration: 447.473182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:15.658824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:15.211098Z","time spent":"447.709528ms","remote":"127.0.0.1:52928","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-05-09T05:13:15.658853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:15.193841Z","time spent":"464.737509ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:13:15.687223Z","caller":"traceutil/trace.go:171","msg":"trace[1959800603] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"180.938942ms","start":"2026-05-09T05:13:15.506264Z","end":"2026-05-09T05:13:15.687203Z","steps":["trace[1959800603] 'process raft request' (duration: 178.45221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:15.69653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.842418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:13:15.696629Z","caller":"traceutil/trace.go:171","msg":"trace[729171614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1425; }","duration":"314.007872ms","start":"2026-05-09T05:13:15.3826Z","end":"2026-05-09T05:13:15.696608Z","steps":["trace[729171614] 'agreement among raft nodes before linearized reading' (duration: 313.745956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:15.6967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:15.382582Z","time spent":"314.105824ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T05:13:30.160659Z","caller":"traceutil/trace.go:171","msg":"trace[1856387169] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"327.442254ms","start":"2026-05-09T05:13:29.833184Z","end":"2026-05-09T05:13:30.160627Z","steps":["trace[1856387169] 'process raft request' (duration: 327.238709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:30.16089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:29.83317Z","time spent":"327.612518ms","remote":"127.0.0.1:52928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:14:08.718411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.42585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:14:08.718495Z","caller":"traceutil/trace.go:171","msg":"trace[1364593724] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1666; }","duration":"177.555734ms","start":"2026-05-09T05:14:08.54092Z","end":"2026-05-09T05:14:08.718476Z","steps":["trace[1364593724] 'count revisions from in-memory index tree' (duration: 177.338738ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:09.049584Z","caller":"traceutil/trace.go:171","msg":"trace[1355368288] linearizableReadLoop","detail":"{readStateIndex:1733; appliedIndex:1732; }","duration":"124.613603ms","start":"2026-05-09T05:14:08.924856Z","end":"2026-05-09T05:14:09.04947Z","steps":["trace[1355368288] 'read index received' (duration: 1.413643ms)","trace[1355368288] 'applied index is now lower than readState.Index' (duration: 123.19865ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:09.04957Z","caller":"traceutil/trace.go:171","msg":"trace[977574284] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"124.798958ms","start":"2026-05-09T05:14:08.92474Z","end":"2026-05-09T05:14:09.049539Z","steps":["trace[977574284] 'process raft request' (duration: 45.06593ms)","trace[977574284] 'compare' (duration: 79.563174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:14:09.050067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.165266ms","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-05-09T05:14:09.050238Z","caller":"traceutil/trace.go:171","msg":"trace[1084369659] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1670; }","duration":"125.336601ms","start":"2026-05-09T05:14:08.924828Z","end":"2026-05-09T05:14:09.050164Z","steps":["trace[1084369659] 'agreement among raft nodes before linearized reading' (duration: 124.816668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:43.2781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.201961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:14:43.278183Z","caller":"traceutil/trace.go:171","msg":"trace[176068140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1825; }","duration":"109.331545ms","start":"2026-05-09T05:14:43.168837Z","end":"2026-05-09T05:14:43.278168Z","steps":["trace[176068140] 'range keys from in-memory index tree' (duration: 109.115719ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:15:23.356836Z","caller":"traceutil/trace.go:171","msg":"trace[116012982] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"157.832407ms","start":"2026-05-09T05:15:23.198969Z","end":"2026-05-09T05:15:23.356802Z","steps":["trace[116012982] 'process raft request' (duration: 157.565971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:11.959357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.440485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-09T05:16:11.959479Z","caller":"traceutil/trace.go:171","msg":"trace[1596449307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2257; }","duration":"112.606137ms","start":"2026-05-09T05:16:11.84684Z","end":"2026-05-09T05:16:11.959446Z","steps":["trace[1596449307] 'range keys from in-memory index tree' (duration: 112.18917ms)"],"step_count":1}