{"level":"warn","ts":"2026-05-09T21:46:53.095754Z","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-09T21:46:53.095865Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-09T21:46:53.095952Z","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-09T21:46:53.095968Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-09T21:46:53.095989Z","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-09T21:46:53.096939Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-05-09T21:46:53.097069Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-09T21:46:53.103328Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.774862ms"} {"level":"info","ts":"2026-05-09T21:46:53.12236Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-05-09T21:46:53.122455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T21:46:53.122475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-05-09T21:46:53.122484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T21:46:53.122493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-05-09T21:46:53.122523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-05-09T21:46:53.130253Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T21:46:53.13545Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T21:46:53.136812Z","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-09T21:46:53.139756Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T21:46:53.140236Z","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-09T21:46:53.140389Z","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-09T21:46:53.140401Z","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-09T21:46:53.140932Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T21:46:53.144774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-05-09T21:46:53.144992Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-09T21:46:53.147537Z","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-09T21:46:53.147846Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-09T21:46:53.147901Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-09T21:46:53.148158Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T21:46:53.148217Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T21:46:53.72361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T21:46:53.723727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T21:46:53.723754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-05-09T21:46:53.723774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-05-09T21:46:53.723792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-09T21:46:53.723808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-05-09T21:46:53.723829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-09T21:46:53.748797Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:46:53.748811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:46:53.748814Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T21:46:53.749236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:46:53.749357Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T21:46:53.749397Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T21:46:53.752019Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T21:46:53.752636Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-05-09T21:46:53.753208Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:46:53.753435Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:46:53.75355Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:47:16.970109Z","caller":"traceutil/trace.go:171","msg":"trace[381234457] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"149.903891ms","start":"2026-05-09T21:47:16.820179Z","end":"2026-05-09T21:47:16.970083Z","steps":["trace[381234457] 'read index received' (duration: 149.716507ms)","trace[381234457] 'applied index is now lower than readState.Index' (duration: 185.614µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:16.970288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.094025ms","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-09T21:47:16.970361Z","caller":"traceutil/trace.go:171","msg":"trace[1317360698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"150.233568ms","start":"2026-05-09T21:47:16.820116Z","end":"2026-05-09T21:47:16.97035Z","steps":["trace[1317360698] 'agreement among raft nodes before linearized reading' (duration: 150.086225ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:17.603628Z","caller":"traceutil/trace.go:171","msg":"trace[1954941402] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:474; }","duration":"490.7128ms","start":"2026-05-09T21:47:17.1129Z","end":"2026-05-09T21:47:17.603613Z","steps":["trace[1954941402] 'read index received' (duration: 429.624353ms)","trace[1954941402] 'applied index is now lower than readState.Index' (duration: 61.087557ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:17.603648Z","caller":"traceutil/trace.go:171","msg":"trace[1564864271] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"632.392316ms","start":"2026-05-09T21:47:16.971222Z","end":"2026-05-09T21:47:17.603614Z","steps":["trace[1564864271] 'process raft request' (duration: 571.299459ms)","trace[1564864271] 'compare' (duration: 60.947244ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:17.603741Z","caller":"traceutil/trace.go:171","msg":"trace[915594982] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"629.372507ms","start":"2026-05-09T21:47:16.974337Z","end":"2026-05-09T21:47:17.60371Z","steps":["trace[915594982] 'process raft request' (duration: 629.232624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:17.603801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.849963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T21:47:17.603829Z","caller":"traceutil/trace.go:171","msg":"trace[821658943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:457; }","duration":"490.973146ms","start":"2026-05-09T21:47:17.112848Z","end":"2026-05-09T21:47:17.603822Z","steps":["trace[821658943] 'agreement among raft nodes before linearized reading' (duration: 490.864763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:17.60385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:17.112824Z","time spent":"491.022007ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:17.604014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.434345ms","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-09T21:47:17.604124Z","caller":"traceutil/trace.go:171","msg":"trace[5435413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"139.585729ms","start":"2026-05-09T21:47:17.464511Z","end":"2026-05-09T21:47:17.604097Z","steps":["trace[5435413] 'agreement among raft nodes before linearized reading' (duration: 139.417135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:17.60431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:16.971199Z","time spent":"632.531469ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:17.60445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:16.974316Z","time spent":"629.509991ms","remote":"127.0.0.1:47608","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-09T21:47:17.604551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.22033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T21:47:17.604819Z","caller":"traceutil/trace.go:171","msg":"trace[1240685761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:457; }","duration":"420.558488ms","start":"2026-05-09T21:47:17.184247Z","end":"2026-05-09T21:47:17.604806Z","steps":["trace[1240685761] 'agreement among raft nodes before linearized reading' (duration: 419.636256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:17.604855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:17.184224Z","time spent":"420.619138ms","remote":"127.0.0.1:47608","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-09T21:47:23.135653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596762698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:47:23.559986Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021914675s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T21:47:23.560798Z","caller":"traceutil/trace.go:171","msg":"trace[1384129089] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.022807885s","start":"2026-05-09T21:47:22.537941Z","end":"2026-05-09T21:47:23.560749Z","steps":["trace[1384129089] 'process raft request' (duration: 1.022459267s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.561055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.537918Z","time spent":"1.023012979s","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:23.575153Z","caller":"traceutil/trace.go:171","msg":"trace[629078050] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.033959976s","start":"2026-05-09T21:47:22.541159Z","end":"2026-05-09T21:47:23.575119Z","steps":["trace[629078050] 'process raft request' (duration: 1.033414864s)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:23.57526Z","caller":"traceutil/trace.go:171","msg":"trace[1128467404] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.028206526s","start":"2026-05-09T21:47:22.547005Z","end":"2026-05-09T21:47:23.575211Z","steps":["trace[1128467404] 'process raft request' (duration: 1.027703285s)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:23.575331Z","caller":"traceutil/trace.go:171","msg":"trace[1601982735] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"988.893959ms","start":"2026-05-09T21:47:22.586429Z","end":"2026-05-09T21:47:23.575323Z","steps":["trace[1601982735] 'process raft request' (duration: 988.582832ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:23.575352Z","caller":"traceutil/trace.go:171","msg":"trace[1040245408] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"992.092171ms","start":"2026-05-09T21:47:22.58325Z","end":"2026-05-09T21:47:23.575342Z","steps":["trace[1040245408] 'process raft request' (duration: 991.61023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.575435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.54698Z","time spent":"1.02837334s","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:47:23.575475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.586382Z","time spent":"989.000112ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:23.57515Z","caller":"traceutil/trace.go:171","msg":"trace[2025328838] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.004550073s","start":"2026-05-09T21:47:22.570545Z","end":"2026-05-09T21:47:23.575095Z","steps":["trace[2025328838] 'process raft request' (duration: 1.004222996s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.575567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.583237Z","time spent":"992.160473ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:47:23.5757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.541146Z","time spent":"1.034382065s","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:47:23.575616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.570527Z","time spent":"1.005061614s","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:23.576212Z","caller":"traceutil/trace.go:171","msg":"trace[464401128] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"970.039703ms","start":"2026-05-09T21:47:22.606147Z","end":"2026-05-09T21:47:23.576187Z","steps":["trace[464401128] 'process raft request' (duration: 968.98814ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:23.576266Z","caller":"traceutil/trace.go:171","msg":"trace[292810572] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:524; }","duration":"941.008778ms","start":"2026-05-09T21:47:22.635232Z","end":"2026-05-09T21:47:23.57624Z","steps":["trace[292810572] 'read index received' (duration: 925.11274ms)","trace[292810572] 'applied index is now lower than readState.Index' (duration: 15.894768ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:23.576322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.606112Z","time spent":"970.150516ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:23.575743Z","caller":"traceutil/trace.go:171","msg":"trace[1875051752] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"972.736034ms","start":"2026-05-09T21:47:22.602993Z","end":"2026-05-09T21:47:23.575729Z","steps":["trace[1875051752] 'process raft request' (duration: 972.07754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.576437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.181232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:23.576469Z","caller":"traceutil/trace.go:171","msg":"trace[1132217830] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"941.337867ms","start":"2026-05-09T21:47:22.635117Z","end":"2026-05-09T21:47:23.576455Z","steps":["trace[1132217830] 'process raft request' (duration: 940.877276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.576438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.602967Z","time spent":"973.44692ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:47:23.576522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.635097Z","time spent":"941.400868ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:23.576551Z","caller":"traceutil/trace.go:171","msg":"trace[2024106276] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"853.520755ms","start":"2026-05-09T21:47:22.723014Z","end":"2026-05-09T21:47:23.576535Z","steps":["trace[2024106276] 'process raft request' (duration: 853.132736ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:23.575287Z","caller":"traceutil/trace.go:171","msg":"trace[883194340] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"991.951548ms","start":"2026-05-09T21:47:22.583292Z","end":"2026-05-09T21:47:23.575243Z","steps":["trace[883194340] 'process raft request' (duration: 991.630191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.576625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.722974Z","time spent":"853.608727ms","remote":"127.0.0.1:47386","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-09T21:47:23.576646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.583288Z","time spent":"993.33467ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:23.575286Z","caller":"traceutil/trace.go:171","msg":"trace[739455582] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"991.928407ms","start":"2026-05-09T21:47:22.583344Z","end":"2026-05-09T21:47:23.575272Z","steps":["trace[739455582] 'process raft request' (duration: 991.62709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.576755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.58334Z","time spent":"993.394421ms","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:23.5765Z","caller":"traceutil/trace.go:171","msg":"trace[484339553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"941.256395ms","start":"2026-05-09T21:47:22.635224Z","end":"2026-05-09T21:47:23.57648Z","steps":["trace[484339553] 'agreement among raft nodes before linearized reading' (duration: 941.113671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:23.576862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:22.635206Z","time spent":"941.630443ms","remote":"127.0.0.1:47306","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-09T21:47:25.132039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596762741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:47:25.633079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596762741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:47:25.977892Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.375110062s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T21:47:25.978655Z","caller":"traceutil/trace.go:171","msg":"trace[1173222622] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.384294569s","start":"2026-05-09T21:47:24.594343Z","end":"2026-05-09T21:47:25.978637Z","steps":["trace[1173222622] 'process raft request' (duration: 1.383927731s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.97878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:24.594294Z","time spent":"1.384425141s","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:25.985231Z","caller":"traceutil/trace.go:171","msg":"trace[269753027] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"1.353960974s","start":"2026-05-09T21:47:24.631238Z","end":"2026-05-09T21:47:25.985199Z","steps":["trace[269753027] 'read index received' (duration: 1.347010138s)","trace[269753027] 'applied index is now lower than readState.Index' (duration: 6.949566ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:47:25.985367Z","caller":"traceutil/trace.go:171","msg":"trace[1564609434] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"251.962674ms","start":"2026-05-09T21:47:25.733394Z","end":"2026-05-09T21:47:25.985357Z","steps":["trace[1564609434] 'process raft request' (duration: 251.688578ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:25.985518Z","caller":"traceutil/trace.go:171","msg":"trace[300233762] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.326173598s","start":"2026-05-09T21:47:24.659308Z","end":"2026-05-09T21:47:25.985482Z","steps":["trace[300233762] 'process raft request' (duration: 1.3253835s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.985605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.300864ms","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-09T21:47:25.985646Z","caller":"traceutil/trace.go:171","msg":"trace[1446049507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"331.383657ms","start":"2026-05-09T21:47:25.654251Z","end":"2026-05-09T21:47:25.985635Z","steps":["trace[1446049507] 'agreement among raft nodes before linearized reading' (duration: 331.306734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.985667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:25.654231Z","time spent":"331.432298ms","remote":"127.0.0.1:47608","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-09T21:47:25.985693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:24.659267Z","time spent":"1.326345612s","remote":"127.0.0.1:47386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:47:25.985777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354549878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:25.985799Z","caller":"traceutil/trace.go:171","msg":"trace[965769475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"1.35461769s","start":"2026-05-09T21:47:24.631177Z","end":"2026-05-09T21:47:25.985795Z","steps":["trace[965769475] 'agreement among raft nodes before linearized reading' (duration: 1.354583939s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.985812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:24.631155Z","time spent":"1.354654621s","remote":"127.0.0.1:47306","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-09T21:47:25.98593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.355918ms","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-09T21:47:25.985952Z","caller":"traceutil/trace.go:171","msg":"trace[1068130716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"285.421209ms","start":"2026-05-09T21:47:25.700524Z","end":"2026-05-09T21:47:25.985945Z","steps":["trace[1068130716] 'agreement among raft nodes before linearized reading' (duration: 285.353878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.986028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.154921ms","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-09T21:47:25.986069Z","caller":"traceutil/trace.go:171","msg":"trace[1460137449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"323.322885ms","start":"2026-05-09T21:47:25.662735Z","end":"2026-05-09T21:47:25.986058Z","steps":["trace[1460137449] 'agreement among raft nodes before linearized reading' (duration: 323.235813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.986098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:25.662699Z","time spent":"323.392446ms","remote":"127.0.0.1:47608","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-09T21:47:25.986175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.245662ms","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-09T21:47:25.986201Z","caller":"traceutil/trace.go:171","msg":"trace[1179266205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"311.294543ms","start":"2026-05-09T21:47:25.674902Z","end":"2026-05-09T21:47:25.986197Z","steps":["trace[1179266205] 'agreement among raft nodes before linearized reading' (duration: 311.238821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:25.986217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:25.674808Z","time spent":"311.405815ms","remote":"127.0.0.1:47608","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-09T21:47:30.827792Z","caller":"traceutil/trace.go:171","msg":"trace[333378712] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"313.915352ms","start":"2026-05-09T21:47:30.513821Z","end":"2026-05-09T21:47:30.827736Z","steps":["trace[333378712] 'process raft request' (duration: 313.796479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:30.827923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.513765Z","time spent":"314.090006ms","remote":"127.0.0.1:47534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:30.828162Z","caller":"traceutil/trace.go:171","msg":"trace[1241113384] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:640; }","duration":"195.989172ms","start":"2026-05-09T21:47:30.63214Z","end":"2026-05-09T21:47:30.828129Z","steps":["trace[1241113384] 'read index received' (duration: 195.971111ms)","trace[1241113384] 'applied index is now lower than readState.Index' (duration: 14.121µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:30.828253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.132005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:30.828286Z","caller":"traceutil/trace.go:171","msg":"trace[1392388871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"196.207097ms","start":"2026-05-09T21:47:30.632068Z","end":"2026-05-09T21:47:30.828275Z","steps":["trace[1392388871] 'agreement among raft nodes before linearized reading' (duration: 196.158716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.330209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596762887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T21:47:31.383126Z","caller":"traceutil/trace.go:171","msg":"trace[978625702] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"553.912546ms","start":"2026-05-09T21:47:30.829173Z","end":"2026-05-09T21:47:31.383086Z","steps":["trace[978625702] 'read index received' (duration: 546.231863ms)","trace[978625702] 'applied index is now lower than readState.Index' (duration: 7.679883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:31.383276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.08629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:31.383299Z","caller":"traceutil/trace.go:171","msg":"trace[1057108930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"554.186763ms","start":"2026-05-09T21:47:30.829106Z","end":"2026-05-09T21:47:31.383292Z","steps":["trace[1057108930] 'agreement among raft nodes before linearized reading' (duration: 554.08269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.383326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.829078Z","time spent":"554.242343ms","remote":"127.0.0.1:47314","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-09T21:47:31.383377Z","caller":"traceutil/trace.go:171","msg":"trace[389249470] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"637.810058ms","start":"2026-05-09T21:47:30.745551Z","end":"2026-05-09T21:47:31.383361Z","steps":["trace[389249470] 'process raft request' (duration: 635.305452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.383475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.745485Z","time spent":"637.933391ms","remote":"127.0.0.1:47434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:31.627666Z","caller":"traceutil/trace.go:171","msg":"trace[2086685070] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"175.343286ms","start":"2026-05-09T21:47:31.452275Z","end":"2026-05-09T21:47:31.627619Z","steps":["trace[2086685070] 'process raft request' (duration: 153.206387ms)","trace[2086685070] 'compare' (duration: 21.621287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:32.524541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596762893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:47:33.025191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596762893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:47:33.525832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596762893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:47:33.653878Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.631907676s","expected-duration":"1s"} {"level":"info","ts":"2026-05-09T21:47:33.655257Z","caller":"traceutil/trace.go:171","msg":"trace[130452701] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.633369319s","start":"2026-05-09T21:47:32.021854Z","end":"2026-05-09T21:47:33.655223Z","steps":["trace[130452701] 'process raft request' (duration: 1.633113753s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:33.655462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:32.021835Z","time spent":"1.633524362s","remote":"127.0.0.1:47608","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-09T21:47:34.252643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.212996ms","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-09T21:47:34.25279Z","caller":"traceutil/trace.go:171","msg":"trace[1717397492] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:617; }","duration":"508.398ms","start":"2026-05-09T21:47:33.744375Z","end":"2026-05-09T21:47:34.252773Z","steps":["trace[1717397492] 'range keys from in-memory index tree' (duration: 508.196585ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:34.258026Z","caller":"traceutil/trace.go:171","msg":"trace[1685546281] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"2.23360009s","start":"2026-05-09T21:47:32.024383Z","end":"2026-05-09T21:47:34.257983Z","steps":["trace[1685546281] 'process raft request' (duration: 2.233312833s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.258207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:32.024367Z","time spent":"2.233750024s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:34.259203Z","caller":"traceutil/trace.go:171","msg":"trace[1352553357] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"2.21854902s","start":"2026-05-09T21:47:32.040618Z","end":"2026-05-09T21:47:34.259167Z","steps":["trace[1352553357] 'process raft request' (duration: 2.217524287s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.259365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:32.040586Z","time spent":"2.218695103s","remote":"127.0.0.1:47434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:34.298515Z","caller":"traceutil/trace.go:171","msg":"trace[1863382323] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:642; }","duration":"2.2743833s","start":"2026-05-09T21:47:32.0241Z","end":"2026-05-09T21:47:34.298483Z","steps":["trace[1863382323] 'read index received' (duration: 1.630821061s)","trace[1863382323] 'applied index is now lower than readState.Index' (duration: 643.560709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:34.299008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.274869061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T21:47:34.299096Z","caller":"traceutil/trace.go:171","msg":"trace[1470563121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"2.275032084s","start":"2026-05-09T21:47:32.024044Z","end":"2026-05-09T21:47:34.299076Z","steps":["trace[1470563121] 'agreement among raft nodes before linearized reading' (duration: 2.274720697s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.299138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:32.024021Z","time spent":"2.275108976s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T21:47:34.299121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.274122044s","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-09T21:47:34.307828Z","caller":"traceutil/trace.go:171","msg":"trace[1559122761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"2.282869211s","start":"2026-05-09T21:47:32.024935Z","end":"2026-05-09T21:47:34.307804Z","steps":["trace[1559122761] 'agreement among raft nodes before linearized reading' (duration: 2.273982861s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.307916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:32.024926Z","time spent":"2.282962973s","remote":"127.0.0.1:47608","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-09T21:47:34.308355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.31992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.308423Z","caller":"traceutil/trace.go:171","msg":"trace[2090249831] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:619; }","duration":"396.388022ms","start":"2026-05-09T21:47:33.912001Z","end":"2026-05-09T21:47:34.308389Z","steps":["trace[2090249831] 'agreement among raft nodes before linearized reading' (duration: 396.091405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.308452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.911979Z","time spent":"396.461874ms","remote":"127.0.0.1:52518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-09T21:47:34.308452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.533388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.308556Z","caller":"traceutil/trace.go:171","msg":"trace[1328852094] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:619; }","duration":"518.688792ms","start":"2026-05-09T21:47:33.789824Z","end":"2026-05-09T21:47:34.308513Z","steps":["trace[1328852094] 'agreement among raft nodes before linearized reading' (duration: 518.248802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.308597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.789775Z","time spent":"518.810895ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T21:47:34.30927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.46702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.309341Z","caller":"traceutil/trace.go:171","msg":"trace[393312358] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:619; }","duration":"519.563441ms","start":"2026-05-09T21:47:33.789748Z","end":"2026-05-09T21:47:34.309311Z","steps":["trace[393312358] 'agreement among raft nodes before linearized reading' (duration: 519.446278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.309368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.789727Z","time spent":"519.633153ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:34.30954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.693174ms","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-09T21:47:34.309583Z","caller":"traceutil/trace.go:171","msg":"trace[175171013] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:619; }","duration":"187.768166ms","start":"2026-05-09T21:47:34.121795Z","end":"2026-05-09T21:47:34.309563Z","steps":["trace[175171013] 'agreement among raft nodes before linearized reading' (duration: 187.671094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.309652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.5573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.309745Z","caller":"traceutil/trace.go:171","msg":"trace[1193159763] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:619; }","duration":"335.678812ms","start":"2026-05-09T21:47:33.974051Z","end":"2026-05-09T21:47:34.309729Z","steps":["trace[1193159763] 'agreement among raft nodes before linearized reading' (duration: 335.531149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.309783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.974042Z","time spent":"335.728514ms","remote":"127.0.0.1:52534","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-09T21:47:34.309971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.412028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:47:34.311459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.351155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:47:34.311454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.623717ms","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-09T21:47:34.311463Z","caller":"traceutil/trace.go:171","msg":"trace[1271169029] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:619; }","duration":"399.920383ms","start":"2026-05-09T21:47:33.91153Z","end":"2026-05-09T21:47:34.31145Z","steps":["trace[1271169029] 'agreement among raft nodes before linearized reading' (duration: 398.374617ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:34.311491Z","caller":"traceutil/trace.go:171","msg":"trace[250262345] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:619; }","duration":"729.376465ms","start":"2026-05-09T21:47:33.582099Z","end":"2026-05-09T21:47:34.311475Z","steps":["trace[250262345] 'agreement among raft nodes before linearized reading' (duration: 729.334404ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:34.311493Z","caller":"traceutil/trace.go:171","msg":"trace[185383822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:619; }","duration":"493.728019ms","start":"2026-05-09T21:47:33.817753Z","end":"2026-05-09T21:47:34.311481Z","steps":["trace[185383822] 'agreement among raft nodes before linearized reading' (duration: 493.650598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.311517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.582073Z","time spent":"729.437616ms","remote":"127.0.0.1:52470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:34.311528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.817723Z","time spent":"493.79728ms","remote":"127.0.0.1:52508","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/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:34.311652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.911448Z","time spent":"400.049785ms","remote":"127.0.0.1:52518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T21:47:34.311784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.278784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.311841Z","caller":"traceutil/trace.go:171","msg":"trace[1128270760] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:619; }","duration":"792.355786ms","start":"2026-05-09T21:47:33.51945Z","end":"2026-05-09T21:47:34.311806Z","steps":["trace[1128270760] 'agreement among raft nodes before linearized reading' (duration: 791.087556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.311869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.519425Z","time spent":"792.435187ms","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:34.312225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.533747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.312262Z","caller":"traceutil/trace.go:171","msg":"trace[636276803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:619; }","duration":"494.588628ms","start":"2026-05-09T21:47:33.817655Z","end":"2026-05-09T21:47:34.312243Z","steps":["trace[636276803] 'agreement among raft nodes before linearized reading' (duration: 494.327612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.312282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.817639Z","time spent":"494.636569ms","remote":"127.0.0.1:52508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T21:47:34.312382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.547118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.312419Z","caller":"traceutil/trace.go:171","msg":"trace[717956825] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:619; }","duration":"764.61983ms","start":"2026-05-09T21:47:33.547789Z","end":"2026-05-09T21:47:34.312409Z","steps":["trace[717956825] 'agreement among raft nodes before linearized reading' (duration: 764.563079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.312441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.547769Z","time spent":"764.66438ms","remote":"127.0.0.1:52468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-09T21:47:34.312475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.956975ms","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-09T21:47:34.312513Z","caller":"traceutil/trace.go:171","msg":"trace[851988890] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:619; }","duration":"401.029627ms","start":"2026-05-09T21:47:33.911473Z","end":"2026-05-09T21:47:34.312503Z","steps":["trace[851988890] 'agreement among raft nodes before linearized reading' (duration: 400.973036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.312539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.911451Z","time spent":"401.076728ms","remote":"127.0.0.1:52518","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":"warn","ts":"2026-05-09T21:47:34.31319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684211215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.313229Z","caller":"traceutil/trace.go:171","msg":"trace[2132099913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"1.684246556s","start":"2026-05-09T21:47:32.628971Z","end":"2026-05-09T21:47:34.313217Z","steps":["trace[2132099913] 'agreement among raft nodes before linearized reading' (duration: 1.684193015s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.313281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.262039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.313317Z","caller":"traceutil/trace.go:171","msg":"trace[538303299] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:619; }","duration":"767.332711ms","start":"2026-05-09T21:47:33.545969Z","end":"2026-05-09T21:47:34.313302Z","steps":["trace[538303299] 'agreement among raft nodes before linearized reading' (duration: 767.269979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.313327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.890314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:47:34.313341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.545939Z","time spent":"767.395463ms","remote":"127.0.0.1:52468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"info","ts":"2026-05-09T21:47:34.313363Z","caller":"traceutil/trace.go:171","msg":"trace[806453971] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:619; }","duration":"494.944525ms","start":"2026-05-09T21:47:33.818401Z","end":"2026-05-09T21:47:34.313346Z","steps":["trace[806453971] 'agreement among raft nodes before linearized reading' (duration: 494.577427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.313392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.818388Z","time spent":"494.996327ms","remote":"127.0.0.1:52508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-09T21:47:34.310035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.080682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.314647Z","caller":"traceutil/trace.go:171","msg":"trace[1420986329] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:619; }","duration":"340.721428ms","start":"2026-05-09T21:47:33.973912Z","end":"2026-05-09T21:47:34.314633Z","steps":["trace[1420986329] 'agreement among raft nodes before linearized reading' (duration: 335.879198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.314678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.973893Z","time spent":"340.776128ms","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-09T21:47:34.310368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.650755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.314816Z","caller":"traceutil/trace.go:171","msg":"trace[2066082757] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:619; }","duration":"193.100956ms","start":"2026-05-09T21:47:34.121707Z","end":"2026-05-09T21:47:34.314808Z","steps":["trace[2066082757] 'agreement among raft nodes before linearized reading' (duration: 188.623925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.31041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.131493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.31501Z","caller":"traceutil/trace.go:171","msg":"trace[208546741] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:619; }","duration":"340.742558ms","start":"2026-05-09T21:47:33.974255Z","end":"2026-05-09T21:47:34.314997Z","steps":["trace[208546741] 'agreement among raft nodes before linearized reading' (duration: 336.129683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.315041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.974248Z","time spent":"340.782848ms","remote":"127.0.0.1:52534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T21:47:34.310408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.477041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.315248Z","caller":"traceutil/trace.go:171","msg":"trace[1185411032] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:619; }","duration":"701.340782ms","start":"2026-05-09T21:47:33.613896Z","end":"2026-05-09T21:47:34.315237Z","steps":["trace[1185411032] 'agreement among raft nodes before linearized reading' (duration: 696.476922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.315283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.613889Z","time spent":"701.380843ms","remote":"127.0.0.1:52470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-09T21:47:34.310454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.557948ms","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-09T21:47:34.31535Z","caller":"traceutil/trace.go:171","msg":"trace[208500358] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:619; }","duration":"769.563191ms","start":"2026-05-09T21:47:33.545777Z","end":"2026-05-09T21:47:34.31534Z","steps":["trace[208500358] 'agreement among raft nodes before linearized reading' (duration: 764.65099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.310472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.586945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.315395Z","caller":"traceutil/trace.go:171","msg":"trace[1266093939] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:619; }","duration":"795.565628ms","start":"2026-05-09T21:47:33.519818Z","end":"2026-05-09T21:47:34.315383Z","steps":["trace[1266093939] 'agreement among raft nodes before linearized reading' (duration: 790.635616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.315437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.519784Z","time spent":"795.643179ms","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-09T21:47:34.315852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.545751Z","time spent":"769.618033ms","remote":"127.0.0.1:52468","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-09T21:47:34.310525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.943313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.316154Z","caller":"traceutil/trace.go:171","msg":"trace[367073185] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:619; }","duration":"796.619791ms","start":"2026-05-09T21:47:33.51951Z","end":"2026-05-09T21:47:34.31613Z","steps":["trace[367073185] 'agreement among raft nodes before linearized reading' (duration: 790.977653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.316214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.519478Z","time spent":"796.725843ms","remote":"127.0.0.1:52454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T21:47:34.311023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.032267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.316273Z","caller":"traceutil/trace.go:171","msg":"trace[25797322] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:619; }","duration":"703.308597ms","start":"2026-05-09T21:47:33.612946Z","end":"2026-05-09T21:47:34.316255Z","steps":["trace[25797322] 'agreement among raft nodes before linearized reading' (duration: 697.521826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.311246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.276512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:47:34.316323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.612922Z","time spent":"703.376697ms","remote":"127.0.0.1:52486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-09T21:47:34.316359Z","caller":"traceutil/trace.go:171","msg":"trace[43981564] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:619; }","duration":"703.401038ms","start":"2026-05-09T21:47:33.612941Z","end":"2026-05-09T21:47:34.316342Z","steps":["trace[43981564] 'agreement among raft nodes before linearized reading' (duration: 698.095348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.316409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.612926Z","time spent":"703.46983ms","remote":"127.0.0.1:52486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:47:34.311421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.15747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.316552Z","caller":"traceutil/trace.go:171","msg":"trace[1835127411] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:619; }","duration":"734.310006ms","start":"2026-05-09T21:47:33.582206Z","end":"2026-05-09T21:47:34.316516Z","steps":["trace[1835127411] 'agreement among raft nodes before linearized reading' (duration: 729.053287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:34.316595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.582178Z","time spent":"734.405798ms","remote":"127.0.0.1:52470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-09T21:47:34.313253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:32.628915Z","time spent":"1.684327648s","remote":"127.0.0.1:47314","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-09T21:47:34.331666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.590469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:34.331732Z","caller":"traceutil/trace.go:171","msg":"trace[675256055] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:620; }","duration":"541.677001ms","start":"2026-05-09T21:47:33.790038Z","end":"2026-05-09T21:47:34.331715Z","steps":["trace[675256055] 'agreement among raft nodes before linearized reading' (duration: 523.332206ms)","trace[675256055] 'get authentication metadata' (duration: 18.250843ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:34.33176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:33.790032Z","time spent":"541.722582ms","remote":"127.0.0.1:52498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"info","ts":"2026-05-09T21:47:36.224609Z","caller":"traceutil/trace.go:171","msg":"trace[442514424] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"552.752019ms","start":"2026-05-09T21:47:35.671801Z","end":"2026-05-09T21:47:36.224553Z","steps":["trace[442514424] 'process raft request' (duration: 550.106431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:36.224968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:35.671789Z","time spent":"552.934894ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:47:37.595218Z","caller":"traceutil/trace.go:171","msg":"trace[1030261214] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"121.171964ms","start":"2026-05-09T21:47:37.473983Z","end":"2026-05-09T21:47:37.595155Z","steps":["trace[1030261214] 'process raft request' (duration: 120.788886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:37.655751Z","caller":"traceutil/trace.go:171","msg":"trace[311913851] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"109.421068ms","start":"2026-05-09T21:47:37.546316Z","end":"2026-05-09T21:47:37.655737Z","steps":["trace[311913851] 'process raft request' (duration: 108.787404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:51.034633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.319751ms","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-09T21:47:51.03473Z","caller":"traceutil/trace.go:171","msg":"trace[1366068207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"736.459724ms","start":"2026-05-09T21:47:50.298251Z","end":"2026-05-09T21:47:51.034711Z","steps":["trace[1366068207] 'range keys from in-memory index tree' (duration: 736.100477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:51.03477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:50.298232Z","time spent":"736.529536ms","remote":"127.0.0.1:47608","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":"warn","ts":"2026-05-09T21:47:51.035476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426078491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v8cz5\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-09T21:47:51.035572Z","caller":"traceutil/trace.go:171","msg":"trace[800189831] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v8cz5; range_end:; response_count:1; response_revision:782; }","duration":"1.426248446s","start":"2026-05-09T21:47:49.609303Z","end":"2026-05-09T21:47:51.035551Z","steps":["trace[800189831] 'range keys from in-memory index tree' (duration: 1.425895638s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:51.035624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:49.609282Z","time spent":"1.426331728s","remote":"127.0.0.1:47534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v8cz5\" "} {"level":"warn","ts":"2026-05-09T21:47:51.03581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.805137ms","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-09T21:47:51.035837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.600632ms","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-09T21:47:51.03587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.88744ms","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-09T21:47:51.035902Z","caller":"traceutil/trace.go:171","msg":"trace[1988279943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"699.94628ms","start":"2026-05-09T21:47:50.335933Z","end":"2026-05-09T21:47:51.03588Z","steps":["trace[1988279943] 'range keys from in-memory index tree' (duration: 699.572852ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:51.035912Z","caller":"traceutil/trace.go:171","msg":"trace[963131079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:782; }","duration":"628.683143ms","start":"2026-05-09T21:47:50.407211Z","end":"2026-05-09T21:47:51.035894Z","steps":["trace[963131079] 'range keys from in-memory index tree' (duration: 628.416708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:51.035939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:50.335908Z","time spent":"700.025402ms","remote":"127.0.0.1:47608","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-09T21:47:51.035949Z","caller":"traceutil/trace.go:171","msg":"trace[1381600188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"617.999652ms","start":"2026-05-09T21:47:50.417928Z","end":"2026-05-09T21:47:51.035928Z","steps":["trace[1381600188] 'range keys from in-memory index tree' (duration: 617.721496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:51.035972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:50.40716Z","time spent":"628.802136ms","remote":"127.0.0.1:47608","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-09T21:47:51.036005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:50.417907Z","time spent":"618.084364ms","remote":"127.0.0.1:47608","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-09T21:47:51.036057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.464395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-09T21:47:51.036054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.342375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:47:51.036078Z","caller":"traceutil/trace.go:171","msg":"trace[850525719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"693.525596ms","start":"2026-05-09T21:47:50.342546Z","end":"2026-05-09T21:47:51.036071Z","steps":["trace[850525719] 'range keys from in-memory index tree' (duration: 693.358052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:51.036097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:50.342525Z","time spent":"693.568617ms","remote":"127.0.0.1:47608","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-09T21:47:51.036099Z","caller":"traceutil/trace.go:171","msg":"trace[1034001530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"405.420067ms","start":"2026-05-09T21:47:50.630668Z","end":"2026-05-09T21:47:51.036088Z","steps":["trace[1034001530] 'range keys from in-memory index tree' (duration: 405.282564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:51.036135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:50.630648Z","time spent":"405.480318ms","remote":"127.0.0.1:47306","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-09T21:48:26.950982Z","caller":"traceutil/trace.go:171","msg":"trace[2116129494] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"137.356504ms","start":"2026-05-09T21:48:26.813584Z","end":"2026-05-09T21:48:26.95094Z","steps":["trace[2116129494] 'process raft request' (duration: 85.422738ms)","trace[2116129494] 'compare' (duration: 51.783773ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:27.735007Z","caller":"traceutil/trace.go:171","msg":"trace[1121929714] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"462.451455ms","start":"2026-05-09T21:48:27.272528Z","end":"2026-05-09T21:48:27.73498Z","steps":["trace[1121929714] 'process raft request' (duration: 462.312862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:27.735155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.272512Z","time spent":"462.570617ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:48:28.400765Z","caller":"traceutil/trace.go:171","msg":"trace[1327461067] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1040; }","duration":"1.124012982s","start":"2026-05-09T21:48:27.276733Z","end":"2026-05-09T21:48:28.400746Z","steps":["trace[1327461067] 'read index received' (duration: 458.074397ms)","trace[1327461067] 'applied index is now lower than readState.Index' (duration: 665.937765ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:28.400889Z","caller":"traceutil/trace.go:171","msg":"trace[1825960781] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.109046056s","start":"2026-05-09T21:48:27.291781Z","end":"2026-05-09T21:48:28.400827Z","steps":["trace[1825960781] 'process raft request' (duration: 1.108933603s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:28.400959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124210586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T21:48:28.400999Z","caller":"traceutil/trace.go:171","msg":"trace[1009527249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"1.124284608s","start":"2026-05-09T21:48:27.276701Z","end":"2026-05-09T21:48:28.400985Z","steps":["trace[1009527249] 'agreement among raft nodes before linearized reading' (duration: 1.124157755s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:28.401025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.27669Z","time spent":"1.124328259s","remote":"127.0.0.1:47608","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-09T21:48:28.401113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.291763Z","time spent":"1.109236379s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:48:28.401475Z","caller":"traceutil/trace.go:171","msg":"trace[2143220196] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.127376917s","start":"2026-05-09T21:48:27.27408Z","end":"2026-05-09T21:48:28.401457Z","steps":["trace[2143220196] 'process raft request' (duration: 1.126501067s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:28.401526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.274056Z","time spent":"1.127444888s","remote":"127.0.0.1:47608","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-09T21:48:28.901759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596763728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:48:29.243307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.996107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:48:29.24344Z","caller":"traceutil/trace.go:171","msg":"trace[1715779840] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.342507128s","start":"2026-05-09T21:48:27.900911Z","end":"2026-05-09T21:48:29.243418Z","steps":["trace[1715779840] 'process raft request' (duration: 1.073819994s)","trace[1715779840] 'compare' (duration: 267.712622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:29.243509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.900885Z","time spent":"1.3425916s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:48:29.338452Z","caller":"traceutil/trace.go:171","msg":"trace[209115888] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1043; }","duration":"937.593913ms","start":"2026-05-09T21:48:28.400823Z","end":"2026-05-09T21:48:29.338417Z","steps":["trace[209115888] 'read index received' (duration: 574.0397ms)","trace[209115888] 'applied index is now lower than readState.Index' (duration: 363.552863ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:29.338558Z","caller":"traceutil/trace.go:171","msg":"trace[324199504] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"933.647336ms","start":"2026-05-09T21:48:28.404879Z","end":"2026-05-09T21:48:29.338527Z","steps":["trace[324199504] 'process raft request' (duration: 933.278588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:29.338705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912409636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:48:29.33872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:28.404853Z","time spent":"933.789208ms","remote":"127.0.0.1:47608","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-09T21:48:29.338995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.058132308s","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-09T21:48:29.339037Z","caller":"traceutil/trace.go:171","msg":"trace[391766511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"2.05819652s","start":"2026-05-09T21:48:27.28083Z","end":"2026-05-09T21:48:29.339027Z","steps":["trace[391766511] 'agreement among raft nodes before linearized reading' (duration: 2.057976265s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:29.339045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.838184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:29.339153Z","caller":"traceutil/trace.go:171","msg":"trace[1825241537] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1003; }","duration":"869.999117ms","start":"2026-05-09T21:48:28.469129Z","end":"2026-05-09T21:48:29.339128Z","steps":["trace[1825241537] 'agreement among raft nodes before linearized reading' (duration: 869.846424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:29.339199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.891117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:48:29.339272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:28.469104Z","time spent":"870.15341ms","remote":"127.0.0.1:47662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-05-09T21:48:29.339284Z","caller":"traceutil/trace.go:171","msg":"trace[2093333162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"708.04164ms","start":"2026-05-09T21:48:28.63123Z","end":"2026-05-09T21:48:29.339271Z","steps":["trace[2093333162] 'agreement among raft nodes before linearized reading' (duration: 707.891387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:29.339086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.280815Z","time spent":"2.058261261s","remote":"127.0.0.1:47608","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-09T21:48:29.339332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:28.631207Z","time spent":"708.117491ms","remote":"127.0.0.1:47306","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-09T21:48:29.33961Z","caller":"traceutil/trace.go:171","msg":"trace[1353145219] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1003; }","duration":"1.912542629s","start":"2026-05-09T21:48:27.426205Z","end":"2026-05-09T21:48:29.338747Z","steps":["trace[1353145219] 'agreement among raft nodes before linearized reading' (duration: 1.912391324s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:29.33975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.059282663s","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-09T21:48:29.339796Z","caller":"traceutil/trace.go:171","msg":"trace[1020159758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"2.059360756s","start":"2026-05-09T21:48:27.280423Z","end":"2026-05-09T21:48:29.339784Z","steps":["trace[1020159758] 'agreement among raft nodes before linearized reading' (duration: 2.058730862s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:29.339798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.426181Z","time spent":"1.913576972s","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:48:29.339826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:27.280407Z","time spent":"2.059412416s","remote":"127.0.0.1:47608","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":"info","ts":"2026-05-09T21:48:49.776104Z","caller":"traceutil/trace.go:171","msg":"trace[1225345762] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"266.973575ms","start":"2026-05-09T21:48:49.509114Z","end":"2026-05-09T21:48:49.776088Z","steps":["trace[1225345762] 'read index received' (duration: 266.838712ms)","trace[1225345762] 'applied index is now lower than readState.Index' (duration: 134.153µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:49.776157Z","caller":"traceutil/trace.go:171","msg":"trace[1430675880] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"339.877182ms","start":"2026-05-09T21:48:49.43626Z","end":"2026-05-09T21:48:49.776137Z","steps":["trace[1430675880] 'process raft request' (duration: 339.727989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:49.776265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:49.436243Z","time spent":"339.963264ms","remote":"127.0.0.1:47608","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-09T21:48:49.776293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.498263ms","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-09T21:48:49.776321Z","caller":"traceutil/trace.go:171","msg":"trace[737915744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1069; }","duration":"229.563595ms","start":"2026-05-09T21:48:49.546749Z","end":"2026-05-09T21:48:49.776313Z","steps":["trace[737915744] 'agreement among raft nodes before linearized reading' (duration: 229.451332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:49.776332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.19569ms","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-09T21:48:49.776386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.08412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:49.77641Z","caller":"traceutil/trace.go:171","msg":"trace[1677110294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"146.143312ms","start":"2026-05-09T21:48:49.630259Z","end":"2026-05-09T21:48:49.776403Z","steps":["trace[1677110294] 'agreement among raft nodes before linearized reading' (duration: 146.105021ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:49.776417Z","caller":"traceutil/trace.go:171","msg":"trace[2122355526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"267.323943ms","start":"2026-05-09T21:48:49.509073Z","end":"2026-05-09T21:48:49.776397Z","steps":["trace[2122355526] 'agreement among raft nodes before linearized reading' (duration: 267.100138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:50.323452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596763912,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:48:51.094502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.897689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:48:51.094897Z","caller":"traceutil/trace.go:171","msg":"trace[1144397879] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"1.315386313s","start":"2026-05-09T21:48:49.779212Z","end":"2026-05-09T21:48:51.094598Z","steps":["trace[1144397879] 'process raft request' (duration: 692.29393ms)","trace[1144397879] 'compare' (duration: 621.554248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:51.095046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:49.779199Z","time spent":"1.315804802s","remote":"127.0.0.1:47608","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-09T21:48:51.122024Z","caller":"traceutil/trace.go:171","msg":"trace[218764504] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"1.342222155s","start":"2026-05-09T21:48:49.779769Z","end":"2026-05-09T21:48:51.121991Z","steps":["trace[218764504] 'process raft request' (duration: 1.34195143s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:51.12226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299387634s","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-09T21:48:51.12233Z","caller":"traceutil/trace.go:171","msg":"trace[742790365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"1.299510887s","start":"2026-05-09T21:48:49.822801Z","end":"2026-05-09T21:48:51.122312Z","steps":["trace[742790365] 'agreement among raft nodes before linearized reading' (duration: 1.299308981s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:51.122385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:49.822788Z","time spent":"1.299583438s","remote":"127.0.0.1:47608","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":"warn","ts":"2026-05-09T21:48:51.122507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:49.779753Z","time spent":"1.342510753s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:48:51.12206Z","caller":"traceutil/trace.go:171","msg":"trace[1604786459] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"1.299177478s","start":"2026-05-09T21:48:49.82285Z","end":"2026-05-09T21:48:51.122027Z","steps":["trace[1604786459] 'read index received' (duration: 648.681691ms)","trace[1604786459] 'applied index is now lower than readState.Index' (duration: 650.492427ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:51.138553Z","caller":"traceutil/trace.go:171","msg":"trace[180511079] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"221.977794ms","start":"2026-05-09T21:48:50.916555Z","end":"2026-05-09T21:48:51.138533Z","steps":["trace[180511079] 'process raft request' (duration: 221.844611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:51.138677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.52085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T21:48:51.138748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.256821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:51.138937Z","caller":"traceutil/trace.go:171","msg":"trace[64552730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"507.501967ms","start":"2026-05-09T21:48:50.631413Z","end":"2026-05-09T21:48:51.138915Z","steps":["trace[64552730] 'agreement among raft nodes before linearized reading' (duration: 507.22133ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:51.138793Z","caller":"traceutil/trace.go:171","msg":"trace[888810367] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1072; }","duration":"744.645133ms","start":"2026-05-09T21:48:50.394137Z","end":"2026-05-09T21:48:51.138782Z","steps":["trace[888810367] 'agreement among raft nodes before linearized reading' (duration: 744.441438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:51.138995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:50.631389Z","time spent":"507.595939ms","remote":"127.0.0.1:47306","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-09T21:48:51.139023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:50.394079Z","time spent":"744.9203ms","remote":"127.0.0.1:47702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-09T21:48:51.138681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.362659ms","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-09T21:48:51.139114Z","caller":"traceutil/trace.go:171","msg":"trace[2145881866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"656.80178ms","start":"2026-05-09T21:48:50.482296Z","end":"2026-05-09T21:48:51.139098Z","steps":["trace[2145881866] 'agreement among raft nodes before linearized reading' (duration: 656.248246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:51.138676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217039973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T21:48:51.139166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:50.482259Z","time spent":"656.894232ms","remote":"127.0.0.1:47608","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":"info","ts":"2026-05-09T21:48:51.139206Z","caller":"traceutil/trace.go:171","msg":"trace[1612688343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1072; }","duration":"1.217609077s","start":"2026-05-09T21:48:49.921578Z","end":"2026-05-09T21:48:51.139187Z","steps":["trace[1612688343] 'agreement among raft nodes before linearized reading' (duration: 1.217030633s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:51.139339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:49.921565Z","time spent":"1.217712929s","remote":"127.0.0.1:52678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-09T21:48:51.299452Z","caller":"traceutil/trace.go:171","msg":"trace[1721189182] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"156.347942ms","start":"2026-05-09T21:48:51.143082Z","end":"2026-05-09T21:48:51.29943Z","steps":["trace[1721189182] 'process raft request' (duration: 156.312222ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:51.299479Z","caller":"traceutil/trace.go:171","msg":"trace[459310707] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"160.947086ms","start":"2026-05-09T21:48:51.138508Z","end":"2026-05-09T21:48:51.299455Z","steps":["trace[459310707] 'read index received' (duration: 91.504366ms)","trace[459310707] 'applied index is now lower than readState.Index' (duration: 69.43997ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:51.299472Z","caller":"traceutil/trace.go:171","msg":"trace[1058260554] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"172.799213ms","start":"2026-05-09T21:48:51.126639Z","end":"2026-05-09T21:48:51.299438Z","steps":["trace[1058260554] 'process raft request' (duration: 103.26478ms)","trace[1058260554] 'compare' (duration: 69.389989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:51.299691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.936275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:51.299757Z","caller":"traceutil/trace.go:171","msg":"trace[1986610940] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1074; }","duration":"173.087948ms","start":"2026-05-09T21:48:51.126657Z","end":"2026-05-09T21:48:51.299745Z","steps":["trace[1986610940] 'agreement among raft nodes before linearized reading' (duration: 172.938455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:51.299702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.527334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:51.299838Z","caller":"traceutil/trace.go:171","msg":"trace[1911997155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"159.714908ms","start":"2026-05-09T21:48:51.14011Z","end":"2026-05-09T21:48:51.299825Z","steps":["trace[1911997155] 'agreement among raft nodes before linearized reading' (duration: 159.494143ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:51.473227Z","caller":"traceutil/trace.go:171","msg":"trace[381806851] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"122.865019ms","start":"2026-05-09T21:48:51.350338Z","end":"2026-05-09T21:48:51.473203Z","steps":["trace[381806851] 'process raft request' (duration: 122.647674ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:52.146084Z","caller":"traceutil/trace.go:171","msg":"trace[921885670] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"366.051542ms","start":"2026-05-09T21:48:51.779997Z","end":"2026-05-09T21:48:52.146049Z","steps":["trace[921885670] 'read index received' (duration: 301.36944ms)","trace[921885670] 'applied index is now lower than readState.Index' (duration: 64.679552ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:52.14639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.374449ms","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-09T21:48:52.146389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.433505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:52.146444Z","caller":"traceutil/trace.go:171","msg":"trace[1345786033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1075; }","duration":"366.503272ms","start":"2026-05-09T21:48:51.779927Z","end":"2026-05-09T21:48:52.14643Z","steps":["trace[1345786033] 'agreement among raft nodes before linearized reading' (duration: 366.290228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:52.1465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:51.779873Z","time spent":"366.615215ms","remote":"127.0.0.1:47608","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-09T21:48:52.146492Z","caller":"traceutil/trace.go:171","msg":"trace[1281541900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1075; }","duration":"201.633409ms","start":"2026-05-09T21:48:51.944839Z","end":"2026-05-09T21:48:52.146473Z","steps":["trace[1281541900] 'agreement among raft nodes before linearized reading' (duration: 201.437515ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:48:52.479507Z","caller":"traceutil/trace.go:171","msg":"trace[703940540] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"328.720002ms","start":"2026-05-09T21:48:52.150739Z","end":"2026-05-09T21:48:52.479459Z","steps":["trace[703940540] 'process raft request' (duration: 328.565348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:52.479746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:52.150717Z","time spent":"328.943677ms","remote":"127.0.0.1:47608","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-09T21:48:52.780737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.409617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:52.780821Z","caller":"traceutil/trace.go:171","msg":"trace[139622895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1076; }","duration":"149.55332ms","start":"2026-05-09T21:48:52.631249Z","end":"2026-05-09T21:48:52.780802Z","steps":["trace[139622895] 'range keys from in-memory index tree' (duration: 149.319726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:53.283419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.142079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:48:53.283705Z","caller":"traceutil/trace.go:171","msg":"trace[1516277439] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"151.666885ms","start":"2026-05-09T21:48:53.132015Z","end":"2026-05-09T21:48:53.283682Z","steps":["trace[1516277439] 'process raft request' (duration: 50.859162ms)","trace[1516277439] 'compare' (duration: 99.983585ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:54.937776Z","caller":"traceutil/trace.go:171","msg":"trace[1913116937] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"306.498184ms","start":"2026-05-09T21:48:54.631251Z","end":"2026-05-09T21:48:54.937749Z","steps":["trace[1913116937] 'read index received' (duration: 306.258937ms)","trace[1913116937] 'applied index is now lower than readState.Index' (duration: 237.517µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:54.93782Z","caller":"traceutil/trace.go:171","msg":"trace[1279234821] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"450.530847ms","start":"2026-05-09T21:48:54.487258Z","end":"2026-05-09T21:48:54.937788Z","steps":["trace[1279234821] 'process raft request' (duration: 450.236449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:54.937947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.642756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:48:54.938004Z","caller":"traceutil/trace.go:171","msg":"trace[1300902660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"306.78363ms","start":"2026-05-09T21:48:54.631193Z","end":"2026-05-09T21:48:54.937977Z","steps":["trace[1300902660] 'agreement among raft nodes before linearized reading' (duration: 306.657757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:54.938051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:54.631174Z","time spent":"306.863222ms","remote":"127.0.0.1:47314","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-09T21:48:54.938122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:54.487245Z","time spent":"450.69724ms","remote":"127.0.0.1:47608","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-09T21:49:07.837795Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596764160,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T21:49:08.08115Z","caller":"traceutil/trace.go:171","msg":"trace[1581504554] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"854.831616ms","start":"2026-05-09T21:49:07.226292Z","end":"2026-05-09T21:49:08.081123Z","steps":["trace[1581504554] 'process raft request' (duration: 854.686703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:08.081309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:07.226279Z","time spent":"854.949499ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:49:08.082137Z","caller":"traceutil/trace.go:171","msg":"trace[1888811904] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1225; }","duration":"744.576011ms","start":"2026-05-09T21:49:07.337525Z","end":"2026-05-09T21:49:08.082101Z","steps":["trace[1888811904] 'read index received' (duration: 743.378833ms)","trace[1888811904] 'applied index is now lower than readState.Index' (duration: 1.196068ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:49:08.082234Z","caller":"traceutil/trace.go:171","msg":"trace[770071961] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"557.190442ms","start":"2026-05-09T21:49:07.525025Z","end":"2026-05-09T21:49:08.082215Z","steps":["trace[770071961] 'process raft request' (duration: 556.904956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:08.082314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:07.525Z","time spent":"557.274285ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:49:08.082472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.912578ms","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-09T21:49:08.082513Z","caller":"traceutil/trace.go:171","msg":"trace[1552871089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1175; }","duration":"745.030601ms","start":"2026-05-09T21:49:07.33747Z","end":"2026-05-09T21:49:08.082501Z","steps":["trace[1552871089] 'agreement among raft nodes before linearized reading' (duration: 744.799986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:08.082536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.02063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-09T21:49:08.082555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:07.33745Z","time spent":"745.095543ms","remote":"127.0.0.1:47608","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-09T21:49:08.082575Z","caller":"traceutil/trace.go:171","msg":"trace[1895522918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1175; }","duration":"643.101672ms","start":"2026-05-09T21:49:07.43946Z","end":"2026-05-09T21:49:08.082562Z","steps":["trace[1895522918] 'agreement among raft nodes before linearized reading' (duration: 642.956139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:08.082609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:07.439436Z","time spent":"643.164364ms","remote":"127.0.0.1:47608","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-09T21:49:08.082624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.281163ms","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-09T21:49:08.082706Z","caller":"traceutil/trace.go:171","msg":"trace[1042944330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1175; }","duration":"662.435397ms","start":"2026-05-09T21:49:07.420251Z","end":"2026-05-09T21:49:08.082686Z","steps":["trace[1042944330] 'agreement among raft nodes before linearized reading' (duration: 662.171881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:08.082753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:07.420188Z","time spent":"662.556669ms","remote":"127.0.0.1:47608","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":"warn","ts":"2026-05-09T21:49:08.717091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.481625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:49:08.717213Z","caller":"traceutil/trace.go:171","msg":"trace[270438422] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"630.404976ms","start":"2026-05-09T21:49:08.086787Z","end":"2026-05-09T21:49:08.717192Z","steps":["trace[270438422] 'process raft request' (duration: 212.695586ms)","trace[270438422] 'compare' (duration: 417.149378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:08.717294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:08.086765Z","time spent":"630.498729ms","remote":"127.0.0.1:47608","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-09T21:49:08.721125Z","caller":"traceutil/trace.go:171","msg":"trace[1985098541] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"633.566527ms","start":"2026-05-09T21:49:08.087532Z","end":"2026-05-09T21:49:08.721099Z","steps":["trace[1985098541] 'process raft request' (duration: 633.415484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:08.721279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:08.087509Z","time spent":"633.681919ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:49:08.7217Z","caller":"traceutil/trace.go:171","msg":"trace[603186551] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"634.026317ms","start":"2026-05-09T21:49:08.087642Z","end":"2026-05-09T21:49:08.721668Z","steps":["trace[603186551] 'process raft request' (duration: 633.422754ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:08.721895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:08.08762Z","time spent":"634.163421ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:49:24.330366Z","caller":"traceutil/trace.go:171","msg":"trace[616455542] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"173.3542ms","start":"2026-05-09T21:49:24.156989Z","end":"2026-05-09T21:49:24.330343Z","steps":["trace[616455542] 'read index received' (duration: 173.230518ms)","trace[616455542] 'applied index is now lower than readState.Index' (duration: 122.682µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:24.330566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.563425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T21:49:24.330589Z","caller":"traceutil/trace.go:171","msg":"trace[824771407] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"182.699637ms","start":"2026-05-09T21:49:24.147861Z","end":"2026-05-09T21:49:24.330561Z","steps":["trace[824771407] 'process raft request' (duration: 182.335059ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:24.33061Z","caller":"traceutil/trace.go:171","msg":"trace[627229453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"173.664217ms","start":"2026-05-09T21:49:24.156936Z","end":"2026-05-09T21:49:24.3306Z","steps":["trace[627229453] 'agreement among raft nodes before linearized reading' (duration: 173.506773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:25.063424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.853069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:49:25.06353Z","caller":"traceutil/trace.go:171","msg":"trace[31827378] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"220.549375ms","start":"2026-05-09T21:49:24.842965Z","end":"2026-05-09T21:49:25.063514Z","steps":["trace[31827378] 'read index received' (duration: 37.901µs)","trace[31827378] 'applied index is now lower than readState.Index' (duration: 220.510324ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:25.063725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.787823ms","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-09T21:49:25.063793Z","caller":"traceutil/trace.go:171","msg":"trace[1789747335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"207.888576ms","start":"2026-05-09T21:49:24.85589Z","end":"2026-05-09T21:49:25.063778Z","steps":["trace[1789747335] 'agreement among raft nodes before linearized reading' (duration: 207.733732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:25.063955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.989175ms","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-09T21:49:25.063982Z","caller":"traceutil/trace.go:171","msg":"trace[1065042449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"221.050417ms","start":"2026-05-09T21:49:24.842925Z","end":"2026-05-09T21:49:25.063975Z","steps":["trace[1065042449] 'agreement among raft nodes before linearized reading' (duration: 220.979786ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:25.064157Z","caller":"traceutil/trace.go:171","msg":"trace[2090976269] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"728.950658ms","start":"2026-05-09T21:49:24.334648Z","end":"2026-05-09T21:49:25.063599Z","steps":["trace[2090976269] 'process raft request' (duration: 113.844692ms)","trace[2090976269] 'compare' (duration: 614.474821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:25.06423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.699551ms","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-09T21:49:25.064257Z","caller":"traceutil/trace.go:171","msg":"trace[1738676107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"207.764402ms","start":"2026-05-09T21:49:24.856486Z","end":"2026-05-09T21:49:25.06425Z","steps":["trace[1738676107] 'agreement among raft nodes before linearized reading' (duration: 207.705891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:25.064253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:24.334624Z","time spent":"729.587251ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:49:25.064173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.917035ms","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-09T21:49:25.064399Z","caller":"traceutil/trace.go:171","msg":"trace[266014186] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1243; }","duration":"119.17978ms","start":"2026-05-09T21:49:24.945208Z","end":"2026-05-09T21:49:25.064388Z","steps":["trace[266014186] 'agreement among raft nodes before linearized reading' (duration: 118.934225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:25.301864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.90136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:49:25.302084Z","caller":"traceutil/trace.go:171","msg":"trace[2123915648] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1298; }","duration":"215.728569ms","start":"2026-05-09T21:49:25.08634Z","end":"2026-05-09T21:49:25.302068Z","steps":["trace[2123915648] 'read index received' (duration: 84.506742ms)","trace[2123915648] 'applied index is now lower than readState.Index' (duration: 131.220627ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:25.302218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.874912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T21:49:25.30225Z","caller":"traceutil/trace.go:171","msg":"trace[1253471065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1246; }","duration":"215.933402ms","start":"2026-05-09T21:49:25.086304Z","end":"2026-05-09T21:49:25.302237Z","steps":["trace[1253471065] 'agreement among raft nodes before linearized reading' (duration: 215.81964ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:25.30226Z","caller":"traceutil/trace.go:171","msg":"trace[1637219124] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"234.394772ms","start":"2026-05-09T21:49:25.067833Z","end":"2026-05-09T21:49:25.302227Z","steps":["trace[1637219124] 'process raft request' (duration: 103.036543ms)","trace[1637219124] 'compare' (duration: 130.784216ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:49:25.302459Z","caller":"traceutil/trace.go:171","msg":"trace[1444573691] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"232.364847ms","start":"2026-05-09T21:49:25.070084Z","end":"2026-05-09T21:49:25.302449Z","steps":["trace[1444573691] 'process raft request' (duration: 231.944538ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:25.302552Z","caller":"traceutil/trace.go:171","msg":"trace[1966350639] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"233.894191ms","start":"2026-05-09T21:49:25.068642Z","end":"2026-05-09T21:49:25.302536Z","steps":["trace[1966350639] 'process raft request' (duration: 233.331679ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:26.667693Z","caller":"traceutil/trace.go:171","msg":"trace[1934579988] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"240.303352ms","start":"2026-05-09T21:49:26.427367Z","end":"2026-05-09T21:49:26.66767Z","steps":["trace[1934579988] 'read index received' (duration: 240.098748ms)","trace[1934579988] 'applied index is now lower than readState.Index' (duration: 203.634µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:26.667847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.457167ms","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-09T21:49:26.667884Z","caller":"traceutil/trace.go:171","msg":"trace[1799961131] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1248; }","duration":"240.551468ms","start":"2026-05-09T21:49:26.427323Z","end":"2026-05-09T21:49:26.667874Z","steps":["trace[1799961131] 'agreement among raft nodes before linearized reading' (duration: 240.441156ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:26.668289Z","caller":"traceutil/trace.go:171","msg":"trace[1215024431] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"332.933025ms","start":"2026-05-09T21:49:26.335341Z","end":"2026-05-09T21:49:26.668274Z","steps":["trace[1215024431] 'process raft request' (duration: 332.184208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:26.668371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:26.335316Z","time spent":"333.001547ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:49:27.127649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.298529ms","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-09T21:49:27.127739Z","caller":"traceutil/trace.go:171","msg":"trace[1114280708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1248; }","duration":"304.458003ms","start":"2026-05-09T21:49:26.823262Z","end":"2026-05-09T21:49:27.12772Z","steps":["trace[1114280708] 'count revisions from in-memory index tree' (duration: 304.204758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:27.127784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:26.82324Z","time spent":"304.532915ms","remote":"127.0.0.1:52608","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-09T21:49:27.128024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.241736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-09T21:49:27.128119Z","caller":"traceutil/trace.go:171","msg":"trace[1494765965] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:1248; }","duration":"405.382799ms","start":"2026-05-09T21:49:26.722715Z","end":"2026-05-09T21:49:27.128098Z","steps":["trace[1494765965] 'range keys from in-memory index tree' (duration: 405.008791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:27.128165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:26.722694Z","time spent":"405.461501ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-05-09T21:49:27.806026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532279596764325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-09T21:49:27.985086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:27.129512Z","time spent":"855.568871ms","remote":"127.0.0.1:47362","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-09T21:49:28.217587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.915353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:49:28.2178Z","caller":"traceutil/trace.go:171","msg":"trace[337743089] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"912.055763ms","start":"2026-05-09T21:49:27.305726Z","end":"2026-05-09T21:49:28.217782Z","steps":["trace[337743089] 'read index received' (duration: 679.162714ms)","trace[337743089] 'applied index is now lower than readState.Index' (duration: 232.891869ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:49:28.217793Z","caller":"traceutil/trace.go:171","msg":"trace[238237433] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.085467383s","start":"2026-05-09T21:49:27.132292Z","end":"2026-05-09T21:49:28.21776Z","steps":["trace[238237433] 'process raft request' (duration: 957.285185ms)","trace[238237433] 'compare' (duration: 127.558745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:28.217932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:27.13227Z","time spent":"1.085593206s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:49:28.218089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.92968ms","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-09T21:49:28.218119Z","caller":"traceutil/trace.go:171","msg":"trace[160033627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"912.000152ms","start":"2026-05-09T21:49:27.306109Z","end":"2026-05-09T21:49:28.21811Z","steps":["trace[160033627] 'agreement among raft nodes before linearized reading' (duration: 911.825908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:28.218151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:27.306087Z","time spent":"912.056603ms","remote":"127.0.0.1:47608","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":"warn","ts":"2026-05-09T21:49:28.21813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.36896ms","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-09T21:49:28.218243Z","caller":"traceutil/trace.go:171","msg":"trace[755018580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"912.545614ms","start":"2026-05-09T21:49:27.305671Z","end":"2026-05-09T21:49:28.218216Z","steps":["trace[755018580] 'agreement among raft nodes before linearized reading' (duration: 912.202686ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:28.218289Z","caller":"traceutil/trace.go:171","msg":"trace[604172173] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"231.963458ms","start":"2026-05-09T21:49:27.986315Z","end":"2026-05-09T21:49:28.218278Z","steps":["trace[604172173] 'process raft request' (duration: 231.382825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:28.218297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:27.30565Z","time spent":"912.636096ms","remote":"127.0.0.1:47608","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-09T21:49:28.218382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.752741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-09T21:49:28.218416Z","caller":"traceutil/trace.go:171","msg":"trace[1178788676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"853.841663ms","start":"2026-05-09T21:49:27.364566Z","end":"2026-05-09T21:49:28.218408Z","steps":["trace[1178788676] 'agreement among raft nodes before linearized reading' (duration: 853.753991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:28.218459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:27.36454Z","time spent":"853.912635ms","remote":"127.0.0.1:47608","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-09T21:49:28.218246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.289203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:49:28.218578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.518212ms","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-09T21:49:28.218605Z","caller":"traceutil/trace.go:171","msg":"trace[1458751847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"911.581413ms","start":"2026-05-09T21:49:27.307016Z","end":"2026-05-09T21:49:28.218597Z","steps":["trace[1458751847] 'agreement among raft nodes before linearized reading' (duration: 911.506722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:28.218629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:27.306995Z","time spent":"911.628674ms","remote":"127.0.0.1:47608","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":"info","ts":"2026-05-09T21:49:28.218574Z","caller":"traceutil/trace.go:171","msg":"trace[705964118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"589.647691ms","start":"2026-05-09T21:49:27.62891Z","end":"2026-05-09T21:49:28.218558Z","steps":["trace[705964118] 'agreement among raft nodes before linearized reading' (duration: 589.300324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:28.218716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:27.628889Z","time spent":"589.820396ms","remote":"127.0.0.1:47306","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-09T21:49:29.691232Z","caller":"traceutil/trace.go:171","msg":"trace[1728010700] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"196.822609ms","start":"2026-05-09T21:49:29.494373Z","end":"2026-05-09T21:49:29.691196Z","steps":["trace[1728010700] 'process raft request' (duration: 196.552623ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:29.908886Z","caller":"traceutil/trace.go:171","msg":"trace[165977092] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"272.842004ms","start":"2026-05-09T21:49:29.636018Z","end":"2026-05-09T21:49:29.90886Z","steps":["trace[165977092] 'process raft request' (duration: 272.650319ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:39.318725Z","caller":"traceutil/trace.go:171","msg":"trace[990906173] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"572.676405ms","start":"2026-05-09T21:49:38.746009Z","end":"2026-05-09T21:49:39.318685Z","steps":["trace[990906173] 'process raft request' (duration: 572.45561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:39.318976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:38.745976Z","time spent":"572.880649ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:49:39.956869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.003057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:49:39.957131Z","caller":"traceutil/trace.go:171","msg":"trace[1070489324] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1399; }","duration":"325.518351ms","start":"2026-05-09T21:49:39.631587Z","end":"2026-05-09T21:49:39.957106Z","steps":["trace[1070489324] 'read index received' (duration: 29.231µs)","trace[1070489324] 'applied index is now lower than readState.Index' (duration: 325.48726ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:39.957242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.654004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:49:39.957378Z","caller":"traceutil/trace.go:171","msg":"trace[1299321518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"325.820057ms","start":"2026-05-09T21:49:39.631545Z","end":"2026-05-09T21:49:39.957365Z","steps":["trace[1299321518] 'agreement among raft nodes before linearized reading' (duration: 325.649834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:39.957472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:39.631511Z","time spent":"325.946849ms","remote":"127.0.0.1:47306","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-09T21:49:39.957602Z","caller":"traceutil/trace.go:171","msg":"trace[473699141] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"632.559262ms","start":"2026-05-09T21:49:39.324969Z","end":"2026-05-09T21:49:39.957529Z","steps":["trace[473699141] 'process raft request' (duration: 228.750218ms)","trace[473699141] 'compare' (duration: 402.777492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:39.957958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:49:39.324945Z","time spent":"632.826577ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:49:54.583951Z","caller":"traceutil/trace.go:171","msg":"trace[2045665940] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"113.930193ms","start":"2026-05-09T21:49:54.470004Z","end":"2026-05-09T21:49:54.583934Z","steps":["trace[2045665940] 'process raft request' (duration: 113.151706ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:54.584089Z","caller":"traceutil/trace.go:171","msg":"trace[300706037] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1469; }","duration":"112.109103ms","start":"2026-05-09T21:49:54.471972Z","end":"2026-05-09T21:49:54.584081Z","steps":["trace[300706037] 'read index received' (duration: 36.052059ms)","trace[300706037] 'applied index is now lower than readState.Index' (duration: 76.056314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:49:54.584291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.846835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-09T21:49:54.584324Z","caller":"traceutil/trace.go:171","msg":"trace[1579158294] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1410; }","duration":"110.904346ms","start":"2026-05-09T21:49:54.473413Z","end":"2026-05-09T21:49:54.584318Z","steps":["trace[1579158294] 'agreement among raft nodes before linearized reading' (duration: 110.802743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:54.584319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.326118ms","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-09T21:49:54.584383Z","caller":"traceutil/trace.go:171","msg":"trace[2083367837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1410; }","duration":"112.41142ms","start":"2026-05-09T21:49:54.471955Z","end":"2026-05-09T21:49:54.584367Z","steps":["trace[2083367837] 'agreement among raft nodes before linearized reading' (duration: 112.238186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:54.584415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.959649ms","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-09T21:49:54.584433Z","caller":"traceutil/trace.go:171","msg":"trace[1598008529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1410; }","duration":"110.987599ms","start":"2026-05-09T21:49:54.473441Z","end":"2026-05-09T21:49:54.584428Z","steps":["trace[1598008529] 'agreement among raft nodes before linearized reading' (duration: 110.936547ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:54.584508Z","caller":"traceutil/trace.go:171","msg":"trace[831167359] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"113.787291ms","start":"2026-05-09T21:49:54.470699Z","end":"2026-05-09T21:49:54.584486Z","steps":["trace[831167359] 'process raft request' (duration: 113.166007ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:00.937907Z","caller":"traceutil/trace.go:171","msg":"trace[829837327] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"246.405328ms","start":"2026-05-09T21:50:00.691469Z","end":"2026-05-09T21:50:00.937874Z","steps":["trace[829837327] 'process raft request' (duration: 149.42312ms)","trace[829837327] 'compare' (duration: 96.62675ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:50:00.938004Z","caller":"traceutil/trace.go:171","msg":"trace[749247107] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"245.757484ms","start":"2026-05-09T21:50:00.692223Z","end":"2026-05-09T21:50:00.937981Z","steps":["trace[749247107] 'process raft request' (duration: 245.713553ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:00.93828Z","caller":"traceutil/trace.go:171","msg":"trace[217034733] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"246.569532ms","start":"2026-05-09T21:50:00.691641Z","end":"2026-05-09T21:50:00.93821Z","steps":["trace[217034733] 'process raft request' (duration: 246.134311ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:00.938448Z","caller":"traceutil/trace.go:171","msg":"trace[878428387] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"246.522691ms","start":"2026-05-09T21:50:00.691902Z","end":"2026-05-09T21:50:00.938425Z","steps":["trace[878428387] 'process raft request' (duration: 245.985188ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:05.105574Z","caller":"traceutil/trace.go:171","msg":"trace[807647567] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"127.761579ms","start":"2026-05-09T21:50:04.977784Z","end":"2026-05-09T21:50:05.105545Z","steps":["trace[807647567] 'process raft request' (duration: 127.577745ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:05.114464Z","caller":"traceutil/trace.go:171","msg":"trace[421383706] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"136.203426ms","start":"2026-05-09T21:50:04.978244Z","end":"2026-05-09T21:50:05.114448Z","steps":["trace[421383706] 'process raft request' (duration: 136.092814ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:05.114614Z","caller":"traceutil/trace.go:171","msg":"trace[312116693] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"135.809338ms","start":"2026-05-09T21:50:04.978798Z","end":"2026-05-09T21:50:05.114607Z","steps":["trace[312116693] 'process raft request' (duration: 135.610634ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:05.115022Z","caller":"traceutil/trace.go:171","msg":"trace[957756908] linearizableReadLoop","detail":"{readStateIndex:1523; appliedIndex:1519; }","duration":"105.204051ms","start":"2026-05-09T21:50:05.009604Z","end":"2026-05-09T21:50:05.114808Z","steps":["trace[957756908] 'read index received' (duration: 95.760141ms)","trace[957756908] 'applied index is now lower than readState.Index' (duration: 9.44242ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:50:05.115104Z","caller":"traceutil/trace.go:171","msg":"trace[1732027576] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"135.743048ms","start":"2026-05-09T21:50:04.979105Z","end":"2026-05-09T21:50:05.114848Z","steps":["trace[1732027576] 'process raft request' (duration: 135.470691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:50:05.11525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.61791ms","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:525"} {"level":"info","ts":"2026-05-09T21:50:05.115334Z","caller":"traceutil/trace.go:171","msg":"trace[749976523] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1460; }","duration":"105.739902ms","start":"2026-05-09T21:50:05.009574Z","end":"2026-05-09T21:50:05.115314Z","steps":["trace[749976523] 'agreement among raft nodes before linearized reading' (duration: 105.442846ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:27.600181Z","caller":"traceutil/trace.go:171","msg":"trace[1098918090] linearizableReadLoop","detail":"{readStateIndex:1628; appliedIndex:1627; }","duration":"136.846845ms","start":"2026-05-09T21:50:27.463314Z","end":"2026-05-09T21:50:27.600161Z","steps":["trace[1098918090] 'read index received' (duration: 134.905981ms)","trace[1098918090] 'applied index is now lower than readState.Index' (duration: 1.939804ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:50:27.600312Z","caller":"traceutil/trace.go:171","msg":"trace[2062970061] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"152.452045ms","start":"2026-05-09T21:50:27.447833Z","end":"2026-05-09T21:50:27.600285Z","steps":["trace[2062970061] 'process raft request' (duration: 150.339498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:50:27.600407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.08586ms","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-09T21:50:27.600443Z","caller":"traceutil/trace.go:171","msg":"trace[860613403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1560; }","duration":"137.164522ms","start":"2026-05-09T21:50:27.463269Z","end":"2026-05-09T21:50:27.600434Z","steps":["trace[860613403] 'agreement among raft nodes before linearized reading' (duration: 137.017858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:50:27.600569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.280169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-09T21:50:27.600596Z","caller":"traceutil/trace.go:171","msg":"trace[324283931] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1560; }","duration":"135.33831ms","start":"2026-05-09T21:50:27.465251Z","end":"2026-05-09T21:50:27.60059Z","steps":["trace[324283931] 'agreement among raft nodes before linearized reading' (duration: 135.275289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:50:27.600685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.956498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:50:27.600709Z","caller":"traceutil/trace.go:171","msg":"trace[126041714] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1560; }","duration":"121.998369ms","start":"2026-05-09T21:50:27.478705Z","end":"2026-05-09T21:50:27.600704Z","steps":["trace[126041714] 'agreement among raft nodes before linearized reading' (duration: 121.954838ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:27.748617Z","caller":"traceutil/trace.go:171","msg":"trace[957571449] linearizableReadLoop","detail":"{readStateIndex:1630; appliedIndex:1628; }","duration":"119.512514ms","start":"2026-05-09T21:50:27.629072Z","end":"2026-05-09T21:50:27.748584Z","steps":["trace[957571449] 'read index received' (duration: 24.627975ms)","trace[957571449] 'applied index is now lower than readState.Index' (duration: 94.883229ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:50:27.748689Z","caller":"traceutil/trace.go:171","msg":"trace[2108284056] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"142.017921ms","start":"2026-05-09T21:50:27.606635Z","end":"2026-05-09T21:50:27.748653Z","steps":["trace[2108284056] 'process raft request' (duration: 103.184286ms)","trace[2108284056] 'compare' (duration: 38.539309ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:50:27.74876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.661898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:50:27.7488Z","caller":"traceutil/trace.go:171","msg":"trace[764695881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1562; }","duration":"119.76951ms","start":"2026-05-09T21:50:27.629016Z","end":"2026-05-09T21:50:27.748785Z","steps":["trace[764695881] 'agreement among raft nodes before linearized reading' (duration: 119.672708ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:32.226704Z","caller":"traceutil/trace.go:171","msg":"trace[137542408] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"127.522436ms","start":"2026-05-09T21:51:32.099124Z","end":"2026-05-09T21:51:32.226647Z","steps":["trace[137542408] 'process raft request' (duration: 127.225499ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:52:59.886749Z","caller":"traceutil/trace.go:171","msg":"trace[1747470902] transaction","detail":"{read_only:false; response_revision:2282; number_of_response:1; }","duration":"107.850302ms","start":"2026-05-09T21:52:59.778871Z","end":"2026-05-09T21:52:59.886722Z","steps":["trace[1747470902] 'process raft request' (duration: 107.693279ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:53:05.842421Z","caller":"traceutil/trace.go:171","msg":"trace[939736597] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2413; }","duration":"212.949885ms","start":"2026-05-09T21:53:05.629448Z","end":"2026-05-09T21:53:05.842398Z","steps":["trace[939736597] 'read index received' (duration: 212.831043ms)","trace[939736597] 'applied index is now lower than readState.Index' (duration: 118.252µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:53:05.842528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.073479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:53:05.842545Z","caller":"traceutil/trace.go:171","msg":"trace[23343345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2314; }","duration":"213.15159ms","start":"2026-05-09T21:53:05.629389Z","end":"2026-05-09T21:53:05.842541Z","steps":["trace[23343345] 'agreement among raft nodes before linearized reading' (duration: 213.085319ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:53:05.842548Z","caller":"traceutil/trace.go:171","msg":"trace[1611084276] transaction","detail":"{read_only:false; response_revision:2314; number_of_response:1; }","duration":"250.954046ms","start":"2026-05-09T21:53:05.591558Z","end":"2026-05-09T21:53:05.842512Z","steps":["trace[1611084276] 'process raft request' (duration: 250.73867ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:53:06.242508Z","caller":"traceutil/trace.go:171","msg":"trace[1213836487] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"191.337388ms","start":"2026-05-09T21:53:06.051142Z","end":"2026-05-09T21:53:06.242479Z","steps":["trace[1213836487] 'process raft request' (duration: 92.925823ms)","trace[1213836487] 'compare' (duration: 98.306422ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:53:06.473006Z","caller":"traceutil/trace.go:171","msg":"trace[1958059032] transaction","detail":"{read_only:false; response_revision:2317; number_of_response:1; }","duration":"112.329613ms","start":"2026-05-09T21:53:06.360649Z","end":"2026-05-09T21:53:06.472978Z","steps":["trace[1958059032] 'process raft request' (duration: 112.156479ms)"],"step_count":1}