{"level":"warn","ts":"2026-04-16T06:10:45.977019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T06:10:45.977131Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T06:10:45.977214Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T06:10:45.977228Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-16T06:10:45.977248Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T06:10:45.977963Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-16T06:10:45.978045Z","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":16,"max-cpu-available":16,"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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T06:10:45.984814Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.148156ms"} {"level":"info","ts":"2026-04-16T06:10:45.990468Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-16T06:10:45.990631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T06:10:45.990673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-16T06:10:45.990699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T06:10:45.990713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-16T06:10:45.990779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-16T06:10:45.996682Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T06:10:45.998263Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T06:10:45.999561Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T06:10:46.002523Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T06:10:46.002767Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T06:10:46.002976Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:10:46.003109Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:10:46.003131Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T06:10:46.006183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-16T06:10:46.00635Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-16T06:10:46.010453Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T06:10:46.010572Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-16T06:10:46.01062Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-16T06:10:46.011025Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T06:10:46.010949Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T06:10:46.791049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T06:10:46.791141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T06:10:46.791168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-16T06:10:46.79119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-16T06:10:46.791202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-16T06:10:46.791218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-16T06:10:46.791233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-16T06:10:46.792637Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T06:10:46.792668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:10:46.793004Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T06:10:46.7932Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:46.79347Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T06:10:46.7935Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T06:10:46.795525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T06:10:46.796095Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:46.796236Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:46.796275Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T06:10:46.797117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-04-16T06:11:45.198594Z","caller":"traceutil/trace.go:171","msg":"trace[1348126664] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"133.168389ms","start":"2026-04-16T06:11:45.0654Z","end":"2026-04-16T06:11:45.198569Z","steps":["trace[1348126664] 'read index received' (duration: 129.5593ms)","trace[1348126664] 'applied index is now lower than readState.Index' (duration: 3.607949ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:11:45.198945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.508498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:11:45.198975Z","caller":"traceutil/trace.go:171","msg":"trace[1386752193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"133.58461ms","start":"2026-04-16T06:11:45.065382Z","end":"2026-04-16T06:11:45.198967Z","steps":["trace[1386752193] 'agreement among raft nodes before linearized reading' (duration: 133.294982ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:49.565206Z","caller":"traceutil/trace.go:171","msg":"trace[212042280] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"138.040419ms","start":"2026-04-16T06:12:49.427136Z","end":"2026-04-16T06:12:49.565176Z","steps":["trace[212042280] 'process raft request' (duration: 137.821773ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:49.604611Z","caller":"traceutil/trace.go:171","msg":"trace[101813299] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"169.62394ms","start":"2026-04-16T06:12:49.434961Z","end":"2026-04-16T06:12:49.604585Z","steps":["trace[101813299] 'process raft request' (duration: 169.542748ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:49.604622Z","caller":"traceutil/trace.go:171","msg":"trace[1566214888] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"171.86587ms","start":"2026-04-16T06:12:49.432735Z","end":"2026-04-16T06:12:49.604601Z","steps":["trace[1566214888] 'process raft request' (duration: 171.617264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:50.048419Z","caller":"traceutil/trace.go:171","msg":"trace[1056829394] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"210.973251ms","start":"2026-04-16T06:12:49.837424Z","end":"2026-04-16T06:12:50.048397Z","steps":["trace[1056829394] 'read index received' (duration: 198.864608ms)","trace[1056829394] 'applied index is now lower than readState.Index' (duration: 12.107603ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:50.048529Z","caller":"traceutil/trace.go:171","msg":"trace[1767683795] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"215.566453ms","start":"2026-04-16T06:12:49.832953Z","end":"2026-04-16T06:12:50.048519Z","steps":["trace[1767683795] 'process raft request' (duration: 203.42215ms)","trace[1767683795] 'compare' (duration: 11.901497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:12:50.048733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.3003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:150495"} {"level":"info","ts":"2026-04-16T06:12:50.048761Z","caller":"traceutil/trace.go:171","msg":"trace[846694632] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:730; }","duration":"211.372642ms","start":"2026-04-16T06:12:49.837381Z","end":"2026-04-16T06:12:50.048753Z","steps":["trace[846694632] 'agreement among raft nodes before linearized reading' (duration: 211.227738ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:50.437809Z","caller":"traceutil/trace.go:171","msg":"trace[445094646] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"107.113945ms","start":"2026-04-16T06:12:50.330674Z","end":"2026-04-16T06:12:50.437788Z","steps":["trace[445094646] 'process raft request' (duration: 100.897419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:53.608939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458512145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:12:54.113939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458512145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:12:54.614707Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458512145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:12:55.115539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458512145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:12:55.616163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458512145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:12:55.816046Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.710202242s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T06:12:55.817178Z","caller":"traceutil/trace.go:171","msg":"trace[1895357100] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"2.712536943s","start":"2026-04-16T06:12:53.10461Z","end":"2026-04-16T06:12:55.817147Z","steps":["trace[1895357100] 'process raft request' (duration: 2.71204204s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.817959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.104586Z","time spent":"2.712667777s","remote":"127.0.0.1:59998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T06:12:55.821421Z","caller":"traceutil/trace.go:171","msg":"trace[1345810674] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"1.29502558s","start":"2026-04-16T06:12:54.526366Z","end":"2026-04-16T06:12:55.821392Z","steps":["trace[1345810674] 'process raft request' (duration: 1.294748493s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.821833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.526328Z","time spent":"1.295158914s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:12:55.832422Z","caller":"traceutil/trace.go:171","msg":"trace[2028186406] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:803; }","duration":"2.72403599s","start":"2026-04-16T06:12:53.108348Z","end":"2026-04-16T06:12:55.832384Z","steps":["trace[2028186406] 'read index received' (duration: 2.707998713s)","trace[2028186406] 'applied index is now lower than readState.Index' (duration: 16.035477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:12:55.832666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.724297107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.83274Z","caller":"traceutil/trace.go:171","msg":"trace[1688742416] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:769; }","duration":"2.72442366s","start":"2026-04-16T06:12:53.108296Z","end":"2026-04-16T06:12:55.83272Z","steps":["trace[1688742416] 'agreement among raft nodes before linearized reading' (duration: 2.724293346s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.832762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.71880175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T06:12:55.832782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.108275Z","time spent":"2.724497132s","remote":"127.0.0.1:60296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:12:55.832816Z","caller":"traceutil/trace.go:171","msg":"trace[1260268591] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:769; }","duration":"2.718904323s","start":"2026-04-16T06:12:53.113891Z","end":"2026-04-16T06:12:55.832796Z","steps":["trace[1260268591] 'agreement among raft nodes before linearized reading' (duration: 2.71878871s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.832948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:53.113868Z","time spent":"2.718992866s","remote":"127.0.0.1:60354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":27,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.833172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.89643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.833237Z","caller":"traceutil/trace.go:171","msg":"trace[1245177148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"766.003843ms","start":"2026-04-16T06:12:55.067212Z","end":"2026-04-16T06:12:55.833216Z","steps":["trace[1245177148] 'agreement among raft nodes before linearized reading' (duration: 765.907931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.833274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:55.067173Z","time spent":"766.094165ms","remote":"127.0.0.1:59886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T06:12:55.833262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.522079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T06:12:55.833349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395527278s","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-04-16T06:12:55.833399Z","caller":"traceutil/trace.go:171","msg":"trace[1149820687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"1.395601631s","start":"2026-04-16T06:12:54.437784Z","end":"2026-04-16T06:12:55.833385Z","steps":["trace[1149820687] 'agreement among raft nodes before linearized reading' (duration: 1.395426616s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.833371Z","caller":"traceutil/trace.go:171","msg":"trace[1961624400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"792.706435ms","start":"2026-04-16T06:12:55.040637Z","end":"2026-04-16T06:12:55.833343Z","steps":["trace[1961624400] 'agreement among raft nodes before linearized reading' (duration: 792.336635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.833437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.437766Z","time spent":"1.395664312s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T06:12:55.833453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:55.040603Z","time spent":"792.841918ms","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T06:12:55.833454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395124678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:55.833577Z","caller":"traceutil/trace.go:171","msg":"trace[1494308443] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:769; }","duration":"1.395394435s","start":"2026-04-16T06:12:54.438163Z","end":"2026-04-16T06:12:55.833557Z","steps":["trace[1494308443] 'agreement among raft nodes before linearized reading' (duration: 1.395004625s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.834038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.438129Z","time spent":"1.395893489s","remote":"127.0.0.1:60032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:55.833694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374754026s","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-04-16T06:12:55.834199Z","caller":"traceutil/trace.go:171","msg":"trace[81628373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"1.375254339s","start":"2026-04-16T06:12:54.45893Z","end":"2026-04-16T06:12:55.834185Z","steps":["trace[81628373] 'agreement among raft nodes before linearized reading' (duration: 1.374658983s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.834248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.458914Z","time spent":"1.37532212s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T06:12:55.833861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380442176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T06:12:55.834388Z","caller":"traceutil/trace.go:171","msg":"trace[628224083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:769; }","duration":"1.380999921s","start":"2026-04-16T06:12:54.453375Z","end":"2026-04-16T06:12:55.834375Z","steps":["trace[628224083] 'agreement among raft nodes before linearized reading' (duration: 1.380419236s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:55.83446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:54.45336Z","time spent":"1.381088694s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T06:12:55.958773Z","caller":"traceutil/trace.go:171","msg":"trace[1651955066] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"120.970453ms","start":"2026-04-16T06:12:55.837769Z","end":"2026-04-16T06:12:55.95874Z","steps":["trace[1651955066] 'process raft request' (duration: 120.743207ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:55.95974Z","caller":"traceutil/trace.go:171","msg":"trace[1214119783] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"120.631975ms","start":"2026-04-16T06:12:55.839073Z","end":"2026-04-16T06:12:55.959705Z","steps":["trace[1214119783] 'process raft request' (duration: 120.420199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.590998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.933814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.591073Z","caller":"traceutil/trace.go:171","msg":"trace[1430985271] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:773; }","duration":"386.042297ms","start":"2026-04-16T06:12:56.205015Z","end":"2026-04-16T06:12:56.591057Z","steps":["trace[1430985271] 'count revisions from in-memory index tree' (duration: 385.871613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.205008Z","time spent":"386.095908ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.591191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.097839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.59125Z","caller":"traceutil/trace.go:171","msg":"trace[465555764] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:773; }","duration":"386.192731ms","start":"2026-04-16T06:12:56.205042Z","end":"2026-04-16T06:12:56.591235Z","steps":["trace[465555764] 'range keys from in-memory index tree' (duration: 385.985366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.205028Z","time spent":"386.240423ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T06:12:56.591298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.231105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.591323Z","caller":"traceutil/trace.go:171","msg":"trace[772108723] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:773; }","duration":"549.269927ms","start":"2026-04-16T06:12:56.042045Z","end":"2026-04-16T06:12:56.591315Z","steps":["trace[772108723] 'range keys from in-memory index tree' (duration: 549.190054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.04204Z","time spent":"549.298528ms","remote":"127.0.0.1:45284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T06:12:56.591405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.896198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.591441Z","caller":"traceutil/trace.go:171","msg":"trace[1471507886] 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:773; }","duration":"470.96031ms","start":"2026-04-16T06:12:56.120467Z","end":"2026-04-16T06:12:56.591427Z","steps":["trace[1471507886] 'count revisions from in-memory index tree' (duration: 470.718594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.120453Z","time spent":"471.002141ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.591459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.415258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.591474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.24882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.591497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.942512ms","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-04-16T06:12:56.591505Z","caller":"traceutil/trace.go:171","msg":"trace[1172813886] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:773; }","duration":"508.524461ms","start":"2026-04-16T06:12:56.082967Z","end":"2026-04-16T06:12:56.591491Z","steps":["trace[1172813886] 'range keys from in-memory index tree' (duration: 508.299145ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.591524Z","caller":"traceutil/trace.go:171","msg":"trace[1198404950] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:773; }","duration":"545.315541ms","start":"2026-04-16T06:12:56.046192Z","end":"2026-04-16T06:12:56.591508Z","steps":["trace[1198404950] 'range keys from in-memory index tree' (duration: 545.205468ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.591531Z","caller":"traceutil/trace.go:171","msg":"trace[1512790939] 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:773; }","duration":"470.003264ms","start":"2026-04-16T06:12:56.121517Z","end":"2026-04-16T06:12:56.591521Z","steps":["trace[1512790939] 'range keys from in-memory index tree' (duration: 469.875001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.082948Z","time spent":"508.583683ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T06:12:56.591556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.046185Z","time spent":"545.362563ms","remote":"127.0.0.1:45284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-16T06:12:56.591569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.121507Z","time spent":"470.050325ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"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-04-16T06:12:56.591599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.660801ms","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-04-16T06:12:56.591622Z","caller":"traceutil/trace.go:171","msg":"trace[1872374290] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:773; }","duration":"494.683882ms","start":"2026-04-16T06:12:56.09693Z","end":"2026-04-16T06:12:56.591614Z","steps":["trace[1872374290] 'count revisions from in-memory index tree' (duration: 494.581719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.586085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.591681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.777784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.591686Z","caller":"traceutil/trace.go:171","msg":"trace[1349938400] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:773; }","duration":"549.715848ms","start":"2026-04-16T06:12:56.041952Z","end":"2026-04-16T06:12:56.591668Z","steps":["trace[1349938400] 'count revisions from in-memory index tree' (duration: 549.433071ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.591702Z","caller":"traceutil/trace.go:171","msg":"trace[431288461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:773; }","duration":"494.856187ms","start":"2026-04-16T06:12:56.09684Z","end":"2026-04-16T06:12:56.591696Z","steps":["trace[431288461] 'range keys from in-memory index tree' (duration: 494.643831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.096814Z","time spent":"494.902458ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T06:12:56.591731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.04193Z","time spent":"549.791051ms","remote":"127.0.0.1:45284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.591709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.993716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.59179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.689935ms","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":"warn","ts":"2026-04-16T06:12:56.591814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.204909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.591817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.885718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.59183Z","caller":"traceutil/trace.go:171","msg":"trace[1716213768] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:773; }","duration":"508.736976ms","start":"2026-04-16T06:12:56.083082Z","end":"2026-04-16T06:12:56.591819Z","steps":["trace[1716213768] 'count revisions from in-memory index tree' (duration: 508.663185ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.591837Z","caller":"traceutil/trace.go:171","msg":"trace[1123841825] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:773; }","duration":"494.296482ms","start":"2026-04-16T06:12:56.097534Z","end":"2026-04-16T06:12:56.591831Z","steps":["trace[1123841825] 'range keys from in-memory index tree' (duration: 494.086556ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.591849Z","caller":"traceutil/trace.go:171","msg":"trace[1066160406] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:773; }","duration":"160.94704ms","start":"2026-04-16T06:12:56.430891Z","end":"2026-04-16T06:12:56.591839Z","steps":["trace[1066160406] 'range keys from in-memory index tree' (duration: 160.764345ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.591845Z","caller":"traceutil/trace.go:171","msg":"trace[576108661] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:773; }","duration":"204.235612ms","start":"2026-04-16T06:12:56.387577Z","end":"2026-04-16T06:12:56.591813Z","steps":["trace[576108661] 'range keys from in-memory index tree' (duration: 203.856162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.097498Z","time spent":"494.356323ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-16T06:12:56.591863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.083077Z","time spent":"508.775958ms","remote":"127.0.0.1:45298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.591864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.241278ms","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-04-16T06:12:56.591923Z","caller":"traceutil/trace.go:171","msg":"trace[819041271] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:773; }","duration":"435.338401ms","start":"2026-04-16T06:12:56.156572Z","end":"2026-04-16T06:12:56.591911Z","steps":["trace[819041271] 'range keys from in-memory index tree' (duration: 435.154176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.443282ms","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":"warn","ts":"2026-04-16T06:12:56.591959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.156547Z","time spent":"435.398843ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T06:12:56.591994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.892863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.592028Z","caller":"traceutil/trace.go:171","msg":"trace[865083781] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:773; }","duration":"182.928214ms","start":"2026-04-16T06:12:56.409089Z","end":"2026-04-16T06:12:56.592017Z","steps":["trace[865083781] 'range keys from in-memory index tree' (duration: 182.792311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.388061ms","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":"info","ts":"2026-04-16T06:12:56.592126Z","caller":"traceutil/trace.go:171","msg":"trace[1970380256] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:773; }","duration":"435.481864ms","start":"2026-04-16T06:12:56.156635Z","end":"2026-04-16T06:12:56.592117Z","steps":["trace[1970380256] 'count revisions from in-memory index tree' (duration: 435.283409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.156598Z","time spent":"435.544886ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.592154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.862915ms","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-04-16T06:12:56.592196Z","caller":"traceutil/trace.go:171","msg":"trace[145623397] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:773; }","duration":"405.976518ms","start":"2026-04-16T06:12:56.186208Z","end":"2026-04-16T06:12:56.592185Z","steps":["trace[145623397] 'range keys from in-memory index tree' (duration: 405.725752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.399156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.592253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.41579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.592259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.997925ms","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-04-16T06:12:56.592291Z","caller":"traceutil/trace.go:171","msg":"trace[334477117] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:773; }","duration":"386.477559ms","start":"2026-04-16T06:12:56.2058Z","end":"2026-04-16T06:12:56.592278Z","steps":["trace[334477117] 'range keys from in-memory index tree' (duration: 386.285504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.026836ms","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-04-16T06:12:56.5923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.758897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.592312Z","caller":"traceutil/trace.go:171","msg":"trace[1199145801] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:773; }","duration":"407.078707ms","start":"2026-04-16T06:12:56.185219Z","end":"2026-04-16T06:12:56.592298Z","steps":["trace[1199145801] 'range keys from in-memory index tree' (duration: 406.856511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.205787Z","time spent":"386.52774ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-16T06:12:56.592334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.072253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.592336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.064758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.592338Z","caller":"traceutil/trace.go:171","msg":"trace[651025440] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:773; }","duration":"407.077658ms","start":"2026-04-16T06:12:56.185248Z","end":"2026-04-16T06:12:56.592326Z","steps":["trace[651025440] 'count revisions from in-memory index tree' (duration: 406.889232ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.592352Z","caller":"traceutil/trace.go:171","msg":"trace[1526535769] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:773; }","duration":"204.822778ms","start":"2026-04-16T06:12:56.387515Z","end":"2026-04-16T06:12:56.592337Z","steps":["trace[1526535769] 'count revisions from in-memory index tree' (duration: 204.717455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.185207Z","time spent":"407.135429ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-16T06:12:56.592367Z","caller":"traceutil/trace.go:171","msg":"trace[166473004] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:773; }","duration":"161.117864ms","start":"2026-04-16T06:12:56.431242Z","end":"2026-04-16T06:12:56.59236Z","steps":["trace[166473004] 'range keys from in-memory index tree' (duration: 161.026032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.793571ms","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":"warn","ts":"2026-04-16T06:12:56.592386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.185204Z","time spent":"407.16832ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.592426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.303398ms","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-04-16T06:12:56.592445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.605148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.592304Z","caller":"traceutil/trace.go:171","msg":"trace[53060876] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:773; }","duration":"182.489182ms","start":"2026-04-16T06:12:56.409799Z","end":"2026-04-16T06:12:56.592288Z","steps":["trace[53060876] 'range keys from in-memory index tree' (duration: 182.339738ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.592471Z","caller":"traceutil/trace.go:171","msg":"trace[1801161071] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:773; }","duration":"358.39218ms","start":"2026-04-16T06:12:56.234068Z","end":"2026-04-16T06:12:56.59246Z","steps":["trace[1801161071] 'range keys from in-memory index tree' (duration: 358.253667ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.592478Z","caller":"traceutil/trace.go:171","msg":"trace[1797840077] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:773; }","duration":"250.645779ms","start":"2026-04-16T06:12:56.341822Z","end":"2026-04-16T06:12:56.592468Z","steps":["trace[1797840077] 'range keys from in-memory index tree' (duration: 250.539046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.298969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.592447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.454153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:12:56.592428Z","caller":"traceutil/trace.go:171","msg":"trace[502568068] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:773; }","duration":"358.886433ms","start":"2026-04-16T06:12:56.233522Z","end":"2026-04-16T06:12:56.592409Z","steps":["trace[502568068] 'range keys from in-memory index tree' (duration: 358.689538ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.591965Z","caller":"traceutil/trace.go:171","msg":"trace[347365593] 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:773; }","duration":"471.505684ms","start":"2026-04-16T06:12:56.120453Z","end":"2026-04-16T06:12:56.591959Z","steps":["trace[347365593] 'range keys from in-memory index tree' (duration: 471.33501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.835462ms","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-04-16T06:12:56.592557Z","caller":"traceutil/trace.go:171","msg":"trace[1637873884] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:773; }","duration":"161.624527ms","start":"2026-04-16T06:12:56.430908Z","end":"2026-04-16T06:12:56.592532Z","steps":["trace[1637873884] 'count revisions from in-memory index tree' (duration: 161.33716ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:56.592583Z","caller":"traceutil/trace.go:171","msg":"trace[129276989] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:773; }","duration":"358.939874ms","start":"2026-04-16T06:12:56.233636Z","end":"2026-04-16T06:12:56.592576Z","steps":["trace[129276989] 'count revisions from in-memory index tree' (duration: 358.600115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.59259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.112506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:12:56.59261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.233623Z","time spent":"358.980496ms","remote":"127.0.0.1:45366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:12:56.592621Z","caller":"traceutil/trace.go:171","msg":"trace[1578350012] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:773; }","duration":"205.159007ms","start":"2026-04-16T06:12:56.387452Z","end":"2026-04-16T06:12:56.592611Z","steps":["trace[1578350012] 'range keys from in-memory index tree' (duration: 205.015203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.591644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.096837Z","time spent":"494.800595ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:12:56.59255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.2335Z","time spent":"359.038777ms","remote":"127.0.0.1:45366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T06:12:56.592513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.234058Z","time spent":"358.440241ms","remote":"127.0.0.1:45366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-16T06:12:56.592556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.120437Z","time spent":"472.107941ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"info","ts":"2026-04-16T06:12:56.592516Z","caller":"traceutil/trace.go:171","msg":"trace[513364980] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:773; }","duration":"250.555947ms","start":"2026-04-16T06:12:56.341951Z","end":"2026-04-16T06:12:56.592507Z","steps":["trace[513364980] 'count revisions from in-memory index tree' (duration: 250.247629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:12:56.592241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:12:56.186169Z","time spent":"406.06179ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"info","ts":"2026-04-16T06:12:56.592368Z","caller":"traceutil/trace.go:171","msg":"trace[791761218] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:773; }","duration":"183.108789ms","start":"2026-04-16T06:12:56.409254Z","end":"2026-04-16T06:12:56.592363Z","steps":["trace[791761218] 'count revisions from in-memory index tree' (duration: 183.018417ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:58.871683Z","caller":"traceutil/trace.go:171","msg":"trace[1542053322] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"202.2637ms","start":"2026-04-16T06:12:58.669377Z","end":"2026-04-16T06:12:58.87164Z","steps":["trace[1542053322] 'process raft request' (duration: 201.653964ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:12:59.053995Z","caller":"traceutil/trace.go:171","msg":"trace[1369880573] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"129.483741ms","start":"2026-04-16T06:12:58.924489Z","end":"2026-04-16T06:12:59.053972Z","steps":["trace[1369880573] 'process raft request' (duration: 67.003336ms)","trace[1369880573] 'compare' (duration: 62.365572ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:12:59.559357Z","caller":"traceutil/trace.go:171","msg":"trace[417155710] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"114.625895ms","start":"2026-04-16T06:12:59.444709Z","end":"2026-04-16T06:12:59.559335Z","steps":["trace[417155710] 'process raft request' (duration: 114.213844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:14:31.100083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.577278ms","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-04-16T06:14:31.100206Z","caller":"traceutil/trace.go:171","msg":"trace[1607582377] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2004; }","duration":"230.773544ms","start":"2026-04-16T06:14:30.869405Z","end":"2026-04-16T06:14:31.100179Z","steps":["trace[1607582377] 'count revisions from in-memory index tree' (duration: 230.446204ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:14:38.850161Z","caller":"traceutil/trace.go:171","msg":"trace[1427432398] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2164; }","duration":"137.537863ms","start":"2026-04-16T06:14:38.7126Z","end":"2026-04-16T06:14:38.850137Z","steps":["trace[1427432398] 'read index received' (duration: 58.974569ms)","trace[1427432398] 'applied index is now lower than readState.Index' (duration: 78.562264ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:14:38.850313Z","caller":"traceutil/trace.go:171","msg":"trace[730452114] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"150.520407ms","start":"2026-04-16T06:14:38.699766Z","end":"2026-04-16T06:14:38.850286Z","steps":["trace[730452114] 'process raft request' (duration: 71.877171ms)","trace[730452114] 'compare' (duration: 78.371668ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:14:38.850533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.918063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T06:14:38.850584Z","caller":"traceutil/trace.go:171","msg":"trace[1559725054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2093; }","duration":"138.016775ms","start":"2026-04-16T06:14:38.712554Z","end":"2026-04-16T06:14:38.850571Z","steps":["trace[1559725054] 'agreement among raft nodes before linearized reading' (duration: 137.719767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:15:05.105225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.464324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-16T06:15:05.105262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.446604ms","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-04-16T06:15:05.105325Z","caller":"traceutil/trace.go:171","msg":"trace[1507317011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2282; }","duration":"110.600828ms","start":"2026-04-16T06:15:04.994706Z","end":"2026-04-16T06:15:05.105306Z","steps":["trace[1507317011] 'range keys from in-memory index tree' (duration: 110.212857ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:15:05.10534Z","caller":"traceutil/trace.go:171","msg":"trace[969757656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2282; }","duration":"110.579807ms","start":"2026-04-16T06:15:04.994742Z","end":"2026-04-16T06:15:05.105322Z","steps":["trace[969757656] 'range keys from in-memory index tree' (duration: 110.235648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:15:05.10562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.014377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T06:15:05.105651Z","caller":"traceutil/trace.go:171","msg":"trace[1339149291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2282; }","duration":"112.114049ms","start":"2026-04-16T06:15:04.993528Z","end":"2026-04-16T06:15:05.105642Z","steps":["trace[1339149291] 'range keys from in-memory index tree' (duration: 111.868593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:15:05.105687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.677641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T06:15:05.105773Z","caller":"traceutil/trace.go:171","msg":"trace[1912171038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2282; }","duration":"117.837675ms","start":"2026-04-16T06:15:04.987914Z","end":"2026-04-16T06:15:05.105751Z","steps":["trace[1912171038] 'range keys from in-memory index tree' (duration: 117.442734ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:16:25.555261Z","caller":"traceutil/trace.go:171","msg":"trace[1658089819] transaction","detail":"{read_only:false; response_revision:2881; number_of_response:1; }","duration":"106.339961ms","start":"2026-04-16T06:16:25.448883Z","end":"2026-04-16T06:16:25.555223Z","steps":["trace[1658089819] 'process raft request' (duration: 105.998312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:16:25.808766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.101444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:16:25.808967Z","caller":"traceutil/trace.go:171","msg":"trace[1797841882] linearizableReadLoop","detail":"{readStateIndex:3000; appliedIndex:2998; }","duration":"200.007226ms","start":"2026-04-16T06:16:25.608943Z","end":"2026-04-16T06:16:25.80895Z","steps":["trace[1797841882] 'read index received' (duration: 67.533612ms)","trace[1797841882] 'applied index is now lower than readState.Index' (duration: 132.472434ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:16:25.80902Z","caller":"traceutil/trace.go:171","msg":"trace[1372178798] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"216.603039ms","start":"2026-04-16T06:16:25.592383Z","end":"2026-04-16T06:16:25.808986Z","steps":["trace[1372178798] 'process raft request' (duration: 216.503476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:16:25.809179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.783282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T06:16:25.809217Z","caller":"traceutil/trace.go:171","msg":"trace[1966031693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2883; }","duration":"191.849224ms","start":"2026-04-16T06:16:25.617358Z","end":"2026-04-16T06:16:25.809207Z","steps":["trace[1966031693] 'agreement among raft nodes before linearized reading' (duration: 191.70977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:16:25.809213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.250633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-04-16T06:16:25.809282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.881205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T06:16:25.809304Z","caller":"traceutil/trace.go:171","msg":"trace[1529510197] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2883; }","duration":"200.414117ms","start":"2026-04-16T06:16:25.608864Z","end":"2026-04-16T06:16:25.809279Z","steps":["trace[1529510197] 'agreement among raft nodes before linearized reading' (duration: 200.177701ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:16:25.809288Z","caller":"traceutil/trace.go:171","msg":"trace[1071954039] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"218.473571ms","start":"2026-04-16T06:16:25.590783Z","end":"2026-04-16T06:16:25.809257Z","steps":["trace[1071954039] 'process raft request' (duration: 85.662787ms)","trace[1071954039] 'compare' (duration: 131.969261ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:16:25.80935Z","caller":"traceutil/trace.go:171","msg":"trace[1406676557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2883; }","duration":"191.981898ms","start":"2026-04-16T06:16:25.617352Z","end":"2026-04-16T06:16:25.809334Z","steps":["trace[1406676557] 'agreement among raft nodes before linearized reading' (duration: 191.827533ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:05.704667Z","caller":"traceutil/trace.go:171","msg":"trace[1458010255] linearizableReadLoop","detail":"{readStateIndex:3448; appliedIndex:3447; }","duration":"265.85347ms","start":"2026-04-16T06:17:05.438792Z","end":"2026-04-16T06:17:05.704645Z","steps":["trace[1458010255] 'read index received' (duration: 265.721096ms)","trace[1458010255] 'applied index is now lower than readState.Index' (duration: 131.664µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:17:05.70477Z","caller":"traceutil/trace.go:171","msg":"trace[1394289061] transaction","detail":"{read_only:false; response_revision:3313; number_of_response:1; }","duration":"394.603831ms","start":"2026-04-16T06:17:05.310133Z","end":"2026-04-16T06:17:05.704737Z","steps":["trace[1394289061] 'process raft request' (duration: 394.363534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.704866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.062336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-04-16T06:17:05.704925Z","caller":"traceutil/trace.go:171","msg":"trace[52461752] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3313; }","duration":"266.167948ms","start":"2026-04-16T06:17:05.438743Z","end":"2026-04-16T06:17:05.704911Z","steps":["trace[52461752] 'agreement among raft nodes before linearized reading' (duration: 265.985073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:05.704971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:17:05.310119Z","time spent":"394.734885ms","remote":"127.0.0.1:60008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:17:05.710274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.028425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r7df7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-16T06:17:05.710379Z","caller":"traceutil/trace.go:171","msg":"trace[1064802763] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r7df7; range_end:; response_count:1; response_revision:3313; }","duration":"127.206559ms","start":"2026-04-16T06:17:05.583151Z","end":"2026-04-16T06:17:05.710358Z","steps":["trace[1064802763] 'agreement among raft nodes before linearized reading' (duration: 126.897371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:17:06.405716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.896985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T06:17:06.405826Z","caller":"traceutil/trace.go:171","msg":"trace[938842388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3324; }","duration":"151.07319ms","start":"2026-04-16T06:17:06.254727Z","end":"2026-04-16T06:17:06.4058Z","steps":["trace[938842388] 'range keys from in-memory index tree' (duration: 150.628517ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:06.547843Z","caller":"traceutil/trace.go:171","msg":"trace[887363893] transaction","detail":"{read_only:false; response_revision:3325; number_of_response:1; }","duration":"137.202201ms","start":"2026-04-16T06:17:06.410617Z","end":"2026-04-16T06:17:06.547819Z","steps":["trace[887363893] 'process raft request' (duration: 137.018726ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:17:06.565284Z","caller":"traceutil/trace.go:171","msg":"trace[1611767724] transaction","detail":"{read_only:false; response_revision:3326; number_of_response:1; }","duration":"151.380099ms","start":"2026-04-16T06:17:06.41387Z","end":"2026-04-16T06:17:06.565251Z","steps":["trace[1611767724] 'process raft request' (duration: 151.191824ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:18:54.756443Z","caller":"traceutil/trace.go:171","msg":"trace[1570967162] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"144.968043ms","start":"2026-04-16T06:18:54.611425Z","end":"2026-04-16T06:18:54.756393Z","steps":["trace[1570967162] 'process raft request' (duration: 144.641084ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:18:57.110619Z","caller":"traceutil/trace.go:171","msg":"trace[120650092] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"125.763956ms","start":"2026-04-16T06:18:56.984818Z","end":"2026-04-16T06:18:57.110582Z","steps":["trace[120650092] 'process raft request' (duration: 125.452607ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:19:05.459674Z","caller":"traceutil/trace.go:171","msg":"trace[1701111366] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"139.485729ms","start":"2026-04-16T06:19:05.320164Z","end":"2026-04-16T06:19:05.459649Z","steps":["trace[1701111366] 'read index received' (duration: 139.351146ms)","trace[1701111366] 'applied index is now lower than readState.Index' (duration: 133.613µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:19:05.459835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.630213ms","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-04-16T06:19:05.459873Z","caller":"traceutil/trace.go:171","msg":"trace[1074374391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4927; }","duration":"139.742316ms","start":"2026-04-16T06:19:05.320119Z","end":"2026-04-16T06:19:05.459861Z","steps":["trace[1074374391] 'agreement among raft nodes before linearized reading' (duration: 139.626603ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:19:05.4598Z","caller":"traceutil/trace.go:171","msg":"trace[146473736] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"139.760307ms","start":"2026-04-16T06:19:05.319999Z","end":"2026-04-16T06:19:05.459759Z","steps":["trace[146473736] 'process raft request' (duration: 139.523511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:19:06.000421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.927613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:19:06.001049Z","caller":"traceutil/trace.go:171","msg":"trace[1814870869] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"317.406855ms","start":"2026-04-16T06:19:05.683619Z","end":"2026-04-16T06:19:06.001025Z","steps":["trace[1814870869] 'process raft request' (duration: 317.298933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:19:06.00118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:19:05.683607Z","time spent":"317.492217ms","remote":"127.0.0.1:60122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:19:06.001609Z","caller":"traceutil/trace.go:171","msg":"trace[571710846] linearizableReadLoop","detail":"{readStateIndex:5147; appliedIndex:5145; }","duration":"164.573473ms","start":"2026-04-16T06:19:05.837008Z","end":"2026-04-16T06:19:06.001581Z","steps":["trace[571710846] 'read index received' (duration: 164.331996ms)","trace[571710846] 'applied index is now lower than readState.Index' (duration: 240.337µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:19:06.001002Z","caller":"traceutil/trace.go:171","msg":"trace[811934018] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"317.654992ms","start":"2026-04-16T06:19:05.683284Z","end":"2026-04-16T06:19:06.000939Z","steps":["trace[811934018] 'process raft request' (duration: 40.093792ms)","trace[811934018] 'compare' (duration: 276.696217ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:19:06.001766Z","caller":"traceutil/trace.go:171","msg":"trace[206380209] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"316.998334ms","start":"2026-04-16T06:19:05.684756Z","end":"2026-04-16T06:19:06.001754Z","steps":["trace[206380209] 'process raft request' (duration: 316.605594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:19:06.001962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:19:05.684747Z","time spent":"317.052556ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:19:06.00196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:19:05.683253Z","time spent":"318.487325ms","remote":"127.0.0.1:60046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:19:06.002041Z","caller":"traceutil/trace.go:171","msg":"trace[493355038] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"317.014535ms","start":"2026-04-16T06:19:05.685011Z","end":"2026-04-16T06:19:06.002025Z","steps":["trace[493355038] 'process raft request' (duration: 316.498071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:19:06.002141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:19:05.684987Z","time spent":"317.086986ms","remote":"127.0.0.1:60122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:19:06.002296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.245172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:15645"} {"level":"info","ts":"2026-04-16T06:19:06.002904Z","caller":"traceutil/trace.go:171","msg":"trace[412882376] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4933; }","duration":"165.411086ms","start":"2026-04-16T06:19:05.836947Z","end":"2026-04-16T06:19:06.002358Z","steps":["trace[412882376] 'agreement among raft nodes before linearized reading' (duration: 165.054926ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:19:09.650632Z","caller":"traceutil/trace.go:171","msg":"trace[1847759521] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"104.729722ms","start":"2026-04-16T06:19:09.545873Z","end":"2026-04-16T06:19:09.650602Z","steps":["trace[1847759521] 'process raft request' (duration: 94.322139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:19:17.649996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.15858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:19:17.650151Z","caller":"traceutil/trace.go:171","msg":"trace[668194992] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"218.324987ms","start":"2026-04-16T06:19:17.431795Z","end":"2026-04-16T06:19:17.65012Z","steps":["trace[668194992] 'process raft request' (duration: 78.93644ms)","trace[668194992] 'compare' (duration: 138.994066ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:19:28.206753Z","caller":"traceutil/trace.go:171","msg":"trace[1088058486] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"191.115206ms","start":"2026-04-16T06:19:28.015584Z","end":"2026-04-16T06:19:28.206699Z","steps":["trace[1088058486] 'process raft request' (duration: 98.090582ms)","trace[1088058486] 'compare' (duration: 92.51933ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:19:28.20694Z","caller":"traceutil/trace.go:171","msg":"trace[2018237484] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"180.662711ms","start":"2026-04-16T06:19:28.026258Z","end":"2026-04-16T06:19:28.206921Z","steps":["trace[2018237484] 'process raft request' (duration: 180.573468ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:19:28.206947Z","caller":"traceutil/trace.go:171","msg":"trace[746911235] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"191.078695ms","start":"2026-04-16T06:19:28.015845Z","end":"2026-04-16T06:19:28.206924Z","steps":["trace[746911235] 'process raft request' (duration: 190.857169ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:19:28.206941Z","caller":"traceutil/trace.go:171","msg":"trace[56713227] linearizableReadLoop","detail":"{readStateIndex:5556; appliedIndex:5552; }","duration":"141.892745ms","start":"2026-04-16T06:19:28.065016Z","end":"2026-04-16T06:19:28.206909Z","steps":["trace[56713227] 'read index received' (duration: 48.647185ms)","trace[56713227] 'applied index is now lower than readState.Index' (duration: 93.24415ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:19:28.206947Z","caller":"traceutil/trace.go:171","msg":"trace[487830860] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"191.134816ms","start":"2026-04-16T06:19:28.015767Z","end":"2026-04-16T06:19:28.206902Z","steps":["trace[487830860] 'process raft request' (duration: 190.797357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:19:28.207146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.105091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:19:28.207219Z","caller":"traceutil/trace.go:171","msg":"trace[1750764744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5329; }","duration":"142.236224ms","start":"2026-04-16T06:19:28.064963Z","end":"2026-04-16T06:19:28.207199Z","steps":["trace[1750764744] 'agreement among raft nodes before linearized reading' (duration: 142.056049ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:20:46.824636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2588} {"level":"info","ts":"2026-04-16T06:20:46.897411Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2588,"took":"70.866914ms","hash":3768714554,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":34607104,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-16T06:20:46.897473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3768714554,"revision":2588,"compact-revision":-1} {"level":"info","ts":"2026-04-16T06:25:00.938306Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021fb.wal"} {"level":"warn","ts":"2026-04-16T06:25:04.779912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.277468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:25:04.780107Z","caller":"traceutil/trace.go:171","msg":"trace[485947888] linearizableReadLoop","detail":"{readStateIndex:8723; appliedIndex:8722; }","duration":"278.341085ms","start":"2026-04-16T06:25:04.501736Z","end":"2026-04-16T06:25:04.780078Z","steps":["trace[485947888] 'read index received' (duration: 152.573964ms)","trace[485947888] 'applied index is now lower than readState.Index' (duration: 125.764651ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:25:04.780129Z","caller":"traceutil/trace.go:171","msg":"trace[1308585919] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"388.945841ms","start":"2026-04-16T06:25:04.391132Z","end":"2026-04-16T06:25:04.780078Z","steps":["trace[1308585919] 'process raft request' (duration: 263.333874ms)","trace[1308585919] 'compare' (duration: 124.938349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:25:04.780235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.465918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:25:04.780345Z","caller":"traceutil/trace.go:171","msg":"trace[1978219219] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8350; }","duration":"278.641223ms","start":"2026-04-16T06:25:04.501683Z","end":"2026-04-16T06:25:04.780324Z","steps":["trace[1978219219] 'agreement among raft nodes before linearized reading' (duration: 278.463428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:25:04.780349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:25:04.391107Z","time spent":"389.118446ms","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:25:04.781396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.681163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:25:04.781479Z","caller":"traceutil/trace.go:171","msg":"trace[286010723] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8351; }","duration":"252.795877ms","start":"2026-04-16T06:25:04.52866Z","end":"2026-04-16T06:25:04.781456Z","steps":["trace[286010723] 'agreement among raft nodes before linearized reading' (duration: 252.624152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:25:04.781734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.198509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r7df7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-16T06:25:04.781815Z","caller":"traceutil/trace.go:171","msg":"trace[993166689] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r7df7; range_end:; response_count:1; response_revision:8351; }","duration":"241.313912ms","start":"2026-04-16T06:25:04.540487Z","end":"2026-04-16T06:25:04.781801Z","steps":["trace[993166689] 'agreement among raft nodes before linearized reading' (duration: 241.121417ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:16.373308Z","caller":"traceutil/trace.go:171","msg":"trace[64222901] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"120.660952ms","start":"2026-04-16T06:25:16.252622Z","end":"2026-04-16T06:25:16.373283Z","steps":["trace[64222901] 'process raft request' (duration: 120.474107ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:16.836141Z","caller":"traceutil/trace.go:171","msg":"trace[2066445882] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"254.602086ms","start":"2026-04-16T06:25:16.581511Z","end":"2026-04-16T06:25:16.836113Z","steps":["trace[2066445882] 'process raft request' (duration: 254.411521ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:16.836223Z","caller":"traceutil/trace.go:171","msg":"trace[1802044676] linearizableReadLoop","detail":"{readStateIndex:8809; appliedIndex:8809; }","duration":"254.570495ms","start":"2026-04-16T06:25:16.581615Z","end":"2026-04-16T06:25:16.836185Z","steps":["trace[1802044676] 'read index received' (duration: 254.555324ms)","trace[1802044676] 'applied index is now lower than readState.Index' (duration: 11.981µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:25:16.836433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.77675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:25:16.836476Z","caller":"traceutil/trace.go:171","msg":"trace[314902960] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8430; }","duration":"254.904984ms","start":"2026-04-16T06:25:16.581562Z","end":"2026-04-16T06:25:16.836467Z","steps":["trace[314902960] 'agreement among raft nodes before linearized reading' (duration: 254.741709ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:44.807406Z","caller":"traceutil/trace.go:171","msg":"trace[1597245201] linearizableReadLoop","detail":"{readStateIndex:9058; appliedIndex:9057; }","duration":"101.769616ms","start":"2026-04-16T06:25:44.705607Z","end":"2026-04-16T06:25:44.807376Z","steps":["trace[1597245201] 'read index received' (duration: 101.599901ms)","trace[1597245201] 'applied index is now lower than readState.Index' (duration: 168.315µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:25:44.807448Z","caller":"traceutil/trace.go:171","msg":"trace[118413231] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"176.455937ms","start":"2026-04-16T06:25:44.630956Z","end":"2026-04-16T06:25:44.807412Z","steps":["trace[118413231] 'process raft request' (duration: 176.223541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:25:44.807634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.013152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-16T06:25:44.8077Z","caller":"traceutil/trace.go:171","msg":"trace[1385030702] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8668; }","duration":"102.126685ms","start":"2026-04-16T06:25:44.70555Z","end":"2026-04-16T06:25:44.807677Z","steps":["trace[1385030702] 'agreement among raft nodes before linearized reading' (duration: 101.94736ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:46.831466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6062} {"level":"info","ts":"2026-04-16T06:25:46.925821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6062,"took":"92.82039ms","hash":4186388767,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":25550848,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-16T06:25:46.925885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4186388767,"revision":6062,"compact-revision":2588} {"level":"info","ts":"2026-04-16T06:25:58.307839Z","caller":"traceutil/trace.go:171","msg":"trace[168970220] linearizableReadLoop","detail":"{readStateIndex:9204; appliedIndex:9202; }","duration":"110.027022ms","start":"2026-04-16T06:25:58.19779Z","end":"2026-04-16T06:25:58.307817Z","steps":["trace[168970220] 'read index received' (duration: 64.483681ms)","trace[168970220] 'applied index is now lower than readState.Index' (duration: 45.542571ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:25:58.307971Z","caller":"traceutil/trace.go:171","msg":"trace[48107] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"110.208718ms","start":"2026-04-16T06:25:58.197734Z","end":"2026-04-16T06:25:58.307942Z","steps":["trace[48107] 'process raft request' (duration: 109.887279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:25:58.308121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.305851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-jpqcn\" ","response":"range_response_count:1 size:4615"} {"level":"info","ts":"2026-04-16T06:25:58.308246Z","caller":"traceutil/trace.go:171","msg":"trace[1639371667] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-jpqcn; range_end:; response_count:1; response_revision:8805; }","duration":"110.424864ms","start":"2026-04-16T06:25:58.19774Z","end":"2026-04-16T06:25:58.308165Z","steps":["trace[1639371667] 'agreement among raft nodes before linearized reading' (duration: 110.237539ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:59.050463Z","caller":"traceutil/trace.go:171","msg":"trace[1165086251] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"171.593793ms","start":"2026-04-16T06:25:58.878836Z","end":"2026-04-16T06:25:59.05043Z","steps":["trace[1165086251] 'process raft request' (duration: 171.363837ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:59.050465Z","caller":"traceutil/trace.go:171","msg":"trace[285463898] linearizableReadLoop","detail":"{readStateIndex:9207; appliedIndex:9206; }","duration":"155.014798ms","start":"2026-04-16T06:25:58.895385Z","end":"2026-04-16T06:25:59.0504Z","steps":["trace[285463898] 'read index received' (duration: 154.892095ms)","trace[285463898] 'applied index is now lower than readState.Index' (duration: 120.793µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:25:59.050817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.402909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-16T06:25:59.050882Z","caller":"traceutil/trace.go:171","msg":"trace[754195387] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8807; }","duration":"155.505792ms","start":"2026-04-16T06:25:58.895359Z","end":"2026-04-16T06:25:59.050865Z","steps":["trace[754195387] 'agreement among raft nodes before linearized reading' (duration: 155.231235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:25:59.238233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.347328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T06:25:59.238509Z","caller":"traceutil/trace.go:171","msg":"trace[178327597] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"182.616537ms","start":"2026-04-16T06:25:59.055869Z","end":"2026-04-16T06:25:59.238486Z","steps":["trace[178327597] 'process raft request' (duration: 182.506324ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:25:59.238527Z","caller":"traceutil/trace.go:171","msg":"trace[639782394] linearizableReadLoop","detail":"{readStateIndex:9208; appliedIndex:9207; }","duration":"187.937112ms","start":"2026-04-16T06:25:59.050554Z","end":"2026-04-16T06:25:59.238491Z","steps":["trace[639782394] 'read index received' (duration: 84.230833ms)","trace[639782394] 'applied index is now lower than readState.Index' (duration: 103.701839ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:25:59.238566Z","caller":"traceutil/trace.go:171","msg":"trace[741128730] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"192.172429ms","start":"2026-04-16T06:25:59.046351Z","end":"2026-04-16T06:25:59.238524Z","steps":["trace[741128730] 'process raft request' (duration: 88.409059ms)","trace[741128730] 'compare' (duration: 102.948088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:25:59.238862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.515809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-16T06:25:59.238906Z","caller":"traceutil/trace.go:171","msg":"trace[2137856053] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8809; }","duration":"255.599731ms","start":"2026-04-16T06:25:58.98329Z","end":"2026-04-16T06:25:59.23889Z","steps":["trace[2137856053] 'agreement among raft nodes before linearized reading' (duration: 255.365505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:25:59.239051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.539559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:25:59.239097Z","caller":"traceutil/trace.go:171","msg":"trace[1422941594] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8809; }","duration":"125.651571ms","start":"2026-04-16T06:25:59.113432Z","end":"2026-04-16T06:25:59.239083Z","steps":["trace[1422941594] 'agreement among raft nodes before linearized reading' (duration: 125.544328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:25:59.239151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.201275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:25:59.239199Z","caller":"traceutil/trace.go:171","msg":"trace[439693742] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8809; }","duration":"277.306427ms","start":"2026-04-16T06:25:58.961878Z","end":"2026-04-16T06:25:59.239184Z","steps":["trace[439693742] 'agreement among raft nodes before linearized reading' (duration: 277.195264ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:26:56.728743Z","caller":"traceutil/trace.go:171","msg":"trace[1538447841] transaction","detail":"{read_only:false; response_revision:9466; number_of_response:1; }","duration":"114.709091ms","start":"2026-04-16T06:26:56.613995Z","end":"2026-04-16T06:26:56.728704Z","steps":["trace[1538447841] 'process raft request' (duration: 114.412813ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:27:10.054694Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T06:27:10.060207Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T06:27:10.060312Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T06:30:46.839026Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8682} {"level":"info","ts":"2026-04-16T06:30:46.919221Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8682,"took":"78.028369ms","hash":2593309181,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":25440256,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T06:30:46.919296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2593309181,"revision":8682,"compact-revision":6062} {"level":"info","ts":"2026-04-16T06:35:46.847002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11439} {"level":"info","ts":"2026-04-16T06:35:46.922333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11439,"took":"72.867871ms","hash":1972602116,"current-db-size-bytes":57913344,"current-db-size":"58 MB","current-db-size-in-use-bytes":23273472,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T06:35:46.922506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1972602116,"revision":11439,"compact-revision":8682} {"level":"warn","ts":"2026-04-16T06:35:59.999741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458549283,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T06:36:00.18697Z","caller":"traceutil/trace.go:171","msg":"trace[977999472] linearizableReadLoop","detail":"{readStateIndex:14138; appliedIndex:14137; }","duration":"687.815535ms","start":"2026-04-16T06:35:59.499132Z","end":"2026-04-16T06:36:00.186948Z","steps":["trace[977999472] 'read index received' (duration: 687.583998ms)","trace[977999472] 'applied index is now lower than readState.Index' (duration: 230.517µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:36:00.187088Z","caller":"traceutil/trace.go:171","msg":"trace[284142479] transaction","detail":"{read_only:false; response_revision:13482; number_of_response:1; }","duration":"810.065524ms","start":"2026-04-16T06:35:59.377011Z","end":"2026-04-16T06:36:00.187077Z","steps":["trace[284142479] 'process raft request' (duration: 809.783777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:00.18721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:59.376999Z","time spent":"810.114806ms","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:36:00.187435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.316008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-16T06:36:00.187479Z","caller":"traceutil/trace.go:171","msg":"trace[1433646342] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13482; }","duration":"688.417031ms","start":"2026-04-16T06:35:59.499051Z","end":"2026-04-16T06:36:00.187468Z","steps":["trace[1433646342] 'agreement among raft nodes before linearized reading' (duration: 688.285637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:00.187513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:59.499025Z","time spent":"688.481172ms","remote":"127.0.0.1:60008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-16T06:36:00.187526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.697361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"warn","ts":"2026-04-16T06:36:00.187545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.664659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r7df7\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-16T06:36:00.187625Z","caller":"traceutil/trace.go:171","msg":"trace[2103052725] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r7df7; range_end:; response_count:1; response_revision:13482; }","duration":"661.807964ms","start":"2026-04-16T06:35:59.525798Z","end":"2026-04-16T06:36:00.187606Z","steps":["trace[2103052725] 'agreement among raft nodes before linearized reading' (duration: 661.559487ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:36:00.187633Z","caller":"traceutil/trace.go:171","msg":"trace[2091062460] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13482; }","duration":"373.852295ms","start":"2026-04-16T06:35:59.81372Z","end":"2026-04-16T06:36:00.187572Z","steps":["trace[2091062460] 'agreement among raft nodes before linearized reading' (duration: 373.66715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:00.18768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:59.525774Z","time spent":"661.894465ms","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-r7df7\" "} {"level":"warn","ts":"2026-04-16T06:36:00.187686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.746897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:138"} {"level":"warn","ts":"2026-04-16T06:36:00.187682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:59.813688Z","time spent":"373.982889ms","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11522,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-16T06:36:00.187773Z","caller":"traceutil/trace.go:171","msg":"trace[1650621809] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:13482; }","duration":"147.899182ms","start":"2026-04-16T06:36:00.039853Z","end":"2026-04-16T06:36:00.187752Z","steps":["trace[1650621809] 'agreement among raft nodes before linearized reading' (duration: 147.664575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:00.187865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.790232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/barbican-db-sync\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-04-16T06:36:00.187862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.242441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T06:36:00.187884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.879995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:546"} {"level":"info","ts":"2026-04-16T06:36:00.187916Z","caller":"traceutil/trace.go:171","msg":"trace[1151402381] range","detail":"{range_begin:/registry/serviceaccounts/openstack/barbican-db-sync; range_end:; response_count:1; response_revision:13482; }","duration":"598.863074ms","start":"2026-04-16T06:35:59.589038Z","end":"2026-04-16T06:36:00.187901Z","steps":["trace[1151402381] 'agreement among raft nodes before linearized reading' (duration: 598.71615ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:36:00.18794Z","caller":"traceutil/trace.go:171","msg":"trace[337662648] range","detail":"{range_begin:/registry/serviceaccounts/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:13482; }","duration":"594.985917ms","start":"2026-04-16T06:35:59.592936Z","end":"2026-04-16T06:36:00.187922Z","steps":["trace[337662648] 'agreement among raft nodes before linearized reading' (duration: 594.845654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:00.187944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:59.589025Z","time spent":"598.914265ms","remote":"127.0.0.1:60072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":558,"request content":"key:\"/registry/serviceaccounts/openstack/barbican-db-sync\" "} {"level":"info","ts":"2026-04-16T06:36:00.187946Z","caller":"traceutil/trace.go:171","msg":"trace[1303295276] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:13482; }","duration":"656.340394ms","start":"2026-04-16T06:35:59.531578Z","end":"2026-04-16T06:36:00.187918Z","steps":["trace[1303295276] 'agreement among raft nodes before linearized reading' (duration: 656.19703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:00.18797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:59.592893Z","time spent":"595.070101ms","remote":"127.0.0.1:60072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":568,"request content":"key:\"/registry/serviceaccounts/openstack/barbican-ks-endpoints\" "} {"level":"warn","ts":"2026-04-16T06:36:00.187994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:35:59.531571Z","time spent":"656.411996ms","remote":"127.0.0.1:45354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:36:04.874278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.462174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-api-certs\" ","response":"range_response_count:1 size:4328"} {"level":"info","ts":"2026-04-16T06:36:04.874511Z","caller":"traceutil/trace.go:171","msg":"trace[820631227] range","detail":"{range_begin:/registry/secrets/openstack/keystone-api-certs; range_end:; response_count:1; response_revision:13517; }","duration":"123.769152ms","start":"2026-04-16T06:36:04.750705Z","end":"2026-04-16T06:36:04.874474Z","steps":["trace[820631227] 'range keys from in-memory index tree' (duration: 123.125495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:04.87566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.926446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:385267"} {"level":"info","ts":"2026-04-16T06:36:04.875734Z","caller":"traceutil/trace.go:171","msg":"trace[1629884519] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:13517; }","duration":"113.058459ms","start":"2026-04-16T06:36:04.762661Z","end":"2026-04-16T06:36:04.87572Z","steps":["trace[1629884519] 'range keys from in-memory index tree' (duration: 111.453565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:20.091873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:20.592158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:21.093082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:21.593089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000614236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T06:36:21.593178Z","caller":"traceutil/trace.go:171","msg":"trace[1481275569] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00073838s","start":"2026-04-16T06:36:19.59242Z","end":"2026-04-16T06:36:21.593158Z","steps":["trace[1481275569] 'agreement among raft nodes before linearized reading' (duration: 2.000620166s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:21.59322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:21.59327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:19.592415Z","time spent":"2.000841152s","remote":"127.0.0.1:59886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/16 06:36:21 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-16T06:36:22.093409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:22.59403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:23.095087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:23.894759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744047458550411,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T06:36:23.895024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.301530741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T06:36:23.895102Z","caller":"traceutil/trace.go:171","msg":"trace[1059145595] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.301663524s","start":"2026-04-16T06:36:21.593419Z","end":"2026-04-16T06:36:23.895082Z","steps":["trace[1059145595] 'agreement among raft nodes before linearized reading' (duration: 2.301563832s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.895141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:21.593203Z","time spent":"2.301929472s","remote":"127.0.0.1:59896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T06:36:23.938925Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.371857109s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T06:36:23.961645Z","caller":"traceutil/trace.go:171","msg":"trace[859792548] transaction","detail":"{read_only:false; response_revision:13609; number_of_response:1; }","duration":"4.394691677s","start":"2026-04-16T06:36:19.566923Z","end":"2026-04-16T06:36:23.961615Z","steps":["trace[859792548] 'process raft request' (duration: 4.372196538s)","trace[859792548] 'compare' (duration: 19.849827ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T06:36:23.961721Z","caller":"traceutil/trace.go:171","msg":"trace[122197891] linearizableReadLoop","detail":"{readStateIndex:14275; appliedIndex:14272; }","duration":"4.37088448s","start":"2026-04-16T06:36:19.590808Z","end":"2026-04-16T06:36:23.961693Z","steps":["trace[122197891] 'read index received' (duration: 4.348315688s)","trace[122197891] 'applied index is now lower than readState.Index' (duration: 22.567921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T06:36:23.9619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:19.5669Z","time spent":"4.394820831s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:36:23.961998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.369463091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T06:36:23.962054Z","caller":"traceutil/trace.go:171","msg":"trace[42021554] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:13611; }","duration":"4.369552513s","start":"2026-04-16T06:36:19.592489Z","end":"2026-04-16T06:36:23.962042Z","steps":["trace[42021554] 'agreement among raft nodes before linearized reading' (duration: 4.36943266s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:36:23.962036Z","caller":"traceutil/trace.go:171","msg":"trace[1767607887] transaction","detail":"{read_only:false; response_revision:13611; number_of_response:1; }","duration":"3.527111942s","start":"2026-04-16T06:36:20.434892Z","end":"2026-04-16T06:36:23.962004Z","steps":["trace[1767607887] 'process raft request' (duration: 3.526694441s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:19.592486Z","time spent":"4.369600484s","remote":"127.0.0.1:60100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":23,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:36:23.962157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.434874Z","time spent":"3.527220255s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T06:36:23.962275Z","caller":"traceutil/trace.go:171","msg":"trace[806523782] transaction","detail":"{read_only:false; response_revision:13610; number_of_response:1; }","duration":"3.705945651s","start":"2026-04-16T06:36:20.25632Z","end":"2026-04-16T06:36:23.962265Z","steps":["trace[806523782] 'process raft request' (duration: 3.7051838s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.256288Z","time spent":"3.706011483s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T06:36:23.962405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.251165049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-04-16T06:36:23.962459Z","caller":"traceutil/trace.go:171","msg":"trace[120263472] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13611; }","duration":"3.251249151s","start":"2026-04-16T06:36:20.711197Z","end":"2026-04-16T06:36:23.962446Z","steps":["trace[120263472] 'agreement among raft nodes before linearized reading' (duration: 3.251085047s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.543630448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-04-16T06:36:23.96249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.711179Z","time spent":"3.251304642s","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11522,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-16T06:36:23.962489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012081511s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:36:23.962509Z","caller":"traceutil/trace.go:171","msg":"trace[1839318202] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13611; }","duration":"3.543744352s","start":"2026-04-16T06:36:20.41875Z","end":"2026-04-16T06:36:23.962494Z","steps":["trace[1839318202] 'agreement among raft nodes before linearized reading' (duration: 3.543540936s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.418743Z","time spent":"3.543798753s","remote":"127.0.0.1:60008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-16T06:36:23.962574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.673518995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-16T06:36:23.962615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737950635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T06:36:23.962628Z","caller":"traceutil/trace.go:171","msg":"trace[3675111] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:13611; }","duration":"3.673601228s","start":"2026-04-16T06:36:20.289014Z","end":"2026-04-16T06:36:23.962615Z","steps":["trace[3675111] 'agreement among raft nodes before linearized reading' (duration: 3.673430583s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:36:23.962648Z","caller":"traceutil/trace.go:171","msg":"trace[1445125632] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:13611; }","duration":"1.738018817s","start":"2026-04-16T06:36:22.224619Z","end":"2026-04-16T06:36:23.962638Z","steps":["trace[1445125632] 'agreement among raft nodes before linearized reading' (duration: 1.737970525s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.289Z","time spent":"3.67365314s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-16T06:36:23.962704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.558360559s","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-04-16T06:36:23.96273Z","caller":"traceutil/trace.go:171","msg":"trace[1749307369] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:13611; }","duration":"2.558415441s","start":"2026-04-16T06:36:21.404307Z","end":"2026-04-16T06:36:23.962722Z","steps":["trace[1749307369] 'agreement among raft nodes before linearized reading' (duration: 2.55837666s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:21.404288Z","time spent":"2.558462013s","remote":"127.0.0.1:45462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:36:23.962451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.371388524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-04-16T06:36:23.962887Z","caller":"traceutil/trace.go:171","msg":"trace[115345783] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:13611; }","duration":"4.372069572s","start":"2026-04-16T06:36:19.590774Z","end":"2026-04-16T06:36:23.962843Z","steps":["trace[115345783] 'agreement among raft nodes before linearized reading' (duration: 4.371127396s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.55061135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T06:36:23.962519Z","caller":"traceutil/trace.go:171","msg":"trace[1467461443] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:13611; }","duration":"1.012145253s","start":"2026-04-16T06:36:22.950365Z","end":"2026-04-16T06:36:23.96251Z","steps":["trace[1467461443] 'agreement among raft nodes before linearized reading' (duration: 1.012094412s)"],"step_count":1} {"level":"info","ts":"2026-04-16T06:36:23.962938Z","caller":"traceutil/trace.go:171","msg":"trace[638411158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13611; }","duration":"3.550667093s","start":"2026-04-16T06:36:20.41226Z","end":"2026-04-16T06:36:23.962927Z","steps":["trace[638411158] 'agreement among raft nodes before linearized reading' (duration: 3.5505666s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:22.950338Z","time spent":"1.012604746s","remote":"127.0.0.1:45394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:36:23.962945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.182653366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T06:36:23.963017Z","caller":"traceutil/trace.go:171","msg":"trace[508968252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13611; }","duration":"3.18277795s","start":"2026-04-16T06:36:20.780223Z","end":"2026-04-16T06:36:23.963001Z","steps":["trace[508968252] 'agreement among raft nodes before linearized reading' (duration: 3.182607335s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.963026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:19.590753Z","time spent":"4.372178626s","remote":"127.0.0.1:60100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5151,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-04-16T06:36:23.963048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.551344341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-16T06:36:23.963086Z","caller":"traceutil/trace.go:171","msg":"trace[1972828850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13611; }","duration":"3.551407893s","start":"2026-04-16T06:36:20.411668Z","end":"2026-04-16T06:36:23.963076Z","steps":["trace[1972828850] 'agreement among raft nodes before linearized reading' (duration: 3.55132511s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.963116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.411649Z","time spent":"3.551459944s","remote":"127.0.0.1:60116","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-04-16T06:36:23.962671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:22.224599Z","time spent":"1.738065438s","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-16T06:36:23.963216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.622705642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r7df7\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-04-16T06:36:23.963052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.780197Z","time spent":"3.182849492s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T06:36:23.963282Z","caller":"traceutil/trace.go:171","msg":"trace[1225222432] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r7df7; range_end:; response_count:1; response_revision:13611; }","duration":"3.622790794s","start":"2026-04-16T06:36:20.34048Z","end":"2026-04-16T06:36:23.96327Z","steps":["trace[1225222432] 'agreement among raft nodes before linearized reading' (duration: 3.622671501s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.96333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.340469Z","time spent":"3.622856076s","remote":"127.0.0.1:60060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-r7df7\" "} {"level":"warn","ts":"2026-04-16T06:36:23.962895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.699712409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-04-16T06:36:23.963446Z","caller":"traceutil/trace.go:171","msg":"trace[135760615] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:13611; }","duration":"3.700298176s","start":"2026-04-16T06:36:20.263142Z","end":"2026-04-16T06:36:23.96344Z","steps":["trace[135760615] 'agreement among raft nodes before linearized reading' (duration: 3.699682009s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.963475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.263125Z","time spent":"3.700344457s","remote":"127.0.0.1:59926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-04-16T06:36:23.962798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.680483989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-16T06:36:23.96341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.552651806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T06:36:23.963719Z","caller":"traceutil/trace.go:171","msg":"trace[517489999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13611; }","duration":"3.552922694s","start":"2026-04-16T06:36:20.410724Z","end":"2026-04-16T06:36:23.963647Z","steps":["trace[517489999] 'agreement among raft nodes before linearized reading' (duration: 3.552272216s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.962975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.412252Z","time spent":"3.550715144s","remote":"127.0.0.1:60116","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-04-16T06:36:23.963522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.764596928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T06:36:23.963841Z","caller":"traceutil/trace.go:171","msg":"trace[748924361] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:13611; }","duration":"2.764937017s","start":"2026-04-16T06:36:21.198879Z","end":"2026-04-16T06:36:23.963816Z","steps":["trace[748924361] 'agreement among raft nodes before linearized reading' (duration: 2.764090564s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.963904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:21.198862Z","time spent":"2.765014799s","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1010,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-16T06:36:23.963559Z","caller":"traceutil/trace.go:171","msg":"trace[1223559319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13611; }","duration":"3.68126896s","start":"2026-04-16T06:36:20.282278Z","end":"2026-04-16T06:36:23.963547Z","steps":["trace[1223559319] 'agreement among raft nodes before linearized reading' (duration: 3.680443547s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T06:36:23.964038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.282265Z","time spent":"3.681760884s","remote":"127.0.0.1:60116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T06:36:23.963875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T06:36:20.410712Z","time spent":"3.55314978s","remote":"127.0.0.1:60116","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\" "}