{"level":"warn","ts":"2026-05-10T00:49:39.245816Z","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-10T00:49:39.245944Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-10T00:49:39.246049Z","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-10T00:49:39.246065Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-10T00:49:39.246091Z","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-10T00:49:39.246851Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-05-10T00:49:39.246938Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-10T00:49:39.251539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.389311ms"} {"level":"info","ts":"2026-05-10T00:49:39.260119Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-05-10T00:49:39.260238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-10T00:49:39.260326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-05-10T00:49:39.260347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-10T00:49:39.260401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-05-10T00:49:39.260474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-05-10T00:49:39.269672Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-10T00:49:39.271014Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-10T00:49:39.272565Z","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-10T00:49:39.274931Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-10T00:49:39.275196Z","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-10T00:49:39.275326Z","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-10T00:49:39.275341Z","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-10T00:49:39.275313Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-10T00:49:39.276025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-05-10T00:49:39.276171Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-10T00:49:39.281193Z","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-10T00:49:39.281347Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-10T00:49:39.281413Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-10T00:49:39.282288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-10T00:49:39.282329Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-10T00:49:39.361341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-10T00:49:39.361413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-10T00:49:39.361452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-05-10T00:49:39.361525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-05-10T00:49:39.361577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-10T00:49:39.361598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-05-10T00:49:39.361614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-10T00:49:39.362781Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-10T00:49:39.362864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:49:39.363175Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-10T00:49:39.363203Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:49:39.363382Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-10T00:49:39.363472Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-10T00:49:39.367291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-10T00:49:39.367375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-05-10T00:49:39.372239Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:49:39.372374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:49:39.372412Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-10T00:49:42.948457Z","caller":"traceutil/trace.go:171","msg":"trace[1592343661] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"131.404561ms","start":"2026-05-10T00:49:42.816971Z","end":"2026-05-10T00:49:42.948376Z","steps":["trace[1592343661] 'process raft request' (duration: 43.859702ms)","trace[1592343661] 'compare' (duration: 87.141419ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:50:15.964701Z","caller":"traceutil/trace.go:171","msg":"trace[243566113] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"479.157079ms","start":"2026-05-10T00:50:15.485519Z","end":"2026-05-10T00:50:15.964676Z","steps":["trace[243566113] 'process raft request' (duration: 478.602406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:15.965129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:15.485498Z","time spent":"479.276462ms","remote":"127.0.0.1:41348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":807,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:50:17.051387Z","caller":"traceutil/trace.go:171","msg":"trace[97372907] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"254.647862ms","start":"2026-05-10T00:50:16.796714Z","end":"2026-05-10T00:50:17.051362Z","steps":["trace[97372907] 'process raft request' (duration: 254.431567ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:17.417836Z","caller":"traceutil/trace.go:171","msg":"trace[698783098] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"613.776025ms","start":"2026-05-10T00:50:16.80404Z","end":"2026-05-10T00:50:17.417816Z","steps":["trace[698783098] 'process raft request' (duration: 610.657873ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:17.41831Z","caller":"traceutil/trace.go:171","msg":"trace[320550477] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:580; }","duration":"360.984065ms","start":"2026-05-10T00:50:17.057309Z","end":"2026-05-10T00:50:17.418293Z","steps":["trace[320550477] 'read index received' (duration: 357.279669ms)","trace[320550477] 'applied index is now lower than readState.Index' (duration: 3.703686ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:50:17.41836Z","caller":"traceutil/trace.go:171","msg":"trace[918559974] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"611.111863ms","start":"2026-05-10T00:50:16.807242Z","end":"2026-05-10T00:50:17.418354Z","steps":["trace[918559974] 'process raft request' (duration: 610.745715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:17.418322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:16.804022Z","time spent":"613.875738ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:50:17.418394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:16.807216Z","time spent":"611.161205ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:50:17.418484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.141098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:50:17.418595Z","caller":"traceutil/trace.go:171","msg":"trace[1816314173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"361.307622ms","start":"2026-05-10T00:50:17.057267Z","end":"2026-05-10T00:50:17.418575Z","steps":["trace[1816314173] 'agreement among raft nodes before linearized reading' (duration: 361.123368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:17.41865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:17.057247Z","time spent":"361.396305ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:50:17.418896Z","caller":"traceutil/trace.go:171","msg":"trace[367135416] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"611.984364ms","start":"2026-05-10T00:50:16.806878Z","end":"2026-05-10T00:50:17.418862Z","steps":["trace[367135416] 'process raft request' (duration: 610.838897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:17.419098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:16.806867Z","time spent":"612.159838ms","remote":"127.0.0.1:41320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:50:23.616356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.978062ms","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-10T00:50:23.616453Z","caller":"traceutil/trace.go:171","msg":"trace[1826511026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"164.138105ms","start":"2026-05-10T00:50:23.452298Z","end":"2026-05-10T00:50:23.616436Z","steps":["trace[1826511026] 'range keys from in-memory index tree' (duration: 163.836689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:23.616652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.846073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-10T00:50:23.616688Z","caller":"traceutil/trace.go:171","msg":"trace[1066155020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"161.883434ms","start":"2026-05-10T00:50:23.454796Z","end":"2026-05-10T00:50:23.616679Z","steps":["trace[1066155020] 'range keys from in-memory index tree' (duration: 161.689449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:23.895244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.648709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:50:23.895432Z","caller":"traceutil/trace.go:171","msg":"trace[2079262927] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"136.124919ms","start":"2026-05-10T00:50:23.759294Z","end":"2026-05-10T00:50:23.895419Z","steps":["trace[2079262927] 'read index received' (duration: 127.537151ms)","trace[2079262927] 'applied index is now lower than readState.Index' (duration: 8.587058ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:50:23.895489Z","caller":"traceutil/trace.go:171","msg":"trace[1824309328] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"261.460469ms","start":"2026-05-10T00:50:23.634012Z","end":"2026-05-10T00:50:23.895473Z","steps":["trace[1824309328] 'process raft request' (duration: 261.327526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:23.895507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.209142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-10T00:50:23.895458Z","caller":"traceutil/trace.go:171","msg":"trace[568433840] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"273.662461ms","start":"2026-05-10T00:50:23.621761Z","end":"2026-05-10T00:50:23.895424Z","steps":["trace[568433840] 'process raft request' (duration: 117.598302ms)","trace[568433840] 'compare' (duration: 155.499116ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:50:23.89554Z","caller":"traceutil/trace.go:171","msg":"trace[1149943429] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:637; }","duration":"136.277343ms","start":"2026-05-10T00:50:23.759254Z","end":"2026-05-10T00:50:23.895531Z","steps":["trace[1149943429] 'agreement among raft nodes before linearized reading' (duration: 136.215661ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:24.229007Z","caller":"traceutil/trace.go:171","msg":"trace[1221219625] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"465.138197ms","start":"2026-05-10T00:50:23.763843Z","end":"2026-05-10T00:50:24.228982Z","steps":["trace[1221219625] 'process raft request' (duration: 464.517462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:24.229149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:23.763824Z","time spent":"465.24854ms","remote":"127.0.0.1:41368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:50:24.243363Z","caller":"traceutil/trace.go:171","msg":"trace[1561562028] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"145.520406ms","start":"2026-05-10T00:50:24.097821Z","end":"2026-05-10T00:50:24.243341Z","steps":["trace[1561562028] 'read index received' (duration: 130.450778ms)","trace[1561562028] 'applied index is now lower than readState.Index' (duration: 15.068678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:50:24.243503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:23.896523Z","time spent":"346.977332ms","remote":"127.0.0.1:41300","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-10T00:50:24.244668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.807155ms","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-10T00:50:24.244704Z","caller":"traceutil/trace.go:171","msg":"trace[659607674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"146.911008ms","start":"2026-05-10T00:50:24.097783Z","end":"2026-05-10T00:50:24.244694Z","steps":["trace[659607674] 'agreement among raft nodes before linearized reading' (duration: 146.039278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:24.244918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.644637ms","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-10T00:50:24.244943Z","caller":"traceutil/trace.go:171","msg":"trace[740883309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"141.682658ms","start":"2026-05-10T00:50:24.103254Z","end":"2026-05-10T00:50:24.244937Z","steps":["trace[740883309] 'agreement among raft nodes before linearized reading' (duration: 141.614546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:24.769321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297078153,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:50:25.212412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.016855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:50:25.213306Z","caller":"traceutil/trace.go:171","msg":"trace[660791912] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"962.522659ms","start":"2026-05-10T00:50:24.250253Z","end":"2026-05-10T00:50:25.212776Z","steps":["trace[660791912] 'process raft request' (duration: 561.966411ms)","trace[660791912] 'compare' (duration: 398.016469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:50:25.213477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:24.250232Z","time spent":"963.188783ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:50:25.214122Z","caller":"traceutil/trace.go:171","msg":"trace[1077128255] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"959.578941ms","start":"2026-05-10T00:50:24.254501Z","end":"2026-05-10T00:50:25.21408Z","steps":["trace[1077128255] 'process raft request' (duration: 958.089716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:25.214333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:24.254488Z","time spent":"959.739084ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:50:25.248225Z","caller":"traceutil/trace.go:171","msg":"trace[130526626] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:662; }","duration":"979.708485ms","start":"2026-05-10T00:50:24.268486Z","end":"2026-05-10T00:50:25.248194Z","steps":["trace[130526626] 'read index received' (duration: 543.74606ms)","trace[130526626] 'applied index is now lower than readState.Index' (duration: 435.961195ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:50:25.248374Z","caller":"traceutil/trace.go:171","msg":"trace[99202397] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"949.231512ms","start":"2026-05-10T00:50:24.29913Z","end":"2026-05-10T00:50:25.248361Z","steps":["trace[99202397] 'process raft request' (duration: 948.953326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:25.248577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:24.299112Z","time spent":"949.293453ms","remote":"127.0.0.1:41544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:50:25.248749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.229807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-05-10T00:50:25.248784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.443485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:50:25.248849Z","caller":"traceutil/trace.go:171","msg":"trace[1543258766] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:642; }","duration":"980.37313ms","start":"2026-05-10T00:50:24.268456Z","end":"2026-05-10T00:50:25.248829Z","steps":["trace[1543258766] 'agreement among raft nodes before linearized reading' (duration: 980.117564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:25.248911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:24.268442Z","time spent":"980.456802ms","remote":"127.0.0.1:41432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-10T00:50:25.24895Z","caller":"traceutil/trace.go:171","msg":"trace[1258906313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"191.65583ms","start":"2026-05-10T00:50:25.057259Z","end":"2026-05-10T00:50:25.248915Z","steps":["trace[1258906313] 'agreement among raft nodes before linearized reading' (duration: 191.21024ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:26.869259Z","caller":"traceutil/trace.go:171","msg":"trace[237828900] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"183.391209ms","start":"2026-05-10T00:50:26.685844Z","end":"2026-05-10T00:50:26.869235Z","steps":["trace[237828900] 'process raft request' (duration: 183.265086ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:39.647502Z","caller":"traceutil/trace.go:171","msg":"trace[1997885049] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"366.198246ms","start":"2026-05-10T00:50:39.281282Z","end":"2026-05-10T00:50:39.64748Z","steps":["trace[1997885049] 'process raft request' (duration: 365.575121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:39.647659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:39.281271Z","time spent":"366.326018ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:50:39.964076Z","caller":"traceutil/trace.go:171","msg":"trace[106347140] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"682.624599ms","start":"2026-05-10T00:50:39.281417Z","end":"2026-05-10T00:50:39.964041Z","steps":["trace[106347140] 'process raft request' (duration: 620.324138ms)","trace[106347140] 'compare' (duration: 62.041435ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:50:39.964211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:39.281393Z","time spent":"682.753742ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:50:39.968508Z","caller":"traceutil/trace.go:171","msg":"trace[1793057464] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:817; }","duration":"334.631004ms","start":"2026-05-10T00:50:39.633859Z","end":"2026-05-10T00:50:39.96849Z","steps":["trace[1793057464] 'read index received' (duration: 13.018851ms)","trace[1793057464] 'applied index is now lower than readState.Index' (duration: 321.611213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:50:39.968682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.812489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jz5pj\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-10T00:50:39.968733Z","caller":"traceutil/trace.go:171","msg":"trace[1831012225] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jz5pj; range_end:; response_count:1; response_revision:793; }","duration":"334.911412ms","start":"2026-05-10T00:50:39.633808Z","end":"2026-05-10T00:50:39.968719Z","steps":["trace[1831012225] 'agreement among raft nodes before linearized reading' (duration: 334.743818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:50:39.968762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.053022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-10T00:50:39.968798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:50:39.633765Z","time spent":"335.024904ms","remote":"127.0.0.1:41456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jz5pj\" "} {"level":"info","ts":"2026-05-10T00:50:39.968824Z","caller":"traceutil/trace.go:171","msg":"trace[165779862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"277.163945ms","start":"2026-05-10T00:50:39.691645Z","end":"2026-05-10T00:50:39.968808Z","steps":["trace[165779862] 'agreement among raft nodes before linearized reading' (duration: 277.013231ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:46.897095Z","caller":"traceutil/trace.go:171","msg":"trace[1535461997] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"108.841109ms","start":"2026-05-10T00:50:46.788229Z","end":"2026-05-10T00:50:46.89707Z","steps":["trace[1535461997] 'process raft request' (duration: 99.452512ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:47.346638Z","caller":"traceutil/trace.go:171","msg":"trace[1551727225] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"272.712892ms","start":"2026-05-10T00:50:47.073903Z","end":"2026-05-10T00:50:47.346615Z","steps":["trace[1551727225] 'process raft request' (duration: 272.660021ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:47.347074Z","caller":"traceutil/trace.go:171","msg":"trace[2088062944] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"274.068913ms","start":"2026-05-10T00:50:47.072994Z","end":"2026-05-10T00:50:47.347063Z","steps":["trace[2088062944] 'process raft request' (duration: 273.446369ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:47.349625Z","caller":"traceutil/trace.go:171","msg":"trace[133933127] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"225.268684ms","start":"2026-05-10T00:50:47.124228Z","end":"2026-05-10T00:50:47.349497Z","steps":["trace[133933127] 'read index received' (duration: 223.075343ms)","trace[133933127] 'applied index is now lower than readState.Index' (duration: 2.192291ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:50:47.350054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.758715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-h7rtg\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-05-10T00:50:47.350143Z","caller":"traceutil/trace.go:171","msg":"trace[854288557] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-h7rtg; range_end:; response_count:1; response_revision:873; }","duration":"225.933589ms","start":"2026-05-10T00:50:47.124198Z","end":"2026-05-10T00:50:47.350131Z","steps":["trace[854288557] 'agreement among raft nodes before linearized reading' (duration: 225.588881ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:47.350309Z","caller":"traceutil/trace.go:171","msg":"trace[2056362536] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"226.381389ms","start":"2026-05-10T00:50:47.123752Z","end":"2026-05-10T00:50:47.350134Z","steps":["trace[2056362536] 'process raft request' (duration: 225.607422ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:47.482246Z","caller":"traceutil/trace.go:171","msg":"trace[647535615] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"128.426262ms","start":"2026-05-10T00:50:47.353796Z","end":"2026-05-10T00:50:47.482222Z","steps":["trace[647535615] 'process raft request' (duration: 59.503217ms)","trace[647535615] 'compare' (duration: 68.513506ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:50:47.482248Z","caller":"traceutil/trace.go:171","msg":"trace[675390987] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"124.971873ms","start":"2026-05-10T00:50:47.357255Z","end":"2026-05-10T00:50:47.482227Z","steps":["trace[675390987] 'process raft request' (duration: 124.731657ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:47.621387Z","caller":"traceutil/trace.go:171","msg":"trace[1430041666] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"124.85834ms","start":"2026-05-10T00:50:47.496501Z","end":"2026-05-10T00:50:47.621359Z","steps":["trace[1430041666] 'process raft request' (duration: 124.586733ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:50:47.815519Z","caller":"traceutil/trace.go:171","msg":"trace[1226530030] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"132.932937ms","start":"2026-05-10T00:50:47.682563Z","end":"2026-05-10T00:50:47.815496Z","steps":["trace[1226530030] 'process raft request' (duration: 125.379782ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:10.339766Z","caller":"traceutil/trace.go:171","msg":"trace[1545688754] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"181.161323ms","start":"2026-05-10T00:51:10.158583Z","end":"2026-05-10T00:51:10.339744Z","steps":["trace[1545688754] 'process raft request' (duration: 181.03457ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:10.369555Z","caller":"traceutil/trace.go:171","msg":"trace[712463918] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"210.4103ms","start":"2026-05-10T00:51:10.159124Z","end":"2026-05-10T00:51:10.369534Z","steps":["trace[712463918] 'process raft request' (duration: 210.245136ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:15.027623Z","caller":"traceutil/trace.go:171","msg":"trace[1378126503] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"167.760092ms","start":"2026-05-10T00:51:14.85984Z","end":"2026-05-10T00:51:15.0276Z","steps":["trace[1378126503] 'process raft request' (duration: 167.602159ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:16.156121Z","caller":"traceutil/trace.go:171","msg":"trace[1973846838] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"154.25552ms","start":"2026-05-10T00:51:16.001834Z","end":"2026-05-10T00:51:16.15609Z","steps":["trace[1973846838] 'process raft request' (duration: 154.071325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:16.888959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297078930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:51:17.96976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81211506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:17.969822Z","caller":"traceutil/trace.go:171","msg":"trace[992102225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"1.812258992s","start":"2026-05-10T00:51:16.157548Z","end":"2026-05-10T00:51:17.969807Z","steps":["trace[992102225] 'range keys from in-memory index tree' (duration: 1.812010626s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:17.969852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:16.157526Z","time spent":"1.812319054s","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:51:17.969892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607666507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-10T00:51:17.969922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779563526s","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-10T00:51:17.969948Z","caller":"traceutil/trace.go:171","msg":"trace[1513252272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"1.60775802s","start":"2026-05-10T00:51:16.362177Z","end":"2026-05-10T00:51:17.969935Z","steps":["trace[1513252272] 'range keys from in-memory index tree' (duration: 1.607563025s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:17.969978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:16.362156Z","time spent":"1.607816361s","remote":"127.0.0.1:41588","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-10T00:51:17.969978Z","caller":"traceutil/trace.go:171","msg":"trace[686123218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.779666248s","start":"2026-05-10T00:51:16.190298Z","end":"2026-05-10T00:51:17.969964Z","steps":["trace[686123218] 'range keys from in-memory index tree' (duration: 1.779394872s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:17.970008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:16.190279Z","time spent":"1.77972279s","remote":"127.0.0.1:41588","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-10T00:51:17.970035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.130744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:51:17.970128Z","caller":"traceutil/trace.go:171","msg":"trace[85438533] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"1.582192708s","start":"2026-05-10T00:51:16.387919Z","end":"2026-05-10T00:51:17.970112Z","steps":["trace[85438533] 'read index received' (duration: 881.848389ms)","trace[85438533] 'applied index is now lower than readState.Index' (duration: 700.342909ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:17.970225Z","caller":"traceutil/trace.go:171","msg":"trace[1720878990] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.585084325s","start":"2026-05-10T00:51:16.385108Z","end":"2026-05-10T00:51:17.970193Z","steps":["trace[1720878990] 'process raft request' (duration: 884.705975ms)","trace[1720878990] 'compare' (duration: 699.716075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:17.970331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582422853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-10T00:51:17.970361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:16.38509Z","time spent":"1.585185607s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:51:17.970379Z","caller":"traceutil/trace.go:171","msg":"trace[1909386837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"1.582511636s","start":"2026-05-10T00:51:16.38784Z","end":"2026-05-10T00:51:17.970352Z","steps":["trace[1909386837] 'agreement among raft nodes before linearized reading' (duration: 1.582341542s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:17.970413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:16.387808Z","time spent":"1.582597678s","remote":"127.0.0.1:41588","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-10T00:51:18.470255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297078933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:51:18.697751Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.310945961s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-10T00:51:19.153388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.356818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:51:19.153507Z","caller":"traceutil/trace.go:171","msg":"trace[66023584] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.766789961s","start":"2026-05-10T00:51:17.386693Z","end":"2026-05-10T00:51:19.153483Z","steps":["trace[66023584] 'process raft request' (duration: 1.311244428s)","trace[66023584] 'compare' (duration: 454.842367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:19.153954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.386668Z","time spent":"1.766877803s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:51:19.167778Z","caller":"traceutil/trace.go:171","msg":"trace[1050103190] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1054; }","duration":"1.197603517s","start":"2026-05-10T00:51:17.970157Z","end":"2026-05-10T00:51:19.167761Z","steps":["trace[1050103190] 'read index received' (duration: 727.790874ms)","trace[1050103190] 'applied index is now lower than readState.Index' (duration: 469.811653ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:19.16781Z","caller":"traceutil/trace.go:171","msg":"trace[570616545] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.192382607s","start":"2026-05-10T00:51:17.9754Z","end":"2026-05-10T00:51:19.167783Z","steps":["trace[570616545] 'process raft request' (duration: 1.192332395s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:19.16784Z","caller":"traceutil/trace.go:171","msg":"trace[1215373557] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.193105113s","start":"2026-05-10T00:51:17.974705Z","end":"2026-05-10T00:51:19.16781Z","steps":["trace[1215373557] 'process raft request' (duration: 1.192901589s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.1679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.813630285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:51:19.167918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262858147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:19.167965Z","caller":"traceutil/trace.go:171","msg":"trace[1941582200] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1021; }","duration":"1.813747637s","start":"2026-05-10T00:51:17.354202Z","end":"2026-05-10T00:51:19.167949Z","steps":["trace[1941582200] 'agreement among raft nodes before linearized reading' (duration: 1.813609594s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:19.167963Z","caller":"traceutil/trace.go:171","msg":"trace[1880373763] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.193158484s","start":"2026-05-10T00:51:17.974781Z","end":"2026-05-10T00:51:19.16794Z","steps":["trace[1880373763] 'process raft request' (duration: 1.192925829s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.167968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.203373916s","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-10T00:51:19.167935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.136077338s","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-10T00:51:19.167976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.974685Z","time spent":"1.193226286s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:19.167998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.354182Z","time spent":"1.813808528s","remote":"127.0.0.1:41406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:51:19.16801Z","caller":"traceutil/trace.go:171","msg":"trace[137540199] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1021; }","duration":"2.203450237s","start":"2026-05-10T00:51:16.96455Z","end":"2026-05-10T00:51:19.168Z","steps":["trace[137540199] 'agreement among raft nodes before linearized reading' (duration: 2.203360135s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.168024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.974775Z","time spent":"1.193220406s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:19.168046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:16.964533Z","time spent":"2.203502278s","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:51:19.167975Z","caller":"traceutil/trace.go:171","msg":"trace[831153539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"1.262929879s","start":"2026-05-10T00:51:17.905031Z","end":"2026-05-10T00:51:19.167961Z","steps":["trace[831153539] 'agreement among raft nodes before linearized reading' (duration: 1.262809396s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:19.168012Z","caller":"traceutil/trace.go:171","msg":"trace[1786445176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"2.136202531s","start":"2026-05-10T00:51:17.0318Z","end":"2026-05-10T00:51:19.168003Z","steps":["trace[1786445176] 'agreement among raft nodes before linearized reading' (duration: 2.136047607s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.168095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.904987Z","time spent":"1.263099373s","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:51:19.168103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.031778Z","time spent":"2.136314503s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:19.16796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.975389Z","time spent":"1.192470708s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:19.355591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.839302ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-10T00:51:19.355779Z","caller":"traceutil/trace.go:171","msg":"trace[61309514] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"187.929549ms","start":"2026-05-10T00:51:19.167832Z","end":"2026-05-10T00:51:19.355761Z","steps":["trace[61309514] 'read index received' (duration: 23.943674ms)","trace[61309514] 'applied index is now lower than readState.Index' (duration: 163.983925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:19.355814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.304754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:51:19.355831Z","caller":"traceutil/trace.go:171","msg":"trace[1977862197] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"184.512681ms","start":"2026-05-10T00:51:19.171302Z","end":"2026-05-10T00:51:19.355814Z","steps":["trace[1977862197] 'process raft request' (duration: 184.391368ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:19.355855Z","caller":"traceutil/trace.go:171","msg":"trace[624750671] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1021; }","duration":"772.380756ms","start":"2026-05-10T00:51:18.583461Z","end":"2026-05-10T00:51:19.355841Z","steps":["trace[624750671] 'agreement among raft nodes before linearized reading' (duration: 772.307314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.355885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:18.583442Z","time spent":"772.433367ms","remote":"127.0.0.1:41822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:19.355963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195699743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:51:19.35599Z","caller":"traceutil/trace.go:171","msg":"trace[619268381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1022; }","duration":"1.195772324s","start":"2026-05-10T00:51:18.16021Z","end":"2026-05-10T00:51:19.355982Z","steps":["trace[619268381] 'agreement among raft nodes before linearized reading' (duration: 1.195642211s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.356014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:18.160191Z","time spent":"1.195817396s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:19.356107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379868925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:19.356126Z","caller":"traceutil/trace.go:171","msg":"trace[1076380759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"1.379914427s","start":"2026-05-10T00:51:17.976206Z","end":"2026-05-10T00:51:19.35612Z","steps":["trace[1076380759] 'agreement among raft nodes before linearized reading' (duration: 1.379882406s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.356152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:17.97619Z","time spent":"1.379958367s","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:51:19.356423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.504382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:51:19.356475Z","caller":"traceutil/trace.go:171","msg":"trace[540744487] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1022; }","duration":"668.604834ms","start":"2026-05-10T00:51:18.687858Z","end":"2026-05-10T00:51:19.356462Z","steps":["trace[540744487] 'agreement among raft nodes before linearized reading' (duration: 668.489941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:19.356507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:18.687834Z","time spent":"668.666175ms","remote":"127.0.0.1:41758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:51:31.351665Z","caller":"traceutil/trace.go:171","msg":"trace[752842887] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"108.672195ms","start":"2026-05-10T00:51:31.242966Z","end":"2026-05-10T00:51:31.351638Z","steps":["trace[752842887] 'process raft request' (duration: 59.745933ms)","trace[752842887] 'compare' (duration: 48.818109ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:34.432513Z","caller":"traceutil/trace.go:171","msg":"trace[1419757102] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"297.411923ms","start":"2026-05-10T00:51:34.135064Z","end":"2026-05-10T00:51:34.432476Z","steps":["trace[1419757102] 'process raft request' (duration: 297.175177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:39.776637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297079106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:51:40.277389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297079106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:51:40.750787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.48351951s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T00:51:40.75105Z","caller":"traceutil/trace.go:171","msg":"trace[896012012] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"1.483868628s","start":"2026-05-10T00:51:39.267161Z","end":"2026-05-10T00:51:40.75103Z","steps":["trace[896012012] 'process raft request' (duration: 1.483742405s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:40.751152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:39.26714Z","time spent":"1.483950131s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:41.091244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.306046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:51:41.091417Z","caller":"traceutil/trace.go:171","msg":"trace[1260927968] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"1.815156734s","start":"2026-05-10T00:51:39.276245Z","end":"2026-05-10T00:51:41.091402Z","steps":["trace[1260927968] 'read index received' (duration: 1.474857667s)","trace[1260927968] 'applied index is now lower than readState.Index' (duration: 340.298177ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:41.091439Z","caller":"traceutil/trace.go:171","msg":"trace[1669826244] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"643.531805ms","start":"2026-05-10T00:51:40.447885Z","end":"2026-05-10T00:51:41.091417Z","steps":["trace[1669826244] 'process raft request' (duration: 643.441093ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:41.091443Z","caller":"traceutil/trace.go:171","msg":"trace[1731763373] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.662569272s","start":"2026-05-10T00:51:39.428839Z","end":"2026-05-10T00:51:41.091408Z","steps":["trace[1731763373] 'process raft request' (duration: 1.41901935s)","trace[1731763373] 'compare' (duration: 243.03105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:41.091533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.679431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:51:41.091567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700155722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-10T00:51:41.091574Z","caller":"traceutil/trace.go:171","msg":"trace[1754779480] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1086; }","duration":"462.755453ms","start":"2026-05-10T00:51:40.628808Z","end":"2026-05-10T00:51:41.091563Z","steps":["trace[1754779480] 'agreement among raft nodes before linearized reading' (duration: 462.688702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:41.091569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:40.447864Z","time spent":"643.630448ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:41.091602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:40.628786Z","time spent":"462.809054ms","remote":"127.0.0.1:41648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:41.091644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515681873s","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-10T00:51:41.091646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023688235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:51:41.091668Z","caller":"traceutil/trace.go:171","msg":"trace[970042981] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"1.515752545s","start":"2026-05-10T00:51:39.57591Z","end":"2026-05-10T00:51:41.091663Z","steps":["trace[970042981] 'agreement among raft nodes before linearized reading' (duration: 1.515692943s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:41.091693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:39.575885Z","time spent":"1.515804046s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:41.091697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505534445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:51:41.091591Z","caller":"traceutil/trace.go:171","msg":"trace[634495989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"1.700220753s","start":"2026-05-10T00:51:39.391363Z","end":"2026-05-10T00:51:41.091583Z","steps":["trace[634495989] 'agreement among raft nodes before linearized reading' (duration: 1.700124331s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:41.09174Z","caller":"traceutil/trace.go:171","msg":"trace[2018279042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1086; }","duration":"1.505613427s","start":"2026-05-10T00:51:39.586114Z","end":"2026-05-10T00:51:41.091728Z","steps":["trace[2018279042] 'agreement among raft nodes before linearized reading' (duration: 1.505452194s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:41.09177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:39.586094Z","time spent":"1.505669179s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:41.091813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.531828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:51:41.091783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815487542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-10T00:51:41.09185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.438853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:41.091875Z","caller":"traceutil/trace.go:171","msg":"trace[1163479737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1086; }","duration":"1.815659446s","start":"2026-05-10T00:51:39.276194Z","end":"2026-05-10T00:51:41.091854Z","steps":["trace[1163479737] 'agreement among raft nodes before linearized reading' (duration: 1.815287618s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:41.091774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:39.391341Z","time spent":"1.700421578s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T00:51:41.091894Z","caller":"traceutil/trace.go:171","msg":"trace[2110767209] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1086; }","duration":"933.520585ms","start":"2026-05-10T00:51:40.15836Z","end":"2026-05-10T00:51:41.09188Z","steps":["trace[2110767209] 'agreement among raft nodes before linearized reading' (duration: 933.452083ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:41.091895Z","caller":"traceutil/trace.go:171","msg":"trace[1398730879] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1086; }","duration":"635.6231ms","start":"2026-05-10T00:51:40.456221Z","end":"2026-05-10T00:51:41.091844Z","steps":["trace[1398730879] 'agreement among raft nodes before linearized reading' (duration: 635.534758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:41.091914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:39.276174Z","time spent":"1.815731607s","remote":"127.0.0.1:41588","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-10T00:51:41.091732Z","caller":"traceutil/trace.go:171","msg":"trace[366785900] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1086; }","duration":"1.023848859s","start":"2026-05-10T00:51:40.067867Z","end":"2026-05-10T00:51:41.091716Z","steps":["trace[366785900] 'agreement among raft nodes before linearized reading' (duration: 1.023682965s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:41.091933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:40.158339Z","time spent":"933.584396ms","remote":"127.0.0.1:41622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:41.091962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:40.067841Z","time spent":"1.024113386s","remote":"127.0.0.1:41788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:41.092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:40.456205Z","time spent":"635.780234ms","remote":"127.0.0.1:58882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:41.092096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.620952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:51:41.092125Z","caller":"traceutil/trace.go:171","msg":"trace[1713394299] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1086; }","duration":"269.685133ms","start":"2026-05-10T00:51:40.822429Z","end":"2026-05-10T00:51:41.092114Z","steps":["trace[1713394299] 'agreement among raft nodes before linearized reading' (duration: 269.637072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:41.092274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:39.428808Z","time spent":"1.662698545s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:51:41.260778Z","caller":"traceutil/trace.go:171","msg":"trace[1264034057] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"163.710093ms","start":"2026-05-10T00:51:41.097049Z","end":"2026-05-10T00:51:41.260759Z","steps":["trace[1264034057] 'process raft request' (duration: 163.646011ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:41.260806Z","caller":"traceutil/trace.go:171","msg":"trace[28734220] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"163.768784ms","start":"2026-05-10T00:51:41.097018Z","end":"2026-05-10T00:51:41.260787Z","steps":["trace[28734220] 'process raft request' (duration: 119.848576ms)","trace[28734220] 'compare' (duration: 43.729544ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:41.260931Z","caller":"traceutil/trace.go:171","msg":"trace[1440960695] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"163.642681ms","start":"2026-05-10T00:51:41.097267Z","end":"2026-05-10T00:51:41.26091Z","steps":["trace[1440960695] 'process raft request' (duration: 163.462137ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:42.948948Z","caller":"traceutil/trace.go:171","msg":"trace[2041123869] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"188.912682ms","start":"2026-05-10T00:51:42.759812Z","end":"2026-05-10T00:51:42.948724Z","steps":["trace[2041123869] 'process raft request' (duration: 188.785789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:43.586384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.291721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:43.58646Z","caller":"traceutil/trace.go:171","msg":"trace[1240702543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"529.444485ms","start":"2026-05-10T00:51:43.056999Z","end":"2026-05-10T00:51:43.586443Z","steps":["trace[1240702543] 'range keys from in-memory index tree' (duration: 529.20799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:43.586525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.056978Z","time spent":"529.534927ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:51:43.587073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.406987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:51:43.587142Z","caller":"traceutil/trace.go:171","msg":"trace[2134325242] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"438.074096ms","start":"2026-05-10T00:51:43.149056Z","end":"2026-05-10T00:51:43.58713Z","steps":["trace[2134325242] 'read index received' (duration: 209.519895ms)","trace[2134325242] 'applied index is now lower than readState.Index' (duration: 228.552971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:43.58739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.356839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:43.587429Z","caller":"traceutil/trace.go:171","msg":"trace[1950981747] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1092; }","duration":"217.42983ms","start":"2026-05-10T00:51:43.369989Z","end":"2026-05-10T00:51:43.587419Z","steps":["trace[1950981747] 'agreement among raft nodes before linearized reading' (duration: 217.368188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:43.587452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.769146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:51:43.58748Z","caller":"traceutil/trace.go:171","msg":"trace[913215964] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"491.030925ms","start":"2026-05-10T00:51:43.096419Z","end":"2026-05-10T00:51:43.58745Z","steps":["trace[913215964] 'process raft request' (duration: 262.180778ms)","trace[913215964] 'compare' (duration: 227.822563ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:43.587528Z","caller":"traceutil/trace.go:171","msg":"trace[1862417517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"322.889269ms","start":"2026-05-10T00:51:43.264622Z","end":"2026-05-10T00:51:43.587511Z","steps":["trace[1862417517] 'agreement among raft nodes before linearized reading' (duration: 322.682164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:43.587574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.264604Z","time spent":"322.961201ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:43.587624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.0964Z","time spent":"491.143769ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:43.587617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.544057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-10T00:51:43.587697Z","caller":"traceutil/trace.go:171","msg":"trace[683009736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"438.66687ms","start":"2026-05-10T00:51:43.14901Z","end":"2026-05-10T00:51:43.587677Z","steps":["trace[683009736] 'agreement among raft nodes before linearized reading' (duration: 438.444475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:43.587815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.148993Z","time spent":"438.809733ms","remote":"127.0.0.1:41588","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-10T00:51:43.587827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.076914ms","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-10T00:51:43.587871Z","caller":"traceutil/trace.go:171","msg":"trace[922811024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"323.144505ms","start":"2026-05-10T00:51:43.264714Z","end":"2026-05-10T00:51:43.587859Z","steps":["trace[922811024] 'agreement among raft nodes before linearized reading' (duration: 322.906979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:43.588012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.264706Z","time spent":"323.221177ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:43.588206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.801957ms","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-10T00:51:43.588314Z","caller":"traceutil/trace.go:171","msg":"trace[81685716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"322.93741ms","start":"2026-05-10T00:51:43.265359Z","end":"2026-05-10T00:51:43.588297Z","steps":["trace[81685716] 'agreement among raft nodes before linearized reading' (duration: 322.279115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:43.588436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.265352Z","time spent":"323.069623ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:44.264438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297079124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:51:44.765452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297079124,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:51:44.97325Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381350159s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-10T00:51:47.056993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000058132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-10T00:51:47.057069Z","caller":"traceutil/trace.go:171","msg":"trace[819314458] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000179194s","start":"2026-05-10T00:51:45.056873Z","end":"2026-05-10T00:51:47.057052Z","steps":["trace[819314458] 'agreement among raft nodes before linearized reading' (duration: 2.000084742s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:47.057115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:45.056856Z","time spent":"2.000249656s","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/10 00:51:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-10T00:51:47.616249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.429664689s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:47.616378Z","caller":"traceutil/trace.go:171","msg":"trace[2011937533] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1092; }","duration":"3.429803043s","start":"2026-05-10T00:51:44.186559Z","end":"2026-05-10T00:51:47.616362Z","steps":["trace[2011937533] 'range keys from in-memory index tree' (duration: 3.429649399s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:47.618522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.64499248s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:51:47.61863Z","caller":"traceutil/trace.go:171","msg":"trace[1258332232] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"4.026776448s","start":"2026-05-10T00:51:43.591833Z","end":"2026-05-10T00:51:47.618609Z","steps":["trace[1258332232] 'process raft request' (duration: 1.381609264s)","trace[1258332232] 'compare' (duration: 2.644585811s)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:47.618686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.591815Z","time spent":"4.026845941s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:47.993593Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.020155603s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T00:51:48.014983Z","caller":"traceutil/trace.go:171","msg":"trace[1410617364] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"4.421242203s","start":"2026-05-10T00:51:43.593714Z","end":"2026-05-10T00:51:48.014956Z","steps":["trace[1410617364] 'process raft request' (duration: 4.420908675s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:48.014979Z","caller":"traceutil/trace.go:171","msg":"trace[1287528391] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"4.422429351s","start":"2026-05-10T00:51:43.592475Z","end":"2026-05-10T00:51:48.014905Z","steps":["trace[1287528391] 'process raft request' (duration: 4.422058872s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:48.015094Z","caller":"traceutil/trace.go:171","msg":"trace[1493960299] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1134; }","duration":"4.250453505s","start":"2026-05-10T00:51:43.764248Z","end":"2026-05-10T00:51:48.014702Z","steps":["trace[1493960299] 'read index received' (duration: 1.209204319s)","trace[1493960299] 'applied index is now lower than readState.Index' (duration: 3.041248206s)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:48.015204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.592455Z","time spent":"4.422633566s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:48.015314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.251044119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-10T00:51:48.015783Z","caller":"traceutil/trace.go:171","msg":"trace[2046994103] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1097; }","duration":"4.251556591s","start":"2026-05-10T00:51:43.764208Z","end":"2026-05-10T00:51:48.015764Z","steps":["trace[2046994103] 'agreement among raft nodes before linearized reading' (duration: 4.250982228s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.015847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.764184Z","time spent":"4.251645963s","remote":"127.0.0.1:41300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"info","ts":"2026-05-10T00:51:48.015331Z","caller":"traceutil/trace.go:171","msg":"trace[677250836] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"4.422978644s","start":"2026-05-10T00:51:43.592326Z","end":"2026-05-10T00:51:48.015305Z","steps":["trace[677250836] 'process raft request' (duration: 4.401934112s)","trace[677250836] 'compare' (duration: 19.68086ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:48.015405Z","caller":"traceutil/trace.go:171","msg":"trace[37739060] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"4.158328809s","start":"2026-05-10T00:51:43.856771Z","end":"2026-05-10T00:51:48.0151Z","steps":["trace[37739060] 'process raft request' (duration: 4.157890358s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.016084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.592296Z","time spent":"4.42369253s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:48.015424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.593665Z","time spent":"4.421396007s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:48.016198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:43.856744Z","time spent":"4.159388223s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:48.085197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.491008245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:51:48.085217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.162511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:51:48.085257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.823629543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:48.085295Z","caller":"traceutil/trace.go:171","msg":"trace[673819332] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1098; }","duration":"1.823701375s","start":"2026-05-10T00:51:46.261584Z","end":"2026-05-10T00:51:48.085286Z","steps":["trace[673819332] 'agreement among raft nodes before linearized reading' (duration: 1.823649073s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.0853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.433638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:48.085285Z","caller":"traceutil/trace.go:171","msg":"trace[526792065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"178.285264ms","start":"2026-05-10T00:51:47.906983Z","end":"2026-05-10T00:51:48.085268Z","steps":["trace[526792065] 'agreement among raft nodes before linearized reading' (duration: 178.148111ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:48.08532Z","caller":"traceutil/trace.go:171","msg":"trace[1554829497] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1098; }","duration":"706.50603ms","start":"2026-05-10T00:51:47.37881Z","end":"2026-05-10T00:51:48.085316Z","steps":["trace[1554829497] 'agreement among raft nodes before linearized reading' (duration: 706.47395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.085325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:46.261561Z","time spent":"1.823757455s","remote":"127.0.0.1:58902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:48.085347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:47.378793Z","time spent":"706.548921ms","remote":"127.0.0.1:41612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:51:48.085264Z","caller":"traceutil/trace.go:171","msg":"trace[1753046265] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1098; }","duration":"1.491079557s","start":"2026-05-10T00:51:46.594169Z","end":"2026-05-10T00:51:48.085249Z","steps":["trace[1753046265] 'agreement among raft nodes before linearized reading' (duration: 1.490901193s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.085641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.132575785s","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-10T00:51:48.085737Z","caller":"traceutil/trace.go:171","msg":"trace[929826743] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"3.132701767s","start":"2026-05-10T00:51:44.953027Z","end":"2026-05-10T00:51:48.085729Z","steps":["trace[929826743] 'agreement among raft nodes before linearized reading' (duration: 3.132525993s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.08578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:44.953008Z","time spent":"3.132763789s","remote":"127.0.0.1:41588","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-10T00:51:48.085409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.2925452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:51:48.08583Z","caller":"traceutil/trace.go:171","msg":"trace[2026904352] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1098; }","duration":"2.293013241s","start":"2026-05-10T00:51:45.792806Z","end":"2026-05-10T00:51:48.08582Z","steps":["trace[2026904352] 'agreement among raft nodes before linearized reading' (duration: 2.29255636s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.08587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:45.792787Z","time spent":"2.293071683s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:48.085646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:46.59411Z","time spent":"1.491517278s","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:48.085197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.373842694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:48.086079Z","caller":"traceutil/trace.go:171","msg":"trace[1553109929] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1098; }","duration":"2.374805726s","start":"2026-05-10T00:51:45.711264Z","end":"2026-05-10T00:51:48.086069Z","steps":["trace[1553109929] 'agreement among raft nodes before linearized reading' (duration: 2.373830863s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.086298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:45.711244Z","time spent":"2.375045682s","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:48.085433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028037747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:48.086388Z","caller":"traceutil/trace.go:171","msg":"trace[2006947768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"1.029057161s","start":"2026-05-10T00:51:47.057319Z","end":"2026-05-10T00:51:48.086376Z","steps":["trace[2006947768] 'agreement among raft nodes before linearized reading' (duration: 1.028017257s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.086442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:47.0573Z","time spent":"1.029129322s","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:51:48.085501Z","caller":"traceutil/trace.go:171","msg":"trace[1527452440] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"2.493595437s","start":"2026-05-10T00:51:45.591883Z","end":"2026-05-10T00:51:48.085479Z","steps":["trace[1527452440] 'process raft request' (duration: 2.492931182s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.0853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.865194886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-10T00:51:48.08655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:45.591859Z","time spent":"2.494638391s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:48.085538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.034914328s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:48.086607Z","caller":"traceutil/trace.go:171","msg":"trace[69887856] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1098; }","duration":"3.036019404s","start":"2026-05-10T00:51:45.050579Z","end":"2026-05-10T00:51:48.086598Z","steps":["trace[69887856] 'agreement among raft nodes before linearized reading' (duration: 3.034932279s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.086636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:45.050558Z","time spent":"3.036067865s","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:51:48.085548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.739690869s","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-10T00:51:48.086723Z","caller":"traceutil/trace.go:171","msg":"trace[138063031] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1098; }","duration":"2.740904376s","start":"2026-05-10T00:51:45.345811Z","end":"2026-05-10T00:51:48.086716Z","steps":["trace[138063031] 'agreement among raft nodes before linearized reading' (duration: 2.739708268s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.086749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:45.345792Z","time spent":"2.740948648s","remote":"127.0.0.1:41682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:51:48.086575Z","caller":"traceutil/trace.go:171","msg":"trace[1800720703] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1098; }","duration":"3.866540827s","start":"2026-05-10T00:51:44.220013Z","end":"2026-05-10T00:51:48.086554Z","steps":["trace[1800720703] 'agreement among raft nodes before linearized reading' (duration: 3.865015612s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:48.087245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:44.219995Z","time spent":"3.867240823s","remote":"127.0.0.1:41668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:51:48.349327Z","caller":"traceutil/trace.go:171","msg":"trace[601540000] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"166.947658ms","start":"2026-05-10T00:51:48.182356Z","end":"2026-05-10T00:51:48.349303Z","steps":["trace[601540000] 'read index received' (duration: 106.183356ms)","trace[601540000] 'applied index is now lower than readState.Index' (duration: 60.763382ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:48.34946Z","caller":"traceutil/trace.go:171","msg":"trace[1466757313] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"168.919034ms","start":"2026-05-10T00:51:48.180508Z","end":"2026-05-10T00:51:48.349427Z","steps":["trace[1466757313] 'process raft request' (duration: 108.111311ms)","trace[1466757313] 'compare' (duration: 60.571758ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:48.349533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.157083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-10T00:51:48.349559Z","caller":"traceutil/trace.go:171","msg":"trace[1449010465] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1101; }","duration":"167.256375ms","start":"2026-05-10T00:51:48.182295Z","end":"2026-05-10T00:51:48.349552Z","steps":["trace[1449010465] 'agreement among raft nodes before linearized reading' (duration: 167.136272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:49.393999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.15486ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-10T00:51:49.394124Z","caller":"traceutil/trace.go:171","msg":"trace[379634990] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"337.02784ms","start":"2026-05-10T00:51:49.057057Z","end":"2026-05-10T00:51:49.394085Z","steps":["trace[379634990] 'read index received' (duration: 120.761397ms)","trace[379634990] 'applied index is now lower than readState.Index' (duration: 216.264812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:49.39429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.229985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:49.39439Z","caller":"traceutil/trace.go:171","msg":"trace[2015103687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"337.380658ms","start":"2026-05-10T00:51:49.056993Z","end":"2026-05-10T00:51:49.394373Z","steps":["trace[2015103687] 'agreement among raft nodes before linearized reading' (duration: 337.199234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:49.394622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:49.05697Z","time spent":"337.539811ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:51:49.979512Z","caller":"traceutil/trace.go:171","msg":"trace[541904278] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"180.848904ms","start":"2026-05-10T00:51:49.798638Z","end":"2026-05-10T00:51:49.979487Z","steps":["trace[541904278] 'read index received' (duration: 180.635779ms)","trace[541904278] 'applied index is now lower than readState.Index' (duration: 211.585µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:49.979627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.973447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:51:49.97966Z","caller":"traceutil/trace.go:171","msg":"trace[919966735] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1102; }","duration":"181.060289ms","start":"2026-05-10T00:51:49.798591Z","end":"2026-05-10T00:51:49.979651Z","steps":["trace[919966735] 'agreement among raft nodes before linearized reading' (duration: 180.984287ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:49.979607Z","caller":"traceutil/trace.go:171","msg":"trace[63232015] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"352.648235ms","start":"2026-05-10T00:51:49.62693Z","end":"2026-05-10T00:51:49.979579Z","steps":["trace[63232015] 'process raft request' (duration: 352.345388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:49.979855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:49.626907Z","time spent":"352.83313ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:51:50.683111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297079154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:51:50.948938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.119371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-10T00:51:50.948994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.065139ms","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-10T00:51:50.949012Z","caller":"traceutil/trace.go:171","msg":"trace[852056232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"929.242523ms","start":"2026-05-10T00:51:50.01975Z","end":"2026-05-10T00:51:50.948993Z","steps":["trace[852056232] 'range keys from in-memory index tree' (duration: 928.968157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:50.949111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:50.019732Z","time spent":"929.368137ms","remote":"127.0.0.1:41588","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-10T00:51:50.949179Z","caller":"traceutil/trace.go:171","msg":"trace[1762168557] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"766.134146ms","start":"2026-05-10T00:51:50.183035Z","end":"2026-05-10T00:51:50.949169Z","steps":["trace[1762168557] 'read index received' (duration: 711.974408ms)","trace[1762168557] 'applied index is now lower than readState.Index' (duration: 54.159038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:50.949258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.224118ms","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-10T00:51:50.94928Z","caller":"traceutil/trace.go:171","msg":"trace[1366696197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"766.276278ms","start":"2026-05-10T00:51:50.182998Z","end":"2026-05-10T00:51:50.949274Z","steps":["trace[1366696197] 'agreement among raft nodes before linearized reading' (duration: 766.213297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:50.949302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:50.182972Z","time spent":"766.32513ms","remote":"127.0.0.1:41588","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-10T00:51:50.949384Z","caller":"traceutil/trace.go:171","msg":"trace[1499545670] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"857.252898ms","start":"2026-05-10T00:51:50.092122Z","end":"2026-05-10T00:51:50.949375Z","steps":["trace[1499545670] 'process raft request' (duration: 802.898196ms)","trace[1499545670] 'compare' (duration: 53.705067ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:51:50.949442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:50.092095Z","time spent":"857.3084ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:51:50.949058Z","caller":"traceutil/trace.go:171","msg":"trace[1137687380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"929.147622ms","start":"2026-05-10T00:51:50.019894Z","end":"2026-05-10T00:51:50.949041Z","steps":["trace[1137687380] 'range keys from in-memory index tree' (duration: 928.936717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:50.949631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:50.019883Z","time spent":"929.702464ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:51:50.949643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.836345ms","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-10T00:51:50.949673Z","caller":"traceutil/trace.go:171","msg":"trace[571199693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"929.96627ms","start":"2026-05-10T00:51:50.0197Z","end":"2026-05-10T00:51:50.949666Z","steps":["trace[571199693] 'range keys from in-memory index tree' (duration: 928.703501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:51:50.949763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:51:50.019679Z","time spent":"930.007712ms","remote":"127.0.0.1:41588","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-10T00:51:51.098472Z","caller":"traceutil/trace.go:171","msg":"trace[1385347217] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"143.982251ms","start":"2026-05-10T00:51:50.954478Z","end":"2026-05-10T00:51:51.098461Z","steps":["trace[1385347217] 'process raft request' (duration: 143.891808ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:51.098498Z","caller":"traceutil/trace.go:171","msg":"trace[789654390] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"144.050452ms","start":"2026-05-10T00:51:50.954396Z","end":"2026-05-10T00:51:51.098453Z","steps":["trace[789654390] 'process raft request' (duration: 143.917749ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:51.098895Z","caller":"traceutil/trace.go:171","msg":"trace[509376306] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"144.074633ms","start":"2026-05-10T00:51:50.954797Z","end":"2026-05-10T00:51:51.098872Z","steps":["trace[509376306] 'process raft request' (duration: 143.634283ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:51.098241Z","caller":"traceutil/trace.go:171","msg":"trace[333919238] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"144.442741ms","start":"2026-05-10T00:51:50.953756Z","end":"2026-05-10T00:51:51.098199Z","steps":["trace[333919238] 'process raft request' (duration: 95.138997ms)","trace[333919238] 'compare' (duration: 49.204712ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:51:53.912833Z","caller":"traceutil/trace.go:171","msg":"trace[520818313] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"109.898783ms","start":"2026-05-10T00:51:53.802914Z","end":"2026-05-10T00:51:53.912813Z","steps":["trace[520818313] 'process raft request' (duration: 105.905379ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:51:54.13413Z","caller":"traceutil/trace.go:171","msg":"trace[1306349729] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"139.993197ms","start":"2026-05-10T00:51:53.994107Z","end":"2026-05-10T00:51:54.1341Z","steps":["trace[1306349729] 'process raft request' (duration: 74.975415ms)","trace[1306349729] 'compare' (duration: 64.798997ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:03.169455Z","caller":"traceutil/trace.go:171","msg":"trace[112786515] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"160.120068ms","start":"2026-05-10T00:52:03.00931Z","end":"2026-05-10T00:52:03.16943Z","steps":["trace[112786515] 'read index received' (duration: 160.036306ms)","trace[112786515] 'applied index is now lower than readState.Index' (duration: 83.092µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:03.169659Z","caller":"traceutil/trace.go:171","msg":"trace[65725904] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"175.788745ms","start":"2026-05-10T00:52:02.993864Z","end":"2026-05-10T00:52:03.169653Z","steps":["trace[65725904] 'process raft request' (duration: 175.455137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:03.169784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.461355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-10T00:52:03.169804Z","caller":"traceutil/trace.go:171","msg":"trace[1783855960] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1195; }","duration":"160.524997ms","start":"2026-05-10T00:52:03.009274Z","end":"2026-05-10T00:52:03.169799Z","steps":["trace[1783855960] 'agreement among raft nodes before linearized reading' (duration: 160.475206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:03.169878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.003439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:03.169892Z","caller":"traceutil/trace.go:171","msg":"trace[1305393468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"114.04462ms","start":"2026-05-10T00:52:03.055844Z","end":"2026-05-10T00:52:03.169889Z","steps":["trace[1305393468] 'agreement among raft nodes before linearized reading' (duration: 114.0223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:05.557046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297079358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:52:05.723173Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.54204047s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T00:52:05.723522Z","caller":"traceutil/trace.go:171","msg":"trace[1398071232] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"1.54244705s","start":"2026-05-10T00:52:04.181049Z","end":"2026-05-10T00:52:05.723496Z","steps":["trace[1398071232] 'process raft request' (duration: 1.542288396s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:05.723666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:04.181034Z","time spent":"1.542561852s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:06.907079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155712485s","expected-duration":"1s"} {"level":"info","ts":"2026-05-10T00:52:06.96923Z","caller":"traceutil/trace.go:171","msg":"trace[2086825368] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1249; }","duration":"1.913202587s","start":"2026-05-10T00:52:05.056007Z","end":"2026-05-10T00:52:06.96921Z","steps":["trace[2086825368] 'read index received' (duration: 667.372623ms)","trace[2086825368] 'applied index is now lower than readState.Index' (duration: 1.245829184s)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:06.969267Z","caller":"traceutil/trace.go:171","msg":"trace[610066953] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"1.795592925s","start":"2026-05-10T00:52:05.173641Z","end":"2026-05-10T00:52:06.969234Z","steps":["trace[610066953] 'process raft request' (duration: 1.733581253s)","trace[610066953] 'compare' (duration: 61.831828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:06.969376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913359632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:52:06.969414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.788530889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-10T00:52:06.969407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.173616Z","time spent":"1.795710407s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:06.969443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213066628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:06.969458Z","caller":"traceutil/trace.go:171","msg":"trace[216761150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"1.913460843s","start":"2026-05-10T00:52:05.05596Z","end":"2026-05-10T00:52:06.969421Z","steps":["trace[216761150] 'agreement among raft nodes before linearized reading' (duration: 1.91331362s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:06.969477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789516803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-10T00:52:06.96949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.787947626s","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-10T00:52:06.969502Z","caller":"traceutil/trace.go:171","msg":"trace[1659151473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"1.789539203s","start":"2026-05-10T00:52:05.179955Z","end":"2026-05-10T00:52:06.969494Z","steps":["trace[1659151473] 'agreement among raft nodes before linearized reading' (duration: 1.789460691s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:06.969506Z","caller":"traceutil/trace.go:171","msg":"trace[1379387558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"1.787978586s","start":"2026-05-10T00:52:05.181523Z","end":"2026-05-10T00:52:06.969502Z","steps":["trace[1379387558] 'agreement among raft nodes before linearized reading' (duration: 1.787933975s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:06.969504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.055939Z","time spent":"1.913554556s","remote":"127.0.0.1:41274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:52:06.969525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.181517Z","time spent":"1.788003577s","remote":"127.0.0.1:41588","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-10T00:52:06.969382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725718329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:06.96955Z","caller":"traceutil/trace.go:171","msg":"trace[936570976] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1202; }","duration":"1.725906393s","start":"2026-05-10T00:52:05.243636Z","end":"2026-05-10T00:52:06.969542Z","steps":["trace[936570976] 'agreement among raft nodes before linearized reading' (duration: 1.725682418s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:06.969571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.243615Z","time spent":"1.725947044s","remote":"127.0.0.1:58938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-10T00:52:06.969444Z","caller":"traceutil/trace.go:171","msg":"trace[1319278341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"1.788578591s","start":"2026-05-10T00:52:05.180855Z","end":"2026-05-10T00:52:06.969434Z","steps":["trace[1319278341] 'agreement among raft nodes before linearized reading' (duration: 1.788505079s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:06.969592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.180846Z","time spent":"1.788742554s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:52:06.969525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.179909Z","time spent":"1.789611145s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:52:06.9694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792200755s","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-10T00:52:06.969679Z","caller":"traceutil/trace.go:171","msg":"trace[2036529627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"1.792518103s","start":"2026-05-10T00:52:05.177144Z","end":"2026-05-10T00:52:06.969662Z","steps":["trace[2036529627] 'agreement among raft nodes before linearized reading' (duration: 1.792163135s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:06.969478Z","caller":"traceutil/trace.go:171","msg":"trace[502153474] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1202; }","duration":"1.213118268s","start":"2026-05-10T00:52:05.756344Z","end":"2026-05-10T00:52:06.969462Z","steps":["trace[502153474] 'agreement among raft nodes before linearized reading' (duration: 1.213083298s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:06.96972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.177127Z","time spent":"1.792579934s","remote":"127.0.0.1:41588","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-10T00:52:06.969723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:05.756327Z","time spent":"1.213387085s","remote":"127.0.0.1:41504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:52:21.558217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297079491,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-10T00:52:21.802734Z","caller":"traceutil/trace.go:171","msg":"trace[517142545] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"779.706314ms","start":"2026-05-10T00:52:21.023007Z","end":"2026-05-10T00:52:21.802714Z","steps":["trace[517142545] 'process raft request' (duration: 779.586081ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:21.802787Z","caller":"traceutil/trace.go:171","msg":"trace[1505226290] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1308; }","duration":"745.173325ms","start":"2026-05-10T00:52:21.057586Z","end":"2026-05-10T00:52:21.802759Z","steps":["trace[1505226290] 'read index received' (duration: 745.162664ms)","trace[1505226290] 'applied index is now lower than readState.Index' (duration: 9.081µs)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:21.802965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.35359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:21.80302Z","caller":"traceutil/trace.go:171","msg":"trace[1414580966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"745.476143ms","start":"2026-05-10T00:52:21.057534Z","end":"2026-05-10T00:52:21.80301Z","steps":["trace[1414580966] 'agreement among raft nodes before linearized reading' (duration: 745.298968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:21.803065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.05751Z","time spent":"745.546244ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:52:21.803261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.022966Z","time spent":"779.838297ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:22.099428Z","caller":"traceutil/trace.go:171","msg":"trace[231203091] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"296.4327ms","start":"2026-05-10T00:52:21.80298Z","end":"2026-05-10T00:52:22.099412Z","steps":["trace[231203091] 'read index received' (duration: 296.287616ms)","trace[231203091] 'applied index is now lower than readState.Index' (duration: 144.584µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:22.099697Z","caller":"traceutil/trace.go:171","msg":"trace[2085847611] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"387.172804ms","start":"2026-05-10T00:52:21.712517Z","end":"2026-05-10T00:52:22.09969Z","steps":["trace[2085847611] 'process raft request' (duration: 386.795355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.099717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.439486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:52:22.099758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024607786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-10T00:52:22.099769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.712495Z","time spent":"387.222685ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:22.099783Z","caller":"traceutil/trace.go:171","msg":"trace[974273922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"295.542019ms","start":"2026-05-10T00:52:21.804223Z","end":"2026-05-10T00:52:22.099765Z","steps":["trace[974273922] 'agreement among raft nodes before linearized reading' (duration: 295.426316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.099777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035224135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-10T00:52:22.099833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.767625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-10T00:52:22.099849Z","caller":"traceutil/trace.go:171","msg":"trace[2035075520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"307.815646ms","start":"2026-05-10T00:52:21.79203Z","end":"2026-05-10T00:52:22.099845Z","steps":["trace[2035075520] 'agreement among raft nodes before linearized reading' (duration: 307.638942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.099864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.792014Z","time spent":"307.845487ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T00:52:22.099868Z","caller":"traceutil/trace.go:171","msg":"trace[601102643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"1.035357359s","start":"2026-05-10T00:52:21.064489Z","end":"2026-05-10T00:52:22.099846Z","steps":["trace[601102643] 'agreement among raft nodes before linearized reading' (duration: 1.035066482s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.099867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022034476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-10T00:52:22.099921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.064478Z","time spent":"1.03543307s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-10T00:52:22.099934Z","caller":"traceutil/trace.go:171","msg":"trace[1663834750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"1.022099388s","start":"2026-05-10T00:52:21.077818Z","end":"2026-05-10T00:52:22.099917Z","steps":["trace[1663834750] 'agreement among raft nodes before linearized reading' (duration: 1.021961595s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.099949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.677406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:22.100046Z","caller":"traceutil/trace.go:171","msg":"trace[99899749] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1257; }","duration":"434.83404ms","start":"2026-05-10T00:52:21.665188Z","end":"2026-05-10T00:52:22.100022Z","steps":["trace[99899749] 'agreement among raft nodes before linearized reading' (duration: 434.628725ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:22.099788Z","caller":"traceutil/trace.go:171","msg":"trace[1365301885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1257; }","duration":"1.024705339s","start":"2026-05-10T00:52:21.075075Z","end":"2026-05-10T00:52:22.09978Z","steps":["trace[1365301885] 'agreement among raft nodes before linearized reading' (duration: 1.024571686s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.100173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.075039Z","time spent":"1.025124939s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-10T00:52:22.099983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.077814Z","time spent":"1.02216098s","remote":"127.0.0.1:41588","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-10T00:52:22.099735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022345164s","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-10T00:52:22.100456Z","caller":"traceutil/trace.go:171","msg":"trace[476419951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"1.023151703s","start":"2026-05-10T00:52:21.077293Z","end":"2026-05-10T00:52:22.100445Z","steps":["trace[476419951] 'agreement among raft nodes before linearized reading' (duration: 1.022227381s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:22.100495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.077276Z","time spent":"1.023210784s","remote":"127.0.0.1:41588","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-10T00:52:22.100116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:21.665169Z","time spent":"434.921301ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-10T00:52:22.450569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.950141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:22.450677Z","caller":"traceutil/trace.go:171","msg":"trace[1627456837] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"345.293934ms","start":"2026-05-10T00:52:22.105365Z","end":"2026-05-10T00:52:22.450659Z","steps":["trace[1627456837] 'process raft request' (duration: 107.176689ms)","trace[1627456837] 'compare' (duration: 237.743396ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:22.450735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:22.1053Z","time spent":"345.407266ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:23.385535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.639651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:23.38586Z","caller":"traceutil/trace.go:171","msg":"trace[399659733] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1309; }","duration":"1.27895843s","start":"2026-05-10T00:52:22.106881Z","end":"2026-05-10T00:52:23.385839Z","steps":["trace[399659733] 'read index received' (duration: 105.773116ms)","trace[399659733] 'applied index is now lower than readState.Index' (duration: 1.173184194s)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:23.385925Z","caller":"traceutil/trace.go:171","msg":"trace[913549257] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"1.280149789s","start":"2026-05-10T00:52:22.105757Z","end":"2026-05-10T00:52:23.385906Z","steps":["trace[913549257] 'process raft request' (duration: 1.279865442s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:23.385975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279095424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-10T00:52:23.386006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:22.105738Z","time spent":"1.28022595s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:23.386058Z","caller":"traceutil/trace.go:171","msg":"trace[1224571337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"1.279190756s","start":"2026-05-10T00:52:22.106817Z","end":"2026-05-10T00:52:23.386008Z","steps":["trace[1224571337] 'agreement among raft nodes before linearized reading' (duration: 1.279096004s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:23.386243Z","caller":"traceutil/trace.go:171","msg":"trace[1522994175] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"1.279558395s","start":"2026-05-10T00:52:22.106676Z","end":"2026-05-10T00:52:23.386234Z","steps":["trace[1522994175] 'process raft request' (duration: 1.279065293s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:23.386241Z","caller":"traceutil/trace.go:171","msg":"trace[111943397] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"1.280466876s","start":"2026-05-10T00:52:22.10574Z","end":"2026-05-10T00:52:23.386207Z","steps":["trace[111943397] 'process raft request' (duration: 1.072093588s)","trace[111943397] 'compare' (duration: 207.528388ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:23.386277Z","caller":"traceutil/trace.go:171","msg":"trace[142711314] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"1.219323935s","start":"2026-05-10T00:52:22.166937Z","end":"2026-05-10T00:52:23.386261Z","steps":["trace[142711314] 'process raft request' (duration: 1.218854174s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:23.3863Z","caller":"traceutil/trace.go:171","msg":"trace[977415193] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"1.280452606s","start":"2026-05-10T00:52:22.105843Z","end":"2026-05-10T00:52:23.386296Z","steps":["trace[977415193] 'process raft request' (duration: 1.279842452s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:23.386325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:22.105811Z","time spent":"1.280502358s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:23.386361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:22.166914Z","time spent":"1.219402697s","remote":"127.0.0.1:41348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-10T00:52:23.386397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:22.105716Z","time spent":"1.28059888s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:23.386281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:22.106664Z","time spent":"1.279596075s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:23.386138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:22.106797Z","time spent":"1.279292408s","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:52:23.996919Z","caller":"traceutil/trace.go:171","msg":"trace[1774927077] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"120.231335ms","start":"2026-05-10T00:52:23.876658Z","end":"2026-05-10T00:52:23.99689Z","steps":["trace[1774927077] 'process raft request' (duration: 52.849637ms)","trace[1774927077] 'compare' (duration: 67.225074ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:24.617367Z","caller":"traceutil/trace.go:171","msg":"trace[577732631] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"159.137735ms","start":"2026-05-10T00:52:24.458206Z","end":"2026-05-10T00:52:24.617344Z","steps":["trace[577732631] 'process raft request' (duration: 158.999852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:24.937992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.049054ms","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-10T00:52:24.938071Z","caller":"traceutil/trace.go:171","msg":"trace[16807314] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1266; }","duration":"203.171026ms","start":"2026-05-10T00:52:24.734881Z","end":"2026-05-10T00:52:24.938052Z","steps":["trace[16807314] 'count revisions from in-memory index tree' (duration: 202.978452ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:25.712028Z","caller":"traceutil/trace.go:171","msg":"trace[1780283813] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"316.11055ms","start":"2026-05-10T00:52:25.395899Z","end":"2026-05-10T00:52:25.712009Z","steps":["trace[1780283813] 'process raft request' (duration: 316.061689ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:25.712093Z","caller":"traceutil/trace.go:171","msg":"trace[1925790938] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"317.133554ms","start":"2026-05-10T00:52:25.39491Z","end":"2026-05-10T00:52:25.712044Z","steps":["trace[1925790938] 'process raft request' (duration: 316.934199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:25.7122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:25.395892Z","time spent":"316.231143ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:25.712204Z","caller":"traceutil/trace.go:171","msg":"trace[1286849432] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"315.581018ms","start":"2026-05-10T00:52:25.396603Z","end":"2026-05-10T00:52:25.712184Z","steps":["trace[1286849432] 'process raft request' (duration: 315.385713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:25.712316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:25.394902Z","time spent":"317.302468ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:25.712426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:25.396596Z","time spent":"315.706141ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:27.143882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.04953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:27.144299Z","caller":"traceutil/trace.go:171","msg":"trace[1935937023] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"815.667655ms","start":"2026-05-10T00:52:26.328614Z","end":"2026-05-10T00:52:27.144282Z","steps":["trace[1935937023] 'process raft request' (duration: 537.169896ms)","trace[1935937023] 'compare' (duration: 277.612079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:27.144378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:26.328592Z","time spent":"815.753447ms","remote":"127.0.0.1:41348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-10T00:52:27.144587Z","caller":"traceutil/trace.go:171","msg":"trace[1950339351] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"678.255408ms","start":"2026-05-10T00:52:26.465808Z","end":"2026-05-10T00:52:27.144064Z","steps":["trace[1950339351] 'read index received' (duration: 400.016304ms)","trace[1950339351] 'applied index is now lower than readState.Index' (duration: 278.237744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:27.144627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.791301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:27.14472Z","caller":"traceutil/trace.go:171","msg":"trace[992112830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"678.921674ms","start":"2026-05-10T00:52:26.46578Z","end":"2026-05-10T00:52:27.144702Z","steps":["trace[992112830] 'agreement among raft nodes before linearized reading' (duration: 678.73007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.144764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:26.465767Z","time spent":"678.989185ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:52:27.144772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.410988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-05-10T00:52:27.144806Z","caller":"traceutil/trace.go:171","msg":"trace[394757136] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1274; }","duration":"542.49764ms","start":"2026-05-10T00:52:26.602298Z","end":"2026-05-10T00:52:27.144796Z","steps":["trace[394757136] 'agreement among raft nodes before linearized reading' (duration: 542.368677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.144834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:26.602274Z","time spent":"542.552162ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-10T00:52:27.144897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.733821ms","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-10T00:52:27.144977Z","caller":"traceutil/trace.go:171","msg":"trace[1780809947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1274; }","duration":"523.842364ms","start":"2026-05-10T00:52:26.621103Z","end":"2026-05-10T00:52:27.144945Z","steps":["trace[1780809947] 'agreement among raft nodes before linearized reading' (duration: 523.642509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.145018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:26.621084Z","time spent":"523.925755ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T00:52:27.468189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.696936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:27.468269Z","caller":"traceutil/trace.go:171","msg":"trace[724757507] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"319.727695ms","start":"2026-05-10T00:52:27.148527Z","end":"2026-05-10T00:52:27.468255Z","steps":["trace[724757507] 'process raft request' (duration: 110.903536ms)","trace[724757507] 'compare' (duration: 208.582053ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:27.46831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:27.148509Z","time spent":"319.781967ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:27.528811Z","caller":"traceutil/trace.go:171","msg":"trace[1513231294] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"113.240781ms","start":"2026-05-10T00:52:27.415553Z","end":"2026-05-10T00:52:27.528794Z","steps":["trace[1513231294] 'read index received' (duration: 113.107738ms)","trace[1513231294] 'applied index is now lower than readState.Index' (duration: 132.583µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:27.52885Z","caller":"traceutil/trace.go:171","msg":"trace[1428957680] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"374.261532ms","start":"2026-05-10T00:52:27.154573Z","end":"2026-05-10T00:52:27.528835Z","steps":["trace[1428957680] 'process raft request' (duration: 374.129049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.528931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.375644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-10T00:52:27.528951Z","caller":"traceutil/trace.go:171","msg":"trace[1719803205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1276; }","duration":"113.424115ms","start":"2026-05-10T00:52:27.415518Z","end":"2026-05-10T00:52:27.528943Z","steps":["trace[1719803205] 'agreement among raft nodes before linearized reading' (duration: 113.341114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.528953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:27.154562Z","time spent":"374.323273ms","remote":"127.0.0.1:41444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:27.852418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.399763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:27.852501Z","caller":"traceutil/trace.go:171","msg":"trace[1877924957] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"319.09632ms","start":"2026-05-10T00:52:27.53339Z","end":"2026-05-10T00:52:27.852486Z","steps":["trace[1877924957] 'process raft request' (duration: 194.560424ms)","trace[1877924957] 'compare' (duration: 124.191488ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:27.852544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:27.533368Z","time spent":"319.154872ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:27.909589Z","caller":"traceutil/trace.go:171","msg":"trace[349108674] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1329; }","duration":"192.670871ms","start":"2026-05-10T00:52:27.716887Z","end":"2026-05-10T00:52:27.909558Z","steps":["trace[349108674] 'read index received' (duration: 11.058149ms)","trace[349108674] 'applied index is now lower than readState.Index' (duration: 181.611692ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:27.909696Z","caller":"traceutil/trace.go:171","msg":"trace[1193326309] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"373.479923ms","start":"2026-05-10T00:52:27.536204Z","end":"2026-05-10T00:52:27.909684Z","steps":["trace[1193326309] 'process raft request' (duration: 373.221597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.909794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:27.536196Z","time spent":"373.525945ms","remote":"127.0.0.1:41718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:27.909999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.091921ms","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-10T00:52:27.910071Z","caller":"traceutil/trace.go:171","msg":"trace[511726534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"193.207024ms","start":"2026-05-10T00:52:27.716846Z","end":"2026-05-10T00:52:27.910053Z","steps":["trace[511726534] 'agreement among raft nodes before linearized reading' (duration: 193.015009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.922541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.363827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-10T00:52:27.92259Z","caller":"traceutil/trace.go:171","msg":"trace[1435481474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"205.46203ms","start":"2026-05-10T00:52:27.717118Z","end":"2026-05-10T00:52:27.92258Z","steps":["trace[1435481474] 'agreement among raft nodes before linearized reading' (duration: 205.343127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:27.92289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.631844ms","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-10T00:52:27.922924Z","caller":"traceutil/trace.go:171","msg":"trace[1019632175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1279; }","duration":"205.705366ms","start":"2026-05-10T00:52:27.717211Z","end":"2026-05-10T00:52:27.922917Z","steps":["trace[1019632175] 'agreement among raft nodes before linearized reading' (duration: 205.635654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:35.911226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.235683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:35.911546Z","caller":"traceutil/trace.go:171","msg":"trace[120260502] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"408.023232ms","start":"2026-05-10T00:52:35.503443Z","end":"2026-05-10T00:52:35.911466Z","steps":["trace[120260502] 'process raft request' (duration: 226.418761ms)","trace[120260502] 'compare' (duration: 181.062529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:35.911704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:35.50342Z","time spent":"408.216677ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:37.196467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184948051s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-10T00:52:37.197129Z","caller":"traceutil/trace.go:171","msg":"trace[1788157144] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"1.281627773s","start":"2026-05-10T00:52:35.915471Z","end":"2026-05-10T00:52:37.197099Z","steps":["trace[1788157144] 'process raft request' (duration: 95.975987ms)","trace[1788157144] 'compare' (duration: 1.184647463s)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:52:37.197169Z","caller":"traceutil/trace.go:171","msg":"trace[1718719171] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.253903194s","start":"2026-05-10T00:52:35.943246Z","end":"2026-05-10T00:52:37.197149Z","steps":["trace[1718719171] 'process raft request' (duration: 1.253557056s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:37.197257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:35.915449Z","time spent":"1.281742856s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-10T00:52:37.197453Z","caller":"traceutil/trace.go:171","msg":"trace[759970787] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1408; }","duration":"1.220989954s","start":"2026-05-10T00:52:35.976436Z","end":"2026-05-10T00:52:37.197426Z","steps":["trace[759970787] 'read index received' (duration: 34.986319ms)","trace[759970787] 'applied index is now lower than readState.Index' (duration: 1.186001445s)"],"step_count":2} {"level":"warn","ts":"2026-05-10T00:52:37.197434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:35.943225Z","time spent":"1.253990626s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:52:37.197656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141303838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-10T00:52:37.197658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.372533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-10T00:52:37.197687Z","caller":"traceutil/trace.go:171","msg":"trace[1810915531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1355; }","duration":"1.14139255s","start":"2026-05-10T00:52:36.056286Z","end":"2026-05-10T00:52:37.197678Z","steps":["trace[1810915531] 'agreement among raft nodes before linearized reading' (duration: 1.141260197s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:37.197655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221208279s","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-10T00:52:37.19776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141405321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-10T00:52:37.197766Z","caller":"traceutil/trace.go:171","msg":"trace[116880300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"1.221343482s","start":"2026-05-10T00:52:35.976388Z","end":"2026-05-10T00:52:37.197732Z","steps":["trace[116880300] 'agreement among raft nodes before linearized reading' (duration: 1.221122657s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:52:37.197785Z","caller":"traceutil/trace.go:171","msg":"trace[1664156241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1355; }","duration":"1.141464322s","start":"2026-05-10T00:52:36.056313Z","end":"2026-05-10T00:52:37.197777Z","steps":["trace[1664156241] 'agreement among raft nodes before linearized reading' (duration: 1.141411171s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:37.197783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.666875ms","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:530"} {"level":"warn","ts":"2026-05-10T00:52:37.197803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:36.056292Z","time spent":"1.141506973s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T00:52:37.197812Z","caller":"traceutil/trace.go:171","msg":"trace[966332811] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1355; }","duration":"126.724317ms","start":"2026-05-10T00:52:37.07108Z","end":"2026-05-10T00:52:37.197804Z","steps":["trace[966332811] 'agreement among raft nodes before linearized reading' (duration: 126.653635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:37.197855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141502713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:52:37.197887Z","caller":"traceutil/trace.go:171","msg":"trace[2041920867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"1.141553534s","start":"2026-05-10T00:52:36.056325Z","end":"2026-05-10T00:52:37.197879Z","steps":["trace[2041920867] 'agreement among raft nodes before linearized reading' (duration: 1.141510133s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:37.197914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:36.056313Z","time spent":"1.141596205s","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-10T00:52:37.197811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:35.976372Z","time spent":"1.221428514s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-10T00:52:37.19771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:36.056267Z","time spent":"1.141437751s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-10T00:52:37.197701Z","caller":"traceutil/trace.go:171","msg":"trace[1416833552] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1355; }","duration":"488.475725ms","start":"2026-05-10T00:52:36.709209Z","end":"2026-05-10T00:52:37.197685Z","steps":["trace[1416833552] 'agreement among raft nodes before linearized reading' (duration: 488.349012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:52:37.198033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:52:36.709188Z","time spent":"488.835684ms","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-10T00:53:23.692144Z","caller":"traceutil/trace.go:171","msg":"trace[1570327024] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"156.593296ms","start":"2026-05-10T00:53:23.535526Z","end":"2026-05-10T00:53:23.692119Z","steps":["trace[1570327024] 'process raft request' (duration: 156.085794ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:53:48.168187Z","caller":"traceutil/trace.go:171","msg":"trace[1844861617] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"221.225671ms","start":"2026-05-10T00:53:47.946936Z","end":"2026-05-10T00:53:48.168162Z","steps":["trace[1844861617] 'process raft request' (duration: 196.850241ms)","trace[1844861617] 'compare' (duration: 24.223826ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:53:48.168342Z","caller":"traceutil/trace.go:171","msg":"trace[153405982] linearizableReadLoop","detail":"{readStateIndex:1752; appliedIndex:1750; }","duration":"111.702866ms","start":"2026-05-10T00:53:48.056612Z","end":"2026-05-10T00:53:48.168315Z","steps":["trace[153405982] 'read index received' (duration: 87.188154ms)","trace[153405982] 'applied index is now lower than readState.Index' (duration: 24.512312ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:53:48.168455Z","caller":"traceutil/trace.go:171","msg":"trace[1038743850] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"220.727929ms","start":"2026-05-10T00:53:47.947661Z","end":"2026-05-10T00:53:48.168389Z","steps":["trace[1038743850] 'process raft request' (duration: 220.462213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:53:48.168514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.849679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:53:48.168561Z","caller":"traceutil/trace.go:171","msg":"trace[638980135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1683; }","duration":"111.985863ms","start":"2026-05-10T00:53:48.056561Z","end":"2026-05-10T00:53:48.168547Z","steps":["trace[638980135] 'agreement among raft nodes before linearized reading' (duration: 111.84892ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:06.616491Z","caller":"traceutil/trace.go:171","msg":"trace[2659777] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"116.988782ms","start":"2026-05-10T00:54:06.499476Z","end":"2026-05-10T00:54:06.616465Z","steps":["trace[2659777] 'process raft request' (duration: 116.858989ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:22.87542Z","caller":"traceutil/trace.go:171","msg":"trace[280509175] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"179.96554ms","start":"2026-05-10T00:54:22.695432Z","end":"2026-05-10T00:54:22.875398Z","steps":["trace[280509175] 'process raft request' (duration: 179.822217ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:25.796221Z","caller":"traceutil/trace.go:171","msg":"trace[2119704482] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"155.61543ms","start":"2026-05-10T00:54:25.640569Z","end":"2026-05-10T00:54:25.796184Z","steps":["trace[2119704482] 'process raft request' (duration: 155.304023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:29.98253Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297080922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:54:30.483261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095762297080922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-10T00:54:30.496557Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.020330786s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-10T00:54:30.502236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.482034Z","time spent":"1.020173112s","remote":"127.0.0.1:41348","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-10T00:54:30.502326Z","caller":"traceutil/trace.go:171","msg":"trace[1025110981] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1938; }","duration":"1.020661534s","start":"2026-05-10T00:54:29.481626Z","end":"2026-05-10T00:54:30.502287Z","steps":["trace[1025110981] 'read index received' (duration: 1.015146003s)","trace[1025110981] 'applied index is now lower than readState.Index' (duration: 5.513971ms)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:54:30.502411Z","caller":"traceutil/trace.go:171","msg":"trace[1900828536] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"1.017386766s","start":"2026-05-10T00:54:29.485001Z","end":"2026-05-10T00:54:30.502388Z","steps":["trace[1900828536] 'process raft request' (duration: 1.01710611s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.502532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.484992Z","time spent":"1.017470148s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-10T00:54:30.50259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018855561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-10T00:54:30.502591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020662904s","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-10T00:54:30.502599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02094601s","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-10T00:54:30.502635Z","caller":"traceutil/trace.go:171","msg":"trace[139009361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"1.018909382s","start":"2026-05-10T00:54:29.483713Z","end":"2026-05-10T00:54:30.502623Z","steps":["trace[139009361] 'agreement among raft nodes before linearized reading' (duration: 1.018788889s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.502638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017372496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-10T00:54:30.502647Z","caller":"traceutil/trace.go:171","msg":"trace[1446571526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1862; }","duration":"1.021037733s","start":"2026-05-10T00:54:29.481597Z","end":"2026-05-10T00:54:30.502635Z","steps":["trace[1446571526] 'agreement among raft nodes before linearized reading' (duration: 1.020866609s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.502658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.483711Z","time spent":"1.018942723s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-10T00:54:30.502671Z","caller":"traceutil/trace.go:171","msg":"trace[1313659612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"1.017411727s","start":"2026-05-10T00:54:29.485249Z","end":"2026-05-10T00:54:30.502661Z","steps":["trace[1313659612] 'agreement among raft nodes before linearized reading' (duration: 1.017370656s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.502684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.481585Z","time spent":"1.021091204s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-10T00:54:30.502698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.485244Z","time spent":"1.017447557s","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-10T00:54:30.502641Z","caller":"traceutil/trace.go:171","msg":"trace[568266592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"1.020731986s","start":"2026-05-10T00:54:29.481897Z","end":"2026-05-10T00:54:30.502629Z","steps":["trace[568266592] 'agreement among raft nodes before linearized reading' (duration: 1.020586432s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.50259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017614942s","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-10T00:54:30.502775Z","caller":"traceutil/trace.go:171","msg":"trace[1333039958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"1.017826406s","start":"2026-05-10T00:54:29.484944Z","end":"2026-05-10T00:54:30.502771Z","steps":["trace[1333039958] 'agreement among raft nodes before linearized reading' (duration: 1.01755347s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.50278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019088206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-10T00:54:30.50279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.484939Z","time spent":"1.017847797s","remote":"127.0.0.1:41588","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-10T00:54:30.50278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.481892Z","time spent":"1.020852269s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-10T00:54:30.502806Z","caller":"traceutil/trace.go:171","msg":"trace[2068997317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1862; }","duration":"1.019132928s","start":"2026-05-10T00:54:29.483665Z","end":"2026-05-10T00:54:30.502798Z","steps":["trace[2068997317] 'agreement among raft nodes before linearized reading' (duration: 1.019062876s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.502826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019071666s","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-10T00:54:30.502861Z","caller":"traceutil/trace.go:171","msg":"trace[1316108047] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1862; }","duration":"1.019129267s","start":"2026-05-10T00:54:29.483725Z","end":"2026-05-10T00:54:30.502854Z","steps":["trace[1316108047] 'agreement among raft nodes before linearized reading' (duration: 1.018995934s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.50286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.483659Z","time spent":"1.019191989s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-10T00:54:30.502712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018996975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-10T00:54:30.502861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020667024s","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":"info","ts":"2026-05-10T00:54:30.502903Z","caller":"traceutil/trace.go:171","msg":"trace[1369330666] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1862; }","duration":"1.020745156s","start":"2026-05-10T00:54:29.482152Z","end":"2026-05-10T00:54:30.502897Z","steps":["trace[1369330666] 'agreement among raft nodes before linearized reading' (duration: 1.020691095s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.502918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.482128Z","time spent":"1.020786257s","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"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-10T00:54:30.50288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.483723Z","time spent":"1.019152988s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-10T00:54:30.502944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021003632s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-10T00:54:30.502962Z","caller":"traceutil/trace.go:171","msg":"trace[1136455346] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1862; }","duration":"1.021034443s","start":"2026-05-10T00:54:29.481923Z","end":"2026-05-10T00:54:30.502957Z","steps":["trace[1136455346] 'agreement among raft nodes before linearized reading' (duration: 1.020984802s)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:30.502953Z","caller":"traceutil/trace.go:171","msg":"trace[1298937658] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1862; }","duration":"1.019193599s","start":"2026-05-10T00:54:29.483701Z","end":"2026-05-10T00:54:30.502894Z","steps":["trace[1298937658] 'agreement among raft nodes before linearized reading' (duration: 1.018957294s)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:30.502974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.481917Z","time spent":"1.021054513s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-10T00:54:30.502993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-10T00:54:29.483698Z","time spent":"1.019285131s","remote":"127.0.0.1:41588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-10T00:54:42.891445Z","caller":"traceutil/trace.go:171","msg":"trace[970136699] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"186.395573ms","start":"2026-05-10T00:54:42.705027Z","end":"2026-05-10T00:54:42.891423Z","steps":["trace[970136699] 'process raft request' (duration: 186.356772ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:42.891623Z","caller":"traceutil/trace.go:171","msg":"trace[1224092169] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"188.215506ms","start":"2026-05-10T00:54:42.703368Z","end":"2026-05-10T00:54:42.891584Z","steps":["trace[1224092169] 'process raft request' (duration: 187.843417ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:42.891682Z","caller":"traceutil/trace.go:171","msg":"trace[382596166] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"188.190545ms","start":"2026-05-10T00:54:42.703454Z","end":"2026-05-10T00:54:42.891644Z","steps":["trace[382596166] 'process raft request' (duration: 187.889148ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:58.897798Z","caller":"traceutil/trace.go:171","msg":"trace[461303544] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"124.271686ms","start":"2026-05-10T00:54:58.77351Z","end":"2026-05-10T00:54:58.897782Z","steps":["trace[461303544] 'read index received' (duration: 124.141483ms)","trace[461303544] 'applied index is now lower than readState.Index' (duration: 129.483µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:54:58.897879Z","caller":"traceutil/trace.go:171","msg":"trace[328618074] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"177.522806ms","start":"2026-05-10T00:54:58.720327Z","end":"2026-05-10T00:54:58.89785Z","steps":["trace[328618074] 'process raft request' (duration: 177.346602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:54:58.898177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.652361ms","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-10T00:54:58.898178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.624944ms","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-10T00:54:58.898206Z","caller":"traceutil/trace.go:171","msg":"trace[709863156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1995; }","duration":"118.731663ms","start":"2026-05-10T00:54:58.779468Z","end":"2026-05-10T00:54:58.8982Z","steps":["trace[709863156] 'agreement among raft nodes before linearized reading' (duration: 118.420426ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:54:58.898233Z","caller":"traceutil/trace.go:171","msg":"trace[189758470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1995; }","duration":"124.750978ms","start":"2026-05-10T00:54:58.773468Z","end":"2026-05-10T00:54:58.898219Z","steps":["trace[189758470] 'agreement among raft nodes before linearized reading' (duration: 124.42072ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:51.438794Z","caller":"traceutil/trace.go:171","msg":"trace[816373464] linearizableReadLoop","detail":"{readStateIndex:2362; appliedIndex:2361; }","duration":"147.11696ms","start":"2026-05-10T00:55:51.291657Z","end":"2026-05-10T00:55:51.438774Z","steps":["trace[816373464] 'read index received' (duration: 146.875645ms)","trace[816373464] 'applied index is now lower than readState.Index' (duration: 240.455µs)"],"step_count":2} {"level":"info","ts":"2026-05-10T00:55:51.438887Z","caller":"traceutil/trace.go:171","msg":"trace[960600704] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"224.560199ms","start":"2026-05-10T00:55:51.214286Z","end":"2026-05-10T00:55:51.438846Z","steps":["trace[960600704] 'process raft request' (duration: 224.371275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:51.439004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.337256ms","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-10T00:55:51.439033Z","caller":"traceutil/trace.go:171","msg":"trace[573561701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2266; }","duration":"147.381107ms","start":"2026-05-10T00:55:51.291643Z","end":"2026-05-10T00:55:51.439024Z","steps":["trace[573561701] 'agreement among raft nodes before linearized reading' (duration: 147.261435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:51.439153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.253833ms","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-10T00:55:51.439168Z","caller":"traceutil/trace.go:171","msg":"trace[803502600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2266; }","duration":"139.294465ms","start":"2026-05-10T00:55:51.299869Z","end":"2026-05-10T00:55:51.439164Z","steps":["trace[803502600] 'agreement among raft nodes before linearized reading' (duration: 139.246654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:51.439693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.683753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-10T00:55:51.439719Z","caller":"traceutil/trace.go:171","msg":"trace[1238706016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2266; }","duration":"131.738154ms","start":"2026-05-10T00:55:51.307973Z","end":"2026-05-10T00:55:51.439712Z","steps":["trace[1238706016] 'agreement among raft nodes before linearized reading' (duration: 131.676382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-10T00:55:51.439778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.179035ms","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":"warn","ts":"2026-05-10T00:55:51.4398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.60174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-10T00:55:51.439854Z","caller":"traceutil/trace.go:171","msg":"trace[1370316586] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2266; }","duration":"127.301608ms","start":"2026-05-10T00:55:51.312533Z","end":"2026-05-10T00:55:51.439835Z","steps":["trace[1370316586] 'agreement among raft nodes before linearized reading' (duration: 127.126204ms)"],"step_count":1} {"level":"info","ts":"2026-05-10T00:55:51.43987Z","caller":"traceutil/trace.go:171","msg":"trace[763308637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2266; }","duration":"131.693983ms","start":"2026-05-10T00:55:51.308158Z","end":"2026-05-10T00:55:51.439852Z","steps":["trace[763308637] 'agreement among raft nodes before linearized reading' (duration: 131.451637ms)"],"step_count":1}