{"level":"warn","ts":"2026-04-01T23:27:47.567975Z","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-01T23:27:47.568068Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-01T23:27:47.568137Z","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-01T23:27:47.568149Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-01T23:27:47.568175Z","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-01T23:27:47.568824Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-04-01T23:27:47.568928Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-01T23:27:47.659484Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"90.326112ms"} {"level":"info","ts":"2026-04-01T23:27:47.942947Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-04-01T23:27:47.943162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T23:27:47.943209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-04-01T23:27:47.94323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T23:27:47.943255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-04-01T23:27:47.943341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-04-01T23:27:47.992481Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T23:27:47.996179Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T23:27:48.000342Z","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-01T23:27:48.009622Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T23:27:48.009839Z","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-01T23:27:48.009973Z","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-01T23:27:48.010011Z","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-01T23:27:48.01022Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T23:27:48.015831Z","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-01T23:27:48.016Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-01T23:27:48.01609Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-04-01T23:27:48.016153Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T23:27:48.016199Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T23:27:48.02366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-04-01T23:27:48.023773Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-04-01T23:27:48.946784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T23:27:48.946865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T23:27:48.946917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-04-01T23:27:48.946937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-04-01T23:27:48.94695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-01T23:27:48.94697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-04-01T23:27:48.947014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-04-01T23:27:48.948198Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T23:27:48.948257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:27:48.948295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:27:48.948373Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:48.948652Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T23:27:48.948741Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T23:27:48.949503Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:48.949636Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:48.949673Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:27:48.951573Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-04-01T23:27:48.952068Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T23:27:54.8842Z","caller":"traceutil/trace.go:171","msg":"trace[133442334] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"117.431878ms","start":"2026-04-01T23:27:54.766748Z","end":"2026-04-01T23:27:54.88418Z","steps":["trace[133442334] 'process raft request' (duration: 117.311115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:05.813887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.682955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:28:05.814545Z","caller":"traceutil/trace.go:171","msg":"trace[410840804] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"793.720353ms","start":"2026-04-01T23:28:05.020805Z","end":"2026-04-01T23:28:05.814526Z","steps":["trace[410840804] 'process raft request' (duration: 793.642171ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.815274Z","caller":"traceutil/trace.go:171","msg":"trace[797780534] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"794.495821ms","start":"2026-04-01T23:28:05.02011Z","end":"2026-04-01T23:28:05.814606Z","steps":["trace[797780534] 'process raft request' (duration: 794.220434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:05.815396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.020791Z","time spent":"793.805994ms","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:05.815608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.020091Z","time spent":"795.346971ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:05.815647Z","caller":"traceutil/trace.go:171","msg":"trace[682054119] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:435; }","duration":"458.091608ms","start":"2026-04-01T23:28:05.357442Z","end":"2026-04-01T23:28:05.815534Z","steps":["trace[682054119] 'read index received' (duration: 77.012µs)","trace[682054119] 'applied index is now lower than readState.Index' (duration: 458.011266ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:05.816029Z","caller":"traceutil/trace.go:171","msg":"trace[1222471027] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"794.965802ms","start":"2026-04-01T23:28:05.021027Z","end":"2026-04-01T23:28:05.815993Z","steps":["trace[1222471027] 'process raft request' (duration: 793.465196ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:05.816295Z","caller":"traceutil/trace.go:171","msg":"trace[1210900341] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"795.560646ms","start":"2026-04-01T23:28:05.019072Z","end":"2026-04-01T23:28:05.814632Z","steps":["trace[1210900341] 'process raft request' (duration: 275.57076ms)","trace[1210900341] 'compare' (duration: 518.437038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:05.816523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.01904Z","time spent":"797.311428ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:05.819102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.652833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T23:28:05.819368Z","caller":"traceutil/trace.go:171","msg":"trace[1903234108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"461.976561ms","start":"2026-04-01T23:28:05.357364Z","end":"2026-04-01T23:28:05.819341Z","steps":["trace[1903234108] 'agreement among raft nodes before linearized reading' (duration: 458.779064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:05.81944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.357331Z","time spent":"462.093654ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:28:05.819187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.021017Z","time spent":"795.219227ms","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:06.325059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800177090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:28:06.770692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.834437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:28:06.770812Z","caller":"traceutil/trace.go:171","msg":"trace[1533494188] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"946.48597ms","start":"2026-04-01T23:28:05.824306Z","end":"2026-04-01T23:28:06.770792Z","steps":["trace[1533494188] 'process raft request' (duration: 532.448448ms)","trace[1533494188] 'compare' (duration: 413.707604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:06.770933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.824285Z","time spent":"946.623424ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:07.181255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.912969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:28:07.181546Z","caller":"traceutil/trace.go:171","msg":"trace[1732606245] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:439; }","duration":"1.356824323s","start":"2026-04-01T23:28:05.824698Z","end":"2026-04-01T23:28:07.181522Z","steps":["trace[1732606245] 'read index received' (duration: 532.060329ms)","trace[1732606245] 'applied index is now lower than readState.Index' (duration: 824.762814ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:07.181535Z","caller":"traceutil/trace.go:171","msg":"trace[347051423] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"1.356837934s","start":"2026-04-01T23:28:05.824661Z","end":"2026-04-01T23:28:07.181499Z","steps":["trace[347051423] 'process raft request' (duration: 1.049566116s)","trace[347051423] 'compare' (duration: 306.722585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:07.181707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.824641Z","time spent":"1.356967876s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:07.18172Z","caller":"traceutil/trace.go:171","msg":"trace[518792830] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"1.355804668s","start":"2026-04-01T23:28:05.825871Z","end":"2026-04-01T23:28:07.181675Z","steps":["trace[518792830] 'process raft request' (duration: 1.355586433s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.181816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234935112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:07.181855Z","caller":"traceutil/trace.go:171","msg":"trace[655102037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"1.235018794s","start":"2026-04-01T23:28:05.946825Z","end":"2026-04-01T23:28:07.181844Z","steps":["trace[655102037] 'agreement among raft nodes before linearized reading' (duration: 1.234948212s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.181881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.9468Z","time spent":"1.235075726s","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:28:07.181905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.825859Z","time spent":"1.355943831s","remote":"127.0.0.1:40152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:07.1819Z","caller":"traceutil/trace.go:171","msg":"trace[1306594874] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"1.357162201s","start":"2026-04-01T23:28:05.824672Z","end":"2026-04-01T23:28:07.181834Z","steps":["trace[1306594874] 'process raft request' (duration: 1.35671502s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.181942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.86931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:28:07.182007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356032263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-01T23:28:07.182031Z","caller":"traceutil/trace.go:171","msg":"trace[1927114351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"656.961812ms","start":"2026-04-01T23:28:06.525047Z","end":"2026-04-01T23:28:07.182009Z","steps":["trace[1927114351] 'agreement among raft nodes before linearized reading' (duration: 656.746727ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:07.182066Z","caller":"traceutil/trace.go:171","msg":"trace[141362239] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:427; }","duration":"1.356102855s","start":"2026-04-01T23:28:05.825944Z","end":"2026-04-01T23:28:07.182047Z","steps":["trace[141362239] 'agreement among raft nodes before linearized reading' (duration: 1.355790397s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.181916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.569885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-01T23:28:07.182084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:06.524963Z","time spent":"657.108775ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:28:07.182143Z","caller":"traceutil/trace.go:171","msg":"trace[1231029783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"728.853962ms","start":"2026-04-01T23:28:06.453256Z","end":"2026-04-01T23:28:07.18211Z","steps":["trace[1231029783] 'agreement among raft nodes before linearized reading' (duration: 728.497324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.182096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.824663Z","time spent":"1.357351856s","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:07.182118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.825936Z","time spent":"1.356172206s","remote":"127.0.0.1:40122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4219,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-01T23:28:07.182175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:06.453233Z","time spent":"728.931373ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:28:07.182735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358032172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-8fkcc\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-01T23:28:07.18279Z","caller":"traceutil/trace.go:171","msg":"trace[1657055908] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-8fkcc; range_end:; response_count:1; response_revision:427; }","duration":"1.358085643s","start":"2026-04-01T23:28:05.82469Z","end":"2026-04-01T23:28:07.182776Z","steps":["trace[1657055908] 'agreement among raft nodes before linearized reading' (duration: 1.356960936s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:07.182823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:05.824634Z","time spent":"1.358181435s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4040,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-8fkcc\" "} {"level":"info","ts":"2026-04-01T23:28:13.228109Z","caller":"traceutil/trace.go:171","msg":"trace[955621690] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"427.005666ms","start":"2026-04-01T23:28:12.801082Z","end":"2026-04-01T23:28:13.228087Z","steps":["trace[955621690] 'process raft request' (duration: 426.661797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:13.228247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:12.801053Z","time spent":"427.115387ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:16.248952Z","caller":"traceutil/trace.go:171","msg":"trace[1799029440] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"932.112101ms","start":"2026-04-01T23:28:15.316815Z","end":"2026-04-01T23:28:16.248928Z","steps":["trace[1799029440] 'process raft request' (duration: 844.526432ms)","trace[1799029440] 'compare' (duration: 87.332293ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:16.249086Z","caller":"traceutil/trace.go:171","msg":"trace[1389727030] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:482; }","duration":"300.028863ms","start":"2026-04-01T23:28:15.949046Z","end":"2026-04-01T23:28:16.249075Z","steps":["trace[1389727030] 'read index received' (duration: 212.307799ms)","trace[1389727030] 'applied index is now lower than readState.Index' (duration: 87.720374ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:16.249105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.31679Z","time spent":"932.244176ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:16.249164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.123516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:16.249188Z","caller":"traceutil/trace.go:171","msg":"trace[796816974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"300.185976ms","start":"2026-04-01T23:28:15.948993Z","end":"2026-04-01T23:28:16.249179Z","steps":["trace[796816974] 'agreement among raft nodes before linearized reading' (duration: 300.122065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:16.249208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.948964Z","time spent":"300.239698ms","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T23:28:16.249248Z","caller":"traceutil/trace.go:171","msg":"trace[331118045] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"932.379629ms","start":"2026-04-01T23:28:15.316832Z","end":"2026-04-01T23:28:16.249212Z","steps":["trace[331118045] 'process raft request' (duration: 932.02446ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:16.249386Z","caller":"traceutil/trace.go:171","msg":"trace[395656262] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"932.514602ms","start":"2026-04-01T23:28:15.316861Z","end":"2026-04-01T23:28:16.249376Z","steps":["trace[395656262] 'process raft request' (duration: 932.159743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:16.24942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.316815Z","time spent":"932.524782ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:16.249448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:15.316834Z","time spent":"932.581084ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:20.715851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.38987Z","time spent":"325.97616ms","remote":"127.0.0.1:56102","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-01T23:28:21.450977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800177261,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:28:21.482821Z","caller":"traceutil/trace.go:171","msg":"trace[980755789] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"980.30698ms","start":"2026-04-01T23:28:20.50247Z","end":"2026-04-01T23:28:21.482777Z","steps":["trace[980755789] 'process raft request' (duration: 980.049054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:21.483064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.502448Z","time spent":"980.504044ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:28:21.552705Z","caller":"traceutil/trace.go:171","msg":"trace[1967025190] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.0489159s","start":"2026-04-01T23:28:20.503742Z","end":"2026-04-01T23:28:21.552658Z","steps":["trace[1967025190] 'process raft request' (duration: 1.048585922s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.552764Z","caller":"traceutil/trace.go:171","msg":"trace[2090406887] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.046559394s","start":"2026-04-01T23:28:20.506158Z","end":"2026-04-01T23:28:21.552718Z","steps":["trace[2090406887] 'process raft request' (duration: 1.046358419s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:21.552929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.503734Z","time spent":"1.049076535s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.552962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.506149Z","time spent":"1.046705737s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:28:21.553075Z","caller":"traceutil/trace.go:171","msg":"trace[1279123366] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.021209619s","start":"2026-04-01T23:28:20.531789Z","end":"2026-04-01T23:28:21.552998Z","steps":["trace[1279123366] 'process raft request' (duration: 1.020900802s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.553204Z","caller":"traceutil/trace.go:171","msg":"trace[88140242] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.016287899s","start":"2026-04-01T23:28:20.536881Z","end":"2026-04-01T23:28:21.553169Z","steps":["trace[88140242] 'process raft request' (duration: 1.015921121s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.553241Z","caller":"traceutil/trace.go:171","msg":"trace[2005079672] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.018544615s","start":"2026-04-01T23:28:20.534677Z","end":"2026-04-01T23:28:21.553222Z","steps":["trace[2005079672] 'process raft request' (duration: 1.018091714s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.553273Z","caller":"traceutil/trace.go:171","msg":"trace[922114972] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.020305687s","start":"2026-04-01T23:28:20.532924Z","end":"2026-04-01T23:28:21.55323Z","steps":["trace[922114972] 'process raft request' (duration: 1.019812305s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.553334Z","caller":"traceutil/trace.go:171","msg":"trace[635710116] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.022759286s","start":"2026-04-01T23:28:20.530525Z","end":"2026-04-01T23:28:21.553284Z","steps":["trace[635710116] 'process raft request' (duration: 1.022036319s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:21.553347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.53467Z","time spent":"1.018633157s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:28:21.553373Z","caller":"traceutil/trace.go:171","msg":"trace[1034024134] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"474.391242ms","start":"2026-04-01T23:28:21.078971Z","end":"2026-04-01T23:28:21.553362Z","steps":["trace[1034024134] 'process raft request' (duration: 474.153516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:21.553367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.536846Z","time spent":"1.016446393s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.553452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.530508Z","time spent":"1.022875359s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.553336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.249451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:21.553476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.078941Z","time spent":"474.474324ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:21.553493Z","caller":"traceutil/trace.go:171","msg":"trace[142766790] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.003027819s","start":"2026-04-01T23:28:20.550451Z","end":"2026-04-01T23:28:21.553479Z","steps":["trace[142766790] 'process raft request' (duration: 1.002455395s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.5535Z","caller":"traceutil/trace.go:171","msg":"trace[400249780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"603.478127ms","start":"2026-04-01T23:28:20.950009Z","end":"2026-04-01T23:28:21.553487Z","steps":["trace[400249780] 'agreement among raft nodes before linearized reading' (duration: 603.256932ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.553545Z","caller":"traceutil/trace.go:171","msg":"trace[1150696159] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"990.149347ms","start":"2026-04-01T23:28:20.563382Z","end":"2026-04-01T23:28:21.553531Z","steps":["trace[1150696159] 'process raft request' (duration: 989.586524ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.55356Z","caller":"traceutil/trace.go:171","msg":"trace[1801740066] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.021823404s","start":"2026-04-01T23:28:20.53173Z","end":"2026-04-01T23:28:21.553554Z","steps":["trace[1801740066] 'process raft request' (duration: 1.020888491s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.553211Z","caller":"traceutil/trace.go:171","msg":"trace[1214172909] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:530; }","duration":"603.113258ms","start":"2026-04-01T23:28:20.950071Z","end":"2026-04-01T23:28:21.553184Z","steps":["trace[1214172909] 'read index received' (duration: 532.526909ms)","trace[1214172909] 'applied index is now lower than readState.Index' (duration: 70.585179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:21.553566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.550433Z","time spent":"1.00310222s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.553451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.532907Z","time spent":"1.02046838s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.553607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.531698Z","time spent":"1.021887685s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.553605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.563361Z","time spent":"990.219358ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.553552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.949982Z","time spent":"603.557178ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-01T23:28:21.55393Z","caller":"traceutil/trace.go:171","msg":"trace[2009892706] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"870.620564ms","start":"2026-04-01T23:28:20.682604Z","end":"2026-04-01T23:28:21.553224Z","steps":["trace[2009892706] 'process raft request' (duration: 870.47923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:21.554024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.682552Z","time spent":"871.422573ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.554041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:20.53175Z","time spent":"1.021418474s","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:21.604421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.379243ms","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-01T23:28:21.604636Z","caller":"traceutil/trace.go:171","msg":"trace[839637515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"260.654399ms","start":"2026-04-01T23:28:21.343956Z","end":"2026-04-01T23:28:21.60461Z","steps":["trace[839637515] 'agreement among raft nodes before linearized reading' (duration: 260.346642ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.60469Z","caller":"traceutil/trace.go:171","msg":"trace[1757440919] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"114.994963ms","start":"2026-04-01T23:28:21.489662Z","end":"2026-04-01T23:28:21.604657Z","steps":["trace[1757440919] 'process raft request' (duration: 114.311997ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.604826Z","caller":"traceutil/trace.go:171","msg":"trace[736633291] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"113.973368ms","start":"2026-04-01T23:28:21.49083Z","end":"2026-04-01T23:28:21.604803Z","steps":["trace[736633291] 'process raft request' (duration: 113.331803ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.605108Z","caller":"traceutil/trace.go:171","msg":"trace[282073901] transaction","detail":"{read_only:false; number_of_response:1; response_revision:524; }","duration":"114.207614ms","start":"2026-04-01T23:28:21.490888Z","end":"2026-04-01T23:28:21.605095Z","steps":["trace[282073901] 'process raft request' (duration: 113.363604ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.697373Z","caller":"traceutil/trace.go:171","msg":"trace[1811151306] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"131.302217ms","start":"2026-04-01T23:28:21.566044Z","end":"2026-04-01T23:28:21.697346Z","steps":["trace[1811151306] 'process raft request' (duration: 131.159633ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.697415Z","caller":"traceutil/trace.go:171","msg":"trace[547182886] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"131.319757ms","start":"2026-04-01T23:28:21.565991Z","end":"2026-04-01T23:28:21.697311Z","steps":["trace[547182886] 'process raft request' (duration: 131.02592ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.697551Z","caller":"traceutil/trace.go:171","msg":"trace[219735269] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"130.096258ms","start":"2026-04-01T23:28:21.567443Z","end":"2026-04-01T23:28:21.69754Z","steps":["trace[219735269] 'process raft request' (duration: 129.862843ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.697648Z","caller":"traceutil/trace.go:171","msg":"trace[2022924517] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"131.209805ms","start":"2026-04-01T23:28:21.566358Z","end":"2026-04-01T23:28:21.697568Z","steps":["trace[2022924517] 'process raft request' (duration: 130.894818ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.697644Z","caller":"traceutil/trace.go:171","msg":"trace[1736554259] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"126.41311ms","start":"2026-04-01T23:28:21.5712Z","end":"2026-04-01T23:28:21.697613Z","steps":["trace[1736554259] 'process raft request' (duration: 126.270296ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.699418Z","caller":"traceutil/trace.go:171","msg":"trace[1904212846] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"116.861238ms","start":"2026-04-01T23:28:21.582475Z","end":"2026-04-01T23:28:21.699336Z","steps":["trace[1904212846] 'process raft request' (duration: 115.064994ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.701135Z","caller":"traceutil/trace.go:171","msg":"trace[936770025] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"114.470391ms","start":"2026-04-01T23:28:21.586633Z","end":"2026-04-01T23:28:21.701103Z","steps":["trace[936770025] 'process raft request' (duration: 114.277866ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:21.70149Z","caller":"traceutil/trace.go:171","msg":"trace[985716493] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"118.897199ms","start":"2026-04-01T23:28:21.58255Z","end":"2026-04-01T23:28:21.701447Z","steps":["trace[985716493] 'process raft request' (duration: 115.333873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:22.032451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.653285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:28:22.032761Z","caller":"traceutil/trace.go:171","msg":"trace[1866317582] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"422.459056ms","start":"2026-04-01T23:28:21.610264Z","end":"2026-04-01T23:28:22.032723Z","steps":["trace[1866317582] 'process raft request' (duration: 281.423112ms)","trace[1866317582] 'compare' (duration: 140.481701ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:22.032846Z","caller":"traceutil/trace.go:171","msg":"trace[47448439] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"420.702183ms","start":"2026-04-01T23:28:21.612114Z","end":"2026-04-01T23:28:22.032816Z","steps":["trace[47448439] 'process raft request' (duration: 420.474558ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:22.032864Z","caller":"traceutil/trace.go:171","msg":"trace[884604834] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"414.838661ms","start":"2026-04-01T23:28:21.618013Z","end":"2026-04-01T23:28:22.032852Z","steps":["trace[884604834] 'process raft request' (duration: 414.78506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:22.032947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.618Z","time spent":"414.911472ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:22.032967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.612083Z","time spent":"420.829246ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:22.032943Z","caller":"traceutil/trace.go:171","msg":"trace[192119594] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"415.425635ms","start":"2026-04-01T23:28:21.617463Z","end":"2026-04-01T23:28:22.032888Z","steps":["trace[192119594] 'process raft request' (duration: 415.22722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:22.033012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.61024Z","time spent":"422.612089ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:22.033193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.617449Z","time spent":"415.62124ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:22.044315Z","caller":"traceutil/trace.go:171","msg":"trace[524053546] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"323.256444ms","start":"2026-04-01T23:28:21.721032Z","end":"2026-04-01T23:28:22.044289Z","steps":["trace[524053546] 'process raft request' (duration: 322.973307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:22.044495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.721008Z","time spent":"323.383117ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:22.044494Z","caller":"traceutil/trace.go:171","msg":"trace[1747418363] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"318.54681ms","start":"2026-04-01T23:28:21.725913Z","end":"2026-04-01T23:28:22.044459Z","steps":["trace[1747418363] 'process raft request' (duration: 318.289284ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:22.044562Z","caller":"traceutil/trace.go:171","msg":"trace[1508523808] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"284.922826ms","start":"2026-04-01T23:28:21.759612Z","end":"2026-04-01T23:28:22.044535Z","steps":["trace[1508523808] 'process raft request' (duration: 284.720711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:22.04479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:21.725897Z","time spent":"318.762215ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:25.891488Z","caller":"traceutil/trace.go:171","msg":"trace[1851780762] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"204.07207ms","start":"2026-04-01T23:28:25.68738Z","end":"2026-04-01T23:28:25.891452Z","steps":["trace[1851780762] 'process raft request' (duration: 203.412133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:26.199611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.83146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T23:28:26.200039Z","caller":"traceutil/trace.go:171","msg":"trace[403315118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"153.301931ms","start":"2026-04-01T23:28:26.046711Z","end":"2026-04-01T23:28:26.200012Z","steps":["trace[403315118] 'range keys from in-memory index tree' (duration: 152.648435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:26.199673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.188866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:26.200466Z","caller":"traceutil/trace.go:171","msg":"trace[439614025] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:601; }","duration":"208.014065ms","start":"2026-04-01T23:28:25.992412Z","end":"2026-04-01T23:28:26.200426Z","steps":["trace[439614025] 'range keys from in-memory index tree' (duration: 207.034372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:26.426114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.823877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:28:26.42611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.238296ms","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-01T23:28:26.426172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.064862ms","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-01T23:28:26.426223Z","caller":"traceutil/trace.go:171","msg":"trace[404679543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"123.95666ms","start":"2026-04-01T23:28:26.302247Z","end":"2026-04-01T23:28:26.426204Z","steps":["trace[404679543] 'range keys from in-memory index tree' (duration: 123.676334ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:26.426249Z","caller":"traceutil/trace.go:171","msg":"trace[1977753129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"119.43817ms","start":"2026-04-01T23:28:26.306783Z","end":"2026-04-01T23:28:26.426221Z","steps":["trace[1977753129] 'range keys from in-memory index tree' (duration: 119.020981ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:26.426261Z","caller":"traceutil/trace.go:171","msg":"trace[33513776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"119.164594ms","start":"2026-04-01T23:28:26.307076Z","end":"2026-04-01T23:28:26.426241Z","steps":["trace[33513776] 'range keys from in-memory index tree' (duration: 118.97822ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:29.481975Z","caller":"traceutil/trace.go:171","msg":"trace[1371252610] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"336.481815ms","start":"2026-04-01T23:28:29.145466Z","end":"2026-04-01T23:28:29.481948Z","steps":["trace[1371252610] 'read index received' (duration: 336.373222ms)","trace[1371252610] 'applied index is now lower than readState.Index' (duration: 107.463µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:29.482054Z","caller":"traceutil/trace.go:171","msg":"trace[1955822874] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"439.163569ms","start":"2026-04-01T23:28:29.042848Z","end":"2026-04-01T23:28:29.482011Z","steps":["trace[1955822874] 'process raft request' (duration: 438.931784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.482189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.6985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.482231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.042823Z","time spent":"439.329734ms","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:29.482255Z","caller":"traceutil/trace.go:171","msg":"trace[1842479776] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:623; }","duration":"336.806742ms","start":"2026-04-01T23:28:29.145434Z","end":"2026-04-01T23:28:29.48224Z","steps":["trace[1842479776] 'agreement among raft nodes before linearized reading' (duration: 336.663959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.482298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.145415Z","time spent":"336.873394ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-04-01T23:28:29.857556Z","caller":"traceutil/trace.go:171","msg":"trace[319304371] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"375.459327ms","start":"2026-04-01T23:28:29.482064Z","end":"2026-04-01T23:28:29.857524Z","steps":["trace[319304371] 'read index received' (duration: 374.590466ms)","trace[319304371] 'applied index is now lower than readState.Index' (duration: 867.681µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:29.857734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.143787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.857699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.453159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.857709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.582209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.857812Z","caller":"traceutil/trace.go:171","msg":"trace[1848028664] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"403.766033ms","start":"2026-04-01T23:28:29.454037Z","end":"2026-04-01T23:28:29.857803Z","steps":["trace[1848028664] 'agreement among raft nodes before linearized reading' (duration: 403.537087ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:29.857822Z","caller":"traceutil/trace.go:171","msg":"trace[1438363786] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"447.611933ms","start":"2026-04-01T23:28:29.410189Z","end":"2026-04-01T23:28:29.857801Z","steps":["trace[1438363786] 'agreement among raft nodes before linearized reading' (duration: 447.356157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.857856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.45401Z","time spent":"403.832605ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-01T23:28:29.857778Z","caller":"traceutil/trace.go:171","msg":"trace[1218664579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"712.25963ms","start":"2026-04-01T23:28:29.145505Z","end":"2026-04-01T23:28:29.857765Z","steps":["trace[1218664579] 'agreement among raft nodes before linearized reading' (duration: 712.155437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.858754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.410111Z","time spent":"447.752486ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:28:29.8589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.544317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.859027Z","caller":"traceutil/trace.go:171","msg":"trace[935402381] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"429.66711ms","start":"2026-04-01T23:28:29.429296Z","end":"2026-04-01T23:28:29.858963Z","steps":["trace[935402381] 'agreement among raft nodes before linearized reading' (duration: 429.445245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.859048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.673281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.859101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.947317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.859155Z","caller":"traceutil/trace.go:171","msg":"trace[680570940] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"384.793763ms","start":"2026-04-01T23:28:29.47431Z","end":"2026-04-01T23:28:29.859104Z","steps":["trace[680570940] 'agreement among raft nodes before linearized reading' (duration: 384.513386ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:29.859161Z","caller":"traceutil/trace.go:171","msg":"trace[17920688] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"430.05149ms","start":"2026-04-01T23:28:29.429088Z","end":"2026-04-01T23:28:29.859139Z","steps":["trace[17920688] 'agreement among raft nodes before linearized reading' (duration: 429.755753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.859191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.163277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.859214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.474298Z","time spent":"384.896745ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T23:28:29.85925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.429066Z","time spent":"430.141391ms","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-01T23:28:29.859262Z","caller":"traceutil/trace.go:171","msg":"trace[904097219] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"405.262709ms","start":"2026-04-01T23:28:29.453979Z","end":"2026-04-01T23:28:29.859241Z","steps":["trace[904097219] 'agreement among raft nodes before linearized reading' (duration: 405.158257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.859315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.453956Z","time spent":"405.341131ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:28:29.858775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.145476Z","time spent":"713.241734ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:28:29.859455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.529376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.85955Z","caller":"traceutil/trace.go:171","msg":"trace[2143698868] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"448.66353ms","start":"2026-04-01T23:28:29.410849Z","end":"2026-04-01T23:28:29.859512Z","steps":["trace[2143698868] 'agreement among raft nodes before linearized reading' (duration: 448.012604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.85959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.410822Z","time spent":"448.756751ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-01T23:28:29.859088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.429264Z","time spent":"429.805274ms","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:28:29.859769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.202872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.859812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.53313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.859869Z","caller":"traceutil/trace.go:171","msg":"trace[1404717156] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"385.604473ms","start":"2026-04-01T23:28:29.474245Z","end":"2026-04-01T23:28:29.85985Z","steps":["trace[1404717156] 'agreement among raft nodes before linearized reading' (duration: 385.517061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.85989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.474223Z","time spent":"385.659694ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:28:29.859884Z","caller":"traceutil/trace.go:171","msg":"trace[1639585282] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:624; }","duration":"430.340215ms","start":"2026-04-01T23:28:29.429526Z","end":"2026-04-01T23:28:29.859866Z","steps":["trace[1639585282] 'agreement among raft nodes before linearized reading' (duration: 429.923895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.859936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.928506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.859955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.429508Z","time spent":"430.412387ms","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-01T23:28:29.85999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.570288ms","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-01T23:28:29.859994Z","caller":"traceutil/trace.go:171","msg":"trace[414347553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:624; }","duration":"384.990407ms","start":"2026-04-01T23:28:29.474976Z","end":"2026-04-01T23:28:29.859967Z","steps":["trace[414347553] 'agreement among raft nodes before linearized reading' (duration: 384.837374ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:29.860013Z","caller":"traceutil/trace.go:171","msg":"trace[1247749222] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:624; }","duration":"636.602848ms","start":"2026-04-01T23:28:29.223401Z","end":"2026-04-01T23:28:29.860004Z","steps":["trace[1247749222] 'agreement among raft nodes before linearized reading' (duration: 636.550557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.474966Z","time spent":"385.05545ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-01T23:28:29.86009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.244447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.860112Z","caller":"traceutil/trace.go:171","msg":"trace[1402274880] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:624; }","duration":"450.300469ms","start":"2026-04-01T23:28:29.409804Z","end":"2026-04-01T23:28:29.860105Z","steps":["trace[1402274880] 'agreement among raft nodes before linearized reading' (duration: 450.253698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.409775Z","time spent":"450.35601ms","remote":"127.0.0.1:55846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T23:28:29.860044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.223398Z","time spent":"636.639199ms","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-01T23:28:29.860189Z","caller":"traceutil/trace.go:171","msg":"trace[714732891] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"616.492822ms","start":"2026-04-01T23:28:29.243673Z","end":"2026-04-01T23:28:29.860166Z","steps":["trace[714732891] 'process raft request' (duration: 613.317265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.881341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.860315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.018224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.860339Z","caller":"traceutil/trace.go:171","msg":"trace[388640641] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:624; }","duration":"509.984584ms","start":"2026-04-01T23:28:29.350324Z","end":"2026-04-01T23:28:29.860309Z","steps":["trace[388640641] 'agreement among raft nodes before linearized reading' (duration: 509.891311ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:29.860352Z","caller":"traceutil/trace.go:171","msg":"trace[1871669082] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:624; }","duration":"510.076936ms","start":"2026-04-01T23:28:29.350257Z","end":"2026-04-01T23:28:29.860334Z","steps":["trace[1871669082] 'agreement among raft nodes before linearized reading' (duration: 510.017335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.243639Z","time spent":"616.608965ms","remote":"127.0.0.1:39774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:29.860375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.350233Z","time spent":"510.135527ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T23:28:29.860377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.350292Z","time spent":"510.075966ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:28:29.860471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.296486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.860463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.968233ms","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-01T23:28:29.860497Z","caller":"traceutil/trace.go:171","msg":"trace[3120468] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:624; }","duration":"509.346378ms","start":"2026-04-01T23:28:29.35114Z","end":"2026-04-01T23:28:29.860486Z","steps":["trace[3120468] 'agreement among raft nodes before linearized reading' (duration: 509.296457ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:29.860519Z","caller":"traceutil/trace.go:171","msg":"trace[776899026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:624; }","duration":"636.045055ms","start":"2026-04-01T23:28:29.224449Z","end":"2026-04-01T23:28:29.860494Z","steps":["trace[776899026] 'agreement among raft nodes before linearized reading' (duration: 635.965883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.351132Z","time spent":"509.392259ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-01T23:28:29.860554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.224419Z","time spent":"636.124807ms","remote":"127.0.0.1:55826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-01T23:28:29.860626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.063227ms","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-01T23:28:29.86061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.220283ms","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-01T23:28:29.860653Z","caller":"traceutil/trace.go:171","msg":"trace[791783998] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:624; }","duration":"665.108687ms","start":"2026-04-01T23:28:29.195534Z","end":"2026-04-01T23:28:29.860642Z","steps":["trace[791783998] 'agreement among raft nodes before linearized reading' (duration: 665.057326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.195529Z","time spent":"665.148559ms","remote":"127.0.0.1:55826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:28:29.860689Z","caller":"traceutil/trace.go:171","msg":"trace[1206834603] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:624; }","duration":"637.317016ms","start":"2026-04-01T23:28:29.223335Z","end":"2026-04-01T23:28:29.860652Z","steps":["trace[1206834603] 'agreement among raft nodes before linearized reading' (duration: 637.180652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.223317Z","time spent":"637.403777ms","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:28:29.860728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.336001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.860764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.671894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:29.860817Z","caller":"traceutil/trace.go:171","msg":"trace[297614496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"700.420394ms","start":"2026-04-01T23:28:29.160354Z","end":"2026-04-01T23:28:29.860775Z","steps":["trace[297614496] 'agreement among raft nodes before linearized reading' (duration: 700.314201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.860877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.531397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:29.860875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.160349Z","time spent":"700.507347ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-01T23:28:29.860922Z","caller":"traceutil/trace.go:171","msg":"trace[1488278550] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"700.570898ms","start":"2026-04-01T23:28:29.160331Z","end":"2026-04-01T23:28:29.860902Z","steps":["trace[1488278550] 'agreement among raft nodes before linearized reading' (duration: 700.425463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.86096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.160317Z","time spent":"700.63063ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-04-01T23:28:29.8608Z","caller":"traceutil/trace.go:171","msg":"trace[303507701] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:624; }","duration":"699.716276ms","start":"2026-04-01T23:28:29.161067Z","end":"2026-04-01T23:28:29.860783Z","steps":["trace[303507701] 'agreement among raft nodes before linearized reading' (duration: 699.659535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.86117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.646272ms","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":"warn","ts":"2026-04-01T23:28:29.861171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.161058Z","time spent":"700.090746ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-01T23:28:29.861208Z","caller":"traceutil/trace.go:171","msg":"trace[1915616249] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:624; }","duration":"665.707013ms","start":"2026-04-01T23:28:29.195491Z","end":"2026-04-01T23:28:29.861198Z","steps":["trace[1915616249] 'agreement among raft nodes before linearized reading' (duration: 664.984505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:29.861306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.195477Z","time spent":"665.817216ms","remote":"127.0.0.1:55826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T23:28:30.449324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800177495,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:28:30.691843Z","caller":"traceutil/trace.go:171","msg":"trace[1734944707] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"743.021795ms","start":"2026-04-01T23:28:29.948788Z","end":"2026-04-01T23:28:30.69181Z","steps":["trace[1734944707] 'read index received' (duration: 707.026184ms)","trace[1734944707] 'applied index is now lower than readState.Index' (duration: 35.994061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:30.692049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.24534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:30.692143Z","caller":"traceutil/trace.go:171","msg":"trace[65764190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"743.380783ms","start":"2026-04-01T23:28:29.948739Z","end":"2026-04-01T23:28:30.69212Z","steps":["trace[65764190] 'agreement among raft nodes before linearized reading' (duration: 743.177688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:30.692184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.948712Z","time spent":"743.464176ms","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:28:30.692409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.826436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T23:28:30.692701Z","caller":"traceutil/trace.go:171","msg":"trace[1883865248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"445.174685ms","start":"2026-04-01T23:28:30.247503Z","end":"2026-04-01T23:28:30.692678Z","steps":["trace[1883865248] 'agreement among raft nodes before linearized reading' (duration: 444.58269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:30.69278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.588165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-01T23:28:30.692822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:30.247478Z","time spent":"445.324977ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:28:30.692934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.576956ms","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-01T23:28:30.692913Z","caller":"traceutil/trace.go:171","msg":"trace[457670942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"193.74706ms","start":"2026-04-01T23:28:30.499123Z","end":"2026-04-01T23:28:30.69287Z","steps":["trace[457670942] 'agreement among raft nodes before linearized reading' (duration: 193.497253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:30.692357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.448565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T23:28:30.692998Z","caller":"traceutil/trace.go:171","msg":"trace[53732307] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"763.314807ms","start":"2026-04-01T23:28:29.92953Z","end":"2026-04-01T23:28:30.692845Z","steps":["trace[53732307] 'process raft request' (duration: 726.365773ms)","trace[53732307] 'compare' (duration: 35.449998ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:30.693025Z","caller":"traceutil/trace.go:171","msg":"trace[2044040402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"191.793752ms","start":"2026-04-01T23:28:30.50121Z","end":"2026-04-01T23:28:30.693004Z","steps":["trace[2044040402] 'agreement among raft nodes before linearized reading' (duration: 191.384272ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:30.693188Z","caller":"traceutil/trace.go:171","msg":"trace[1086122545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"190.333447ms","start":"2026-04-01T23:28:30.502833Z","end":"2026-04-01T23:28:30.693166Z","steps":["trace[1086122545] 'agreement among raft nodes before linearized reading' (duration: 189.300602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:30.693386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:29.929491Z","time spent":"763.813219ms","remote":"127.0.0.1:39880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:32.413223Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.693728269s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:28:32.447646Z","caller":"traceutil/trace.go:171","msg":"trace[788774676] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.745709957s","start":"2026-04-01T23:28:30.701888Z","end":"2026-04-01T23:28:32.447598Z","steps":["trace[788774676] 'process raft request' (duration: 1.711611672s)","trace[788774676] 'compare' (duration: 33.531472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:32.447853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:30.701878Z","time spent":"1.74585034s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:32.448007Z","caller":"traceutil/trace.go:171","msg":"trace[951413301] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.745987073s","start":"2026-04-01T23:28:30.701991Z","end":"2026-04-01T23:28:32.447978Z","steps":["trace[951413301] 'process raft request' (duration: 1.745572503s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:32.448136Z","caller":"traceutil/trace.go:171","msg":"trace[1850154820] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"616.473771ms","start":"2026-04-01T23:28:31.831654Z","end":"2026-04-01T23:28:32.448128Z","steps":["trace[1850154820] 'process raft request' (duration: 616.408419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.448189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:31.831615Z","time spent":"616.547783ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:28:32.448193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:30.701948Z","time spent":"1.746138687s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:32.44825Z","caller":"traceutil/trace.go:171","msg":"trace[2068307762] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.74625256s","start":"2026-04-01T23:28:30.701991Z","end":"2026-04-01T23:28:32.448244Z","steps":["trace[2068307762] 'process raft request' (duration: 1.745935852s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.44829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:30.701948Z","time spent":"1.746321341s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:28:32.448343Z","caller":"traceutil/trace.go:171","msg":"trace[386246052] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:649; }","duration":"1.746192159s","start":"2026-04-01T23:28:30.702143Z","end":"2026-04-01T23:28:32.448335Z","steps":["trace[386246052] 'read index received' (duration: 7.578084ms)","trace[386246052] 'applied index is now lower than readState.Index' (duration: 1.738613195s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:32.44843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746313712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-01T23:28:32.448455Z","caller":"traceutil/trace.go:171","msg":"trace[1147007049] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:630; }","duration":"1.746359202s","start":"2026-04-01T23:28:30.70209Z","end":"2026-04-01T23:28:32.448449Z","steps":["trace[1147007049] 'agreement among raft nodes before linearized reading' (duration: 1.746306251s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.448474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:30.70208Z","time spent":"1.746390633s","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-01T23:28:32.448474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49879689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:32.448514Z","caller":"traceutil/trace.go:171","msg":"trace[479100771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"1.498860752s","start":"2026-04-01T23:28:30.949639Z","end":"2026-04-01T23:28:32.4485Z","steps":["trace[479100771] 'agreement among raft nodes before linearized reading' (duration: 1.49880147s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.448554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:30.949617Z","time spent":"1.498931083s","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:28:32.44867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.178054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T23:28:32.448769Z","caller":"traceutil/trace.go:171","msg":"trace[1269957101] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:630; }","duration":"378.288816ms","start":"2026-04-01T23:28:32.070454Z","end":"2026-04-01T23:28:32.448743Z","steps":["trace[1269957101] 'agreement among raft nodes before linearized reading' (duration: 378.00619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.448821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:32.070374Z","time spent":"378.43585ms","remote":"127.0.0.1:39710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.95\" "} {"level":"warn","ts":"2026-04-01T23:28:32.863601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.866457ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-01T23:28:32.863708Z","caller":"traceutil/trace.go:171","msg":"trace[1534294744] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"413.253392ms","start":"2026-04-01T23:28:32.450432Z","end":"2026-04-01T23:28:32.863686Z","steps":["trace[1534294744] 'read index received' (duration: 130.290193ms)","trace[1534294744] 'applied index is now lower than readState.Index' (duration: 282.961459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:32.863987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.521359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:28:32.864127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.34509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T23:28:32.864139Z","caller":"traceutil/trace.go:171","msg":"trace[305802654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"413.699493ms","start":"2026-04-01T23:28:32.45038Z","end":"2026-04-01T23:28:32.864079Z","steps":["trace[305802654] 'agreement among raft nodes before linearized reading' (duration: 413.503727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.8642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:32.450357Z","time spent":"413.829447ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:28:32.86403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.825417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-01T23:28:32.864172Z","caller":"traceutil/trace.go:171","msg":"trace[1348818955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"150.424001ms","start":"2026-04-01T23:28:32.713734Z","end":"2026-04-01T23:28:32.864158Z","steps":["trace[1348818955] 'agreement among raft nodes before linearized reading' (duration: 150.280228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.864366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:32.449843Z","time spent":"413.949678ms","remote":"127.0.0.1:39710","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-01T23:28:32.864422Z","caller":"traceutil/trace.go:171","msg":"trace[2092771494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:630; }","duration":"412.162365ms","start":"2026-04-01T23:28:32.452183Z","end":"2026-04-01T23:28:32.864346Z","steps":["trace[2092771494] 'agreement among raft nodes before linearized reading' (duration: 411.672503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:32.864633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:32.452128Z","time spent":"412.419282ms","remote":"127.0.0.1:39832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-01T23:28:34.323863Z","caller":"traceutil/trace.go:171","msg":"trace[1513765746] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"202.227805ms","start":"2026-04-01T23:28:34.121612Z","end":"2026-04-01T23:28:34.32384Z","steps":["trace[1513765746] 'read index received' (duration: 202.047891ms)","trace[1513765746] 'applied index is now lower than readState.Index' (duration: 179.104µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:28:34.32393Z","caller":"traceutil/trace.go:171","msg":"trace[1179574879] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"329.340351ms","start":"2026-04-01T23:28:33.994549Z","end":"2026-04-01T23:28:34.32389Z","steps":["trace[1179574879] 'process raft request' (duration: 329.173697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:34.324083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:33.99451Z","time spent":"329.494305ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:28:34.324075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.444771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-01T23:28:34.324169Z","caller":"traceutil/trace.go:171","msg":"trace[944251655] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:643; }","duration":"202.587544ms","start":"2026-04-01T23:28:34.121559Z","end":"2026-04-01T23:28:34.324146Z","steps":["trace[944251655] 'agreement among raft nodes before linearized reading' (duration: 202.365188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:34.562981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.534531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-01T23:28:34.563085Z","caller":"traceutil/trace.go:171","msg":"trace[354568595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"109.698505ms","start":"2026-04-01T23:28:34.453364Z","end":"2026-04-01T23:28:34.563062Z","steps":["trace[354568595] 'range keys from in-memory index tree' (duration: 109.252944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:34.563147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.678625ms","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-01T23:28:34.563272Z","caller":"traceutil/trace.go:171","msg":"trace[1465005669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"109.882239ms","start":"2026-04-01T23:28:34.453363Z","end":"2026-04-01T23:28:34.563245Z","steps":["trace[1465005669] 'range keys from in-memory index tree' (duration: 109.440449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:34.56357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.091414ms","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-01T23:28:34.563673Z","caller":"traceutil/trace.go:171","msg":"trace[1184891363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"110.290659ms","start":"2026-04-01T23:28:34.453358Z","end":"2026-04-01T23:28:34.563649Z","steps":["trace[1184891363] 'range keys from in-memory index tree' (duration: 109.608602ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:34.733268Z","caller":"traceutil/trace.go:171","msg":"trace[2123081625] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"148.010023ms","start":"2026-04-01T23:28:34.585233Z","end":"2026-04-01T23:28:34.733243Z","steps":["trace[2123081625] 'process raft request' (duration: 147.712756ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:28:34.87411Z","caller":"traceutil/trace.go:171","msg":"trace[1151237790] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"109.679575ms","start":"2026-04-01T23:28:34.76439Z","end":"2026-04-01T23:28:34.874069Z","steps":["trace[1151237790] 'process raft request' (duration: 109.46963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:38.061826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800177623,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:28:38.081724Z","caller":"traceutil/trace.go:171","msg":"trace[571540133] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:731; }","duration":"520.544351ms","start":"2026-04-01T23:28:37.561119Z","end":"2026-04-01T23:28:38.081664Z","steps":["trace[571540133] 'read index received' (duration: 512.66988ms)","trace[571540133] 'applied index is now lower than readState.Index' (duration: 7.873271ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:38.081959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.78831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:28:38.082049Z","caller":"traceutil/trace.go:171","msg":"trace[2135045882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"131.961385ms","start":"2026-04-01T23:28:37.950066Z","end":"2026-04-01T23:28:38.082028Z","steps":["trace[2135045882] 'agreement among raft nodes before linearized reading' (duration: 131.836442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:38.082036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.910979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-01T23:28:38.082112Z","caller":"traceutil/trace.go:171","msg":"trace[2078269469] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:707; }","duration":"521.032592ms","start":"2026-04-01T23:28:37.561062Z","end":"2026-04-01T23:28:38.082095Z","steps":["trace[2078269469] 'agreement among raft nodes before linearized reading' (duration: 520.751215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:38.08219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:37.561037Z","time spent":"521.143925ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5201,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-01T23:28:38.081989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.284173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-01T23:28:38.08227Z","caller":"traceutil/trace.go:171","msg":"trace[1172723542] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:707; }","duration":"320.60444ms","start":"2026-04-01T23:28:37.761625Z","end":"2026-04-01T23:28:38.082229Z","steps":["trace[1172723542] 'agreement among raft nodes before linearized reading' (duration: 320.216861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:38.082329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:28:37.761594Z","time spent":"320.710433ms","remote":"127.0.0.1:39716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-04-01T23:28:48.62814Z","caller":"traceutil/trace.go:171","msg":"trace[1521786176] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"206.840857ms","start":"2026-04-01T23:28:48.421267Z","end":"2026-04-01T23:28:48.628108Z","steps":["trace[1521786176] 'read index received' (duration: 180.966921ms)","trace[1521786176] 'applied index is now lower than readState.Index' (duration: 25.872116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:28:48.628491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.705985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:28:48.628536Z","caller":"traceutil/trace.go:171","msg":"trace[1873626628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"204.809887ms","start":"2026-04-01T23:28:48.423714Z","end":"2026-04-01T23:28:48.628524Z","steps":["trace[1873626628] 'agreement among raft nodes before linearized reading' (duration: 204.583832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:28:48.628497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.200805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tx2fk\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-01T23:28:48.628603Z","caller":"traceutil/trace.go:171","msg":"trace[1048791953] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-tx2fk; range_end:; response_count:1; response_revision:790; }","duration":"207.368619ms","start":"2026-04-01T23:28:48.421206Z","end":"2026-04-01T23:28:48.628575Z","steps":["trace[1048791953] 'agreement among raft nodes before linearized reading' (duration: 207.047582ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:15.793433Z","caller":"traceutil/trace.go:171","msg":"trace[737546257] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"157.225144ms","start":"2026-04-01T23:29:15.636181Z","end":"2026-04-01T23:29:15.793406Z","steps":["trace[737546257] 'process raft request' (duration: 157.06617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:16.080433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.067404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:16.080532Z","caller":"traceutil/trace.go:171","msg":"trace[1275925832] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1185; }","duration":"219.223948ms","start":"2026-04-01T23:29:15.861287Z","end":"2026-04-01T23:29:16.080511Z","steps":["trace[1275925832] 'count revisions from in-memory index tree' (duration: 218.934081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:16.080612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.103895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:16.080726Z","caller":"traceutil/trace.go:171","msg":"trace[377184077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"130.285789ms","start":"2026-04-01T23:29:15.950413Z","end":"2026-04-01T23:29:16.080699Z","steps":["trace[377184077] 'range keys from in-memory index tree' (duration: 129.997493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:38.955193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.65505ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:38.955534Z","caller":"traceutil/trace.go:171","msg":"trace[173856879] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1445; }","duration":"103.025819ms","start":"2026-04-01T23:29:38.852334Z","end":"2026-04-01T23:29:38.95536Z","steps":["trace[173856879] 'range keys from in-memory index tree' (duration: 102.62469ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:39.174202Z","caller":"traceutil/trace.go:171","msg":"trace[1768752032] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1492; }","duration":"215.497178ms","start":"2026-04-01T23:29:38.958667Z","end":"2026-04-01T23:29:39.174164Z","steps":["trace[1768752032] 'read index received' (duration: 215.252992ms)","trace[1768752032] 'applied index is now lower than readState.Index' (duration: 242.756µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:39.174347Z","caller":"traceutil/trace.go:171","msg":"trace[1972808255] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"215.729213ms","start":"2026-04-01T23:29:38.958586Z","end":"2026-04-01T23:29:39.174315Z","steps":["trace[1972808255] 'process raft request' (duration: 215.330134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:39.174505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.796915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:29:39.174664Z","caller":"traceutil/trace.go:171","msg":"trace[81536756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1446; }","duration":"215.985369ms","start":"2026-04-01T23:29:38.95866Z","end":"2026-04-01T23:29:39.174645Z","steps":["trace[81536756] 'agreement among raft nodes before linearized reading' (duration: 215.651621ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:39.201922Z","caller":"traceutil/trace.go:171","msg":"trace[1147646264] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"240.609096ms","start":"2026-04-01T23:29:38.961283Z","end":"2026-04-01T23:29:39.201892Z","steps":["trace[1147646264] 'process raft request' (duration: 240.566675ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:39.20217Z","caller":"traceutil/trace.go:171","msg":"trace[784594632] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"243.21992ms","start":"2026-04-01T23:29:38.958788Z","end":"2026-04-01T23:29:39.202008Z","steps":["trace[784594632] 'process raft request' (duration: 242.924893ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:39.369722Z","caller":"traceutil/trace.go:171","msg":"trace[439149127] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"112.050928ms","start":"2026-04-01T23:29:39.257633Z","end":"2026-04-01T23:29:39.369684Z","steps":["trace[439149127] 'read index received' (duration: 86.777205ms)","trace[439149127] 'applied index is now lower than readState.Index' (duration: 25.272513ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:39.369839Z","caller":"traceutil/trace.go:171","msg":"trace[1132308546] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"190.160543ms","start":"2026-04-01T23:29:39.179593Z","end":"2026-04-01T23:29:39.369753Z","steps":["trace[1132308546] 'process raft request' (duration: 164.827659ms)","trace[1132308546] 'compare' (duration: 25.112099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:29:39.370023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.367136ms","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-01T23:29:39.370083Z","caller":"traceutil/trace.go:171","msg":"trace[1960213012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1449; }","duration":"112.496079ms","start":"2026-04-01T23:29:39.257574Z","end":"2026-04-01T23:29:39.37007Z","steps":["trace[1960213012] 'agreement among raft nodes before linearized reading' (duration: 112.255513ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:53.625421Z","caller":"traceutil/trace.go:171","msg":"trace[1965030225] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"146.212987ms","start":"2026-04-01T23:29:53.479178Z","end":"2026-04-01T23:29:53.625391Z","steps":["trace[1965030225] 'process raft request' (duration: 126.811396ms)","trace[1965030225] 'compare' (duration: 19.104264ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:53.62547Z","caller":"traceutil/trace.go:171","msg":"trace[87646666] linearizableReadLoop","detail":"{readStateIndex:1771; appliedIndex:1769; }","duration":"132.538515ms","start":"2026-04-01T23:29:53.492909Z","end":"2026-04-01T23:29:53.625448Z","steps":["trace[87646666] 'read index received' (duration: 61.200934ms)","trace[87646666] 'applied index is now lower than readState.Index' (duration: 71.336281ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:53.625494Z","caller":"traceutil/trace.go:171","msg":"trace[758510664] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"112.685943ms","start":"2026-04-01T23:29:53.512786Z","end":"2026-04-01T23:29:53.625472Z","steps":["trace[758510664] 'process raft request' (duration: 112.463278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:53.625624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.681629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:29:53.625618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.68136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:53.62566Z","caller":"traceutil/trace.go:171","msg":"trace[864159967] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:1700; }","duration":"132.754681ms","start":"2026-04-01T23:29:53.492897Z","end":"2026-04-01T23:29:53.625652Z","steps":["trace[864159967] 'agreement among raft nodes before linearized reading' (duration: 132.669859ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:53.625676Z","caller":"traceutil/trace.go:171","msg":"trace[1463523850] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:1700; }","duration":"132.773011ms","start":"2026-04-01T23:29:53.49289Z","end":"2026-04-01T23:29:53.625663Z","steps":["trace[1463523850] 'agreement among raft nodes before linearized reading' (duration: 132.645878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:29:53.625914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.486213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:29:53.625965Z","caller":"traceutil/trace.go:171","msg":"trace[984281933] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:1700; }","duration":"132.554225ms","start":"2026-04-01T23:29:53.493396Z","end":"2026-04-01T23:29:53.625951Z","steps":["trace[984281933] 'agreement among raft nodes before linearized reading' (duration: 132.469063ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:29:53.893339Z","caller":"traceutil/trace.go:171","msg":"trace[914904809] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"125.651868ms","start":"2026-04-01T23:29:53.767663Z","end":"2026-04-01T23:29:53.893315Z","steps":["trace[914904809] 'process raft request' (duration: 109.357734ms)","trace[914904809] 'compare' (duration: 16.195382ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:29:53.926459Z","caller":"traceutil/trace.go:171","msg":"trace[1508235027] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"155.292167ms","start":"2026-04-01T23:29:53.771139Z","end":"2026-04-01T23:29:53.926431Z","steps":["trace[1508235027] 'process raft request' (duration: 155.127303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:05.89322Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800179877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:30:06.393644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800179877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:30:06.894187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800179877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:30:07.395033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576314917800179877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:30:07.691539Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.371077867s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T23:30:07.691932Z","caller":"traceutil/trace.go:171","msg":"trace[1208516577] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"2.371549307s","start":"2026-04-01T23:30:05.320358Z","end":"2026-04-01T23:30:07.691907Z","steps":["trace[1208516577] 'process raft request' (duration: 2.371409984s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.692081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.320338Z","time spent":"2.371668861s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:07.783721Z","caller":"traceutil/trace.go:171","msg":"trace[683724444] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"1.716666271s","start":"2026-04-01T23:30:06.067025Z","end":"2026-04-01T23:30:07.783691Z","steps":["trace[683724444] 'process raft request' (duration: 1.716558979s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:07.783769Z","caller":"traceutil/trace.go:171","msg":"trace[598992559] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"2.296905837s","start":"2026-04-01T23:30:05.48682Z","end":"2026-04-01T23:30:07.783726Z","steps":["trace[598992559] 'process raft request' (duration: 2.296439496s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.783921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:06.067Z","time spent":"1.716821795s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:07.783957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.486788Z","time spent":"2.297068781s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:07.784059Z","caller":"traceutil/trace.go:171","msg":"trace[1429577340] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"838.92766ms","start":"2026-04-01T23:30:06.945108Z","end":"2026-04-01T23:30:07.784035Z","steps":["trace[1429577340] 'process raft request' (duration: 838.781127ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:07.784075Z","caller":"traceutil/trace.go:171","msg":"trace[87140003] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"1.277819867s","start":"2026-04-01T23:30:06.506227Z","end":"2026-04-01T23:30:07.784047Z","steps":["trace[87140003] 'process raft request' (duration: 1.277415417s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.784215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:06.506194Z","time spent":"1.277953599s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:07.784217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:06.945084Z","time spent":"839.051444ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:30:07.86298Z","caller":"traceutil/trace.go:171","msg":"trace[1089903330] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1931; }","duration":"2.469987456s","start":"2026-04-01T23:30:05.392972Z","end":"2026-04-01T23:30:07.862959Z","steps":["trace[1089903330] 'read index received' (duration: 2.298810654s)","trace[1089903330] 'applied index is now lower than readState.Index' (duration: 171.175522ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:07.863199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.470216341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-01T23:30:07.863216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.777829674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f0bb67c-39de-4e04-a372-ac54b6ecf649\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-01T23:30:07.863228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.460221138s","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-01T23:30:07.863242Z","caller":"traceutil/trace.go:171","msg":"trace[2110475928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1862; }","duration":"2.470310183s","start":"2026-04-01T23:30:05.392921Z","end":"2026-04-01T23:30:07.863232Z","steps":["trace[2110475928] 'agreement among raft nodes before linearized reading' (duration: 2.47016683s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.863174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915809682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:07.863266Z","caller":"traceutil/trace.go:171","msg":"trace[1479656198] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5f0bb67c-39de-4e04-a372-ac54b6ecf649; range_end:; response_count:1; response_revision:1862; }","duration":"1.777926318s","start":"2026-04-01T23:30:06.085324Z","end":"2026-04-01T23:30:07.863251Z","steps":["trace[1479656198] 'agreement among raft nodes before linearized reading' (duration: 1.777784114s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:07.863276Z","caller":"traceutil/trace.go:171","msg":"trace[1353854915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"2.46031523s","start":"2026-04-01T23:30:05.402948Z","end":"2026-04-01T23:30:07.863263Z","steps":["trace[1353854915] 'agreement among raft nodes before linearized reading' (duration: 2.460153926s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.863281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.392898Z","time spent":"2.470374934s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:30:07.8633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:06.08531Z","time spent":"1.777982379s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5f0bb67c-39de-4e04-a372-ac54b6ecf649\" "} {"level":"info","ts":"2026-04-01T23:30:07.863298Z","caller":"traceutil/trace.go:171","msg":"trace[1988177642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"1.915974086s","start":"2026-04-01T23:30:05.947304Z","end":"2026-04-01T23:30:07.863278Z","steps":["trace[1988177642] 'agreement among raft nodes before linearized reading' (duration: 1.915800922s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.863307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.402917Z","time spent":"2.460383642s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T23:30:07.863337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.947283Z","time spent":"1.916047568s","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:30:07.863342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.556522296s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10328"} {"level":"warn","ts":"2026-04-01T23:30:07.863436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.252427428s","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-01T23:30:07.863435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184069089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:30:07.863437Z","caller":"traceutil/trace.go:171","msg":"trace[2042494796] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1862; }","duration":"1.55666998s","start":"2026-04-01T23:30:06.306743Z","end":"2026-04-01T23:30:07.863413Z","steps":["trace[2042494796] 'agreement among raft nodes before linearized reading' (duration: 1.556371642s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:07.863464Z","caller":"traceutil/trace.go:171","msg":"trace[1801515068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"2.252482869s","start":"2026-04-01T23:30:05.610972Z","end":"2026-04-01T23:30:07.863455Z","steps":["trace[1801515068] 'agreement among raft nodes before linearized reading' (duration: 2.252409767s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:07.863468Z","caller":"traceutil/trace.go:171","msg":"trace[1301839161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"2.184321825s","start":"2026-04-01T23:30:05.679138Z","end":"2026-04-01T23:30:07.86346Z","steps":["trace[1301839161] 'agreement among raft nodes before linearized reading' (duration: 2.184221992s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.863431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.23272167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-04-01T23:30:07.863491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.610948Z","time spent":"2.25253711s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:07.863495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.679116Z","time spent":"2.184372527s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T23:30:07.86355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.016793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:07.863548Z","caller":"traceutil/trace.go:171","msg":"trace[117798902] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"2.232873663s","start":"2026-04-01T23:30:05.630644Z","end":"2026-04-01T23:30:07.863518Z","steps":["trace[117798902] 'agreement among raft nodes before linearized reading' (duration: 2.232573846s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.863489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:06.306722Z","time spent":"1.556757132s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10352,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-01T23:30:07.863581Z","caller":"traceutil/trace.go:171","msg":"trace[1530724090] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1862; }","duration":"797.089924ms","start":"2026-04-01T23:30:07.066482Z","end":"2026-04-01T23:30:07.863572Z","steps":["trace[1530724090] 'agreement among raft nodes before linearized reading' (duration: 797.032303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.863598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.630628Z","time spent":"2.232956356s","remote":"127.0.0.1:54614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-01T23:30:07.863609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:07.06646Z","time spent":"797.142106ms","remote":"127.0.0.1:40068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-01T23:30:07.863653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.451879185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-01T23:30:07.863743Z","caller":"traceutil/trace.go:171","msg":"trace[1676474849] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1862; }","duration":"2.452003799s","start":"2026-04-01T23:30:05.411713Z","end":"2026-04-01T23:30:07.863716Z","steps":["trace[1676474849] 'agreement among raft nodes before linearized reading' (duration: 2.451716562s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:07.86379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:05.411703Z","time spent":"2.4520784s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-01T23:30:08.032596Z","caller":"traceutil/trace.go:171","msg":"trace[1865645492] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"164.374207ms","start":"2026-04-01T23:30:07.868184Z","end":"2026-04-01T23:30:08.032559Z","steps":["trace[1865645492] 'process raft request' (duration: 164.238384ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:08.032605Z","caller":"traceutil/trace.go:171","msg":"trace[2147164351] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"164.639963ms","start":"2026-04-01T23:30:07.867932Z","end":"2026-04-01T23:30:08.032572Z","steps":["trace[2147164351] 'process raft request' (duration: 152.245972ms)","trace[2147164351] 'compare' (duration: 12.114995ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:08.033148Z","caller":"traceutil/trace.go:171","msg":"trace[2090836146] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1937; }","duration":"161.485128ms","start":"2026-04-01T23:30:07.871647Z","end":"2026-04-01T23:30:08.033132Z","steps":["trace[2090836146] 'read index received' (duration: 46.095179ms)","trace[2090836146] 'applied index is now lower than readState.Index' (duration: 115.388659ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:08.033267Z","caller":"traceutil/trace.go:171","msg":"trace[157921400] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"163.968157ms","start":"2026-04-01T23:30:07.869274Z","end":"2026-04-01T23:30:08.033242Z","steps":["trace[157921400] 'process raft request' (duration: 163.810564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:08.033289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.616611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:08.033329Z","caller":"traceutil/trace.go:171","msg":"trace[1278600298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1867; }","duration":"161.697662ms","start":"2026-04-01T23:30:07.871621Z","end":"2026-04-01T23:30:08.033318Z","steps":["trace[1278600298] 'agreement among raft nodes before linearized reading' (duration: 161.6151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:08.033461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.838202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:08.033615Z","caller":"traceutil/trace.go:171","msg":"trace[1508387301] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"164.781987ms","start":"2026-04-01T23:30:07.868781Z","end":"2026-04-01T23:30:08.033563Z","steps":["trace[1508387301] 'process raft request' (duration: 164.239574ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:08.033674Z","caller":"traceutil/trace.go:171","msg":"trace[712077568] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1867; }","duration":"161.074638ms","start":"2026-04-01T23:30:07.872569Z","end":"2026-04-01T23:30:08.033644Z","steps":["trace[712077568] 'agreement among raft nodes before linearized reading' (duration: 160.815532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.210536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.565692ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-01T23:30:13.210803Z","caller":"traceutil/trace.go:171","msg":"trace[1271781213] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"857.818718ms","start":"2026-04-01T23:30:12.352966Z","end":"2026-04-01T23:30:13.210785Z","steps":["trace[1271781213] 'process raft request' (duration: 857.683725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.210911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:12.352932Z","time spent":"857.91436ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:30:13.210677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:12.147271Z","time spent":"1.063398915s","remote":"127.0.0.1:39710","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-01T23:30:13.210829Z","caller":"traceutil/trace.go:171","msg":"trace[1514430962] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"1.062117964s","start":"2026-04-01T23:30:12.148692Z","end":"2026-04-01T23:30:13.21081Z","steps":["trace[1514430962] 'read index received' (duration: 218.244604ms)","trace[1514430962] 'applied index is now lower than readState.Index' (duration: 843.87091ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:13.21184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.595365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:30:13.211912Z","caller":"traceutil/trace.go:171","msg":"trace[1099301756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1910; }","duration":"261.722018ms","start":"2026-04-01T23:30:12.950171Z","end":"2026-04-01T23:30:13.211893Z","steps":["trace[1099301756] 'agreement among raft nodes before linearized reading' (duration: 261.600905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.21205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063292822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5f0bb67c-39de-4e04-a372-ac54b6ecf649\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-04-01T23:30:13.212174Z","caller":"traceutil/trace.go:171","msg":"trace[1235338326] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5f0bb67c-39de-4e04-a372-ac54b6ecf649; range_end:; response_count:1; response_revision:1910; }","duration":"1.063461097s","start":"2026-04-01T23:30:12.148661Z","end":"2026-04-01T23:30:13.212122Z","steps":["trace[1235338326] 'agreement among raft nodes before linearized reading' (duration: 1.063053266s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.212281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:12.148647Z","time spent":"1.063574469s","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1327,"request content":"key:\"/registry/persistentvolumes/pvc-5f0bb67c-39de-4e04-a372-ac54b6ecf649\" "} {"level":"warn","ts":"2026-04-01T23:30:13.212387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.423255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-01T23:30:13.212452Z","caller":"traceutil/trace.go:171","msg":"trace[132352268] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1910; }","duration":"250.521288ms","start":"2026-04-01T23:30:12.961912Z","end":"2026-04-01T23:30:13.212433Z","steps":["trace[132352268] 'agreement among raft nodes before linearized reading' (duration: 250.327593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:13.212877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.645752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11664"} {"level":"info","ts":"2026-04-01T23:30:13.212954Z","caller":"traceutil/trace.go:171","msg":"trace[1511796002] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1910; }","duration":"161.750764ms","start":"2026-04-01T23:30:13.051187Z","end":"2026-04-01T23:30:13.212938Z","steps":["trace[1511796002] 'agreement among raft nodes before linearized reading' (duration: 161.537338ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:15.874088Z","caller":"traceutil/trace.go:171","msg":"trace[142623850] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"140.986755ms","start":"2026-04-01T23:30:15.733079Z","end":"2026-04-01T23:30:15.874066Z","steps":["trace[142623850] 'process raft request' (duration: 140.815011ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:15.924546Z","caller":"traceutil/trace.go:171","msg":"trace[847522098] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"107.790728ms","start":"2026-04-01T23:30:15.81673Z","end":"2026-04-01T23:30:15.924521Z","steps":["trace[847522098] 'process raft request' (duration: 107.696696ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:15.924724Z","caller":"traceutil/trace.go:171","msg":"trace[1458859247] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"110.669729ms","start":"2026-04-01T23:30:15.814044Z","end":"2026-04-01T23:30:15.924714Z","steps":["trace[1458859247] 'process raft request' (duration: 110.269379ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:15.924841Z","caller":"traceutil/trace.go:171","msg":"trace[1407265633] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"106.192669ms","start":"2026-04-01T23:30:15.818626Z","end":"2026-04-01T23:30:15.924818Z","steps":["trace[1407265633] 'process raft request' (duration: 105.843011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:17.692898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.52075ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-01T23:30:17.69298Z","caller":"traceutil/trace.go:171","msg":"trace[1313963197] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2019; }","duration":"448.332699ms","start":"2026-04-01T23:30:17.244633Z","end":"2026-04-01T23:30:17.692966Z","steps":["trace[1313963197] 'read index received' (duration: 338.715657ms)","trace[1313963197] 'applied index is now lower than readState.Index' (duration: 109.615952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:17.693124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.484264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-01T23:30:17.693161Z","caller":"traceutil/trace.go:171","msg":"trace[795163318] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1943; }","duration":"448.565356ms","start":"2026-04-01T23:30:17.244588Z","end":"2026-04-01T23:30:17.693154Z","steps":["trace[795163318] 'agreement among raft nodes before linearized reading' (duration: 448.409452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:17.693185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:30:17.244565Z","time spent":"448.614626ms","remote":"127.0.0.1:54614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-01T23:30:38.210028Z","caller":"traceutil/trace.go:171","msg":"trace[233003055] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"104.836636ms","start":"2026-04-01T23:30:38.105162Z","end":"2026-04-01T23:30:38.209998Z","steps":["trace[233003055] 'process raft request' (duration: 104.643261ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:38.264293Z","caller":"traceutil/trace.go:171","msg":"trace[1640222848] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2156; }","duration":"136.040534ms","start":"2026-04-01T23:30:38.128236Z","end":"2026-04-01T23:30:38.264276Z","steps":["trace[1640222848] 'read index received' (duration: 81.648983ms)","trace[1640222848] 'applied index is now lower than readState.Index' (duration: 54.390691ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:38.264395Z","caller":"traceutil/trace.go:171","msg":"trace[1565269979] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"148.203509ms","start":"2026-04-01T23:30:38.116178Z","end":"2026-04-01T23:30:38.264382Z","steps":["trace[1565269979] 'process raft request' (duration: 148.007545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:38.264573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.597247ms","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-01T23:30:38.26465Z","caller":"traceutil/trace.go:171","msg":"trace[865758492] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2077; }","duration":"101.763461ms","start":"2026-04-01T23:30:38.162866Z","end":"2026-04-01T23:30:38.26463Z","steps":["trace[865758492] 'agreement among raft nodes before linearized reading' (duration: 101.550666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:38.264645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.383312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T23:30:38.264732Z","caller":"traceutil/trace.go:171","msg":"trace[1113187233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2077; }","duration":"136.500864ms","start":"2026-04-01T23:30:38.128213Z","end":"2026-04-01T23:30:38.264714Z","steps":["trace[1113187233] 'agreement among raft nodes before linearized reading' (duration: 136.280329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:38.53285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.769772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:30:38.533081Z","caller":"traceutil/trace.go:171","msg":"trace[1672152152] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"161.937763ms","start":"2026-04-01T23:30:38.37112Z","end":"2026-04-01T23:30:38.533058Z","steps":["trace[1672152152] 'process raft request' (duration: 161.857382ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:38.533085Z","caller":"traceutil/trace.go:171","msg":"trace[1685774101] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"166.097524ms","start":"2026-04-01T23:30:38.366953Z","end":"2026-04-01T23:30:38.53305Z","steps":["trace[1685774101] 'read index received' (duration: 36.20285ms)","trace[1685774101] 'applied index is now lower than readState.Index' (duration: 129.890994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:30:38.533332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.695891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-01T23:30:38.53333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.057895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T23:30:38.533407Z","caller":"traceutil/trace.go:171","msg":"trace[130425029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2081; }","duration":"213.821913ms","start":"2026-04-01T23:30:38.319569Z","end":"2026-04-01T23:30:38.533391Z","steps":["trace[130425029] 'agreement among raft nodes before linearized reading' (duration: 213.562747ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:38.533425Z","caller":"traceutil/trace.go:171","msg":"trace[344715658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2081; }","duration":"190.191799ms","start":"2026-04-01T23:30:38.343211Z","end":"2026-04-01T23:30:38.533403Z","steps":["trace[344715658] 'agreement among raft nodes before linearized reading' (duration: 189.919022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:30:38.533411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.091778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-04-01T23:30:38.533343Z","caller":"traceutil/trace.go:171","msg":"trace[360360053] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"178.355602ms","start":"2026-04-01T23:30:38.35492Z","end":"2026-04-01T23:30:38.533276Z","steps":["trace[360360053] 'process raft request' (duration: 48.057827ms)","trace[360360053] 'compare' (duration: 129.625039ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:30:38.533513Z","caller":"traceutil/trace.go:171","msg":"trace[1253478718] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2081; }","duration":"181.235352ms","start":"2026-04-01T23:30:38.352254Z","end":"2026-04-01T23:30:38.53349Z","steps":["trace[1253478718] 'agreement among raft nodes before linearized reading' (duration: 180.882263ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:30:53.942089Z","caller":"traceutil/trace.go:171","msg":"trace[360671371] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"132.402515ms","start":"2026-04-01T23:30:53.809656Z","end":"2026-04-01T23:30:53.942058Z","steps":["trace[360671371] 'process raft request' (duration: 132.237011ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:00.38568Z","caller":"traceutil/trace.go:171","msg":"trace[1594451798] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"266.002531ms","start":"2026-04-01T23:31:00.119649Z","end":"2026-04-01T23:31:00.385652Z","steps":["trace[1594451798] 'read index received' (duration: 265.823927ms)","trace[1594451798] 'applied index is now lower than readState.Index' (duration: 176.684µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:00.385826Z","caller":"traceutil/trace.go:171","msg":"trace[1764804988] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"272.824966ms","start":"2026-04-01T23:31:00.112974Z","end":"2026-04-01T23:31:00.385799Z","steps":["trace[1764804988] 'process raft request' (duration: 272.453817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:00.385955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.282168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T23:31:00.385996Z","caller":"traceutil/trace.go:171","msg":"trace[1001251294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2193; }","duration":"266.346969ms","start":"2026-04-01T23:31:00.11964Z","end":"2026-04-01T23:31:00.385987Z","steps":["trace[1001251294] 'agreement among raft nodes before linearized reading' (duration: 266.114754ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:00.545119Z","caller":"traceutil/trace.go:171","msg":"trace[361229510] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"265.649192ms","start":"2026-04-01T23:31:00.27944Z","end":"2026-04-01T23:31:00.54509Z","steps":["trace[361229510] 'process raft request' (duration: 264.962726ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:00.545542Z","caller":"traceutil/trace.go:171","msg":"trace[176283355] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"184.143513ms","start":"2026-04-01T23:31:00.36137Z","end":"2026-04-01T23:31:00.545513Z","steps":["trace[176283355] 'process raft request' (duration: 183.547518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:00.545882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.457729ms","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-01T23:31:00.545954Z","caller":"traceutil/trace.go:171","msg":"trace[618965686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2196; }","duration":"109.579672ms","start":"2026-04-01T23:31:00.436358Z","end":"2026-04-01T23:31:00.545938Z","steps":["trace[618965686] 'agreement among raft nodes before linearized reading' (duration: 109.386867ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:00.545578Z","caller":"traceutil/trace.go:171","msg":"trace[1434010699] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"155.111877ms","start":"2026-04-01T23:31:00.39044Z","end":"2026-04-01T23:31:00.545552Z","steps":["trace[1434010699] 'process raft request' (duration: 154.79904ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:00.545785Z","caller":"traceutil/trace.go:171","msg":"trace[1121429053] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2279; }","duration":"108.892105ms","start":"2026-04-01T23:31:00.43641Z","end":"2026-04-01T23:31:00.545302Z","steps":["trace[1121429053] 'read index received' (duration: 108.035955ms)","trace[1121429053] 'applied index is now lower than readState.Index' (duration: 855.05µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:50.998124Z","caller":"traceutil/trace.go:171","msg":"trace[1103280185] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"274.000173ms","start":"2026-04-01T23:31:50.724094Z","end":"2026-04-01T23:31:50.998094Z","steps":["trace[1103280185] 'process raft request' (duration: 273.85092ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.127671Z","caller":"traceutil/trace.go:171","msg":"trace[1860718055] linearizableReadLoop","detail":"{readStateIndex:2742; appliedIndex:2740; }","duration":"364.200727ms","start":"2026-04-01T23:31:50.763447Z","end":"2026-04-01T23:31:51.127648Z","steps":["trace[1860718055] 'read index received' (duration: 234.543273ms)","trace[1860718055] 'applied index is now lower than readState.Index' (duration: 129.656324ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:31:51.127758Z","caller":"traceutil/trace.go:171","msg":"trace[880497507] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"321.6101ms","start":"2026-04-01T23:31:50.80614Z","end":"2026-04-01T23:31:51.12775Z","steps":["trace[880497507] 'process raft request' (duration: 321.448846ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.127695Z","caller":"traceutil/trace.go:171","msg":"trace[2067595439] transaction","detail":"{read_only:false; response_revision:2639; number_of_response:1; }","duration":"402.889419ms","start":"2026-04-01T23:31:50.724785Z","end":"2026-04-01T23:31:51.127674Z","steps":["trace[2067595439] 'process raft request' (duration: 402.272995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.127838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.806124Z","time spent":"321.656482ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:51.12793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.724761Z","time spent":"403.075183ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:31:51.127949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.186382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-01T23:31:51.127966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.500564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-01T23:31:51.128043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.704459ms","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-01T23:31:51.128065Z","caller":"traceutil/trace.go:171","msg":"trace[774441830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2640; }","duration":"357.769241ms","start":"2026-04-01T23:31:50.770292Z","end":"2026-04-01T23:31:51.128061Z","steps":["trace[774441830] 'agreement among raft nodes before linearized reading' (duration: 357.71939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.127998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.81032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-04-01T23:31:51.128084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.770271Z","time spent":"357.808192ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:31:51.128066Z","caller":"traceutil/trace.go:171","msg":"trace[488076324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2640; }","duration":"364.647267ms","start":"2026-04-01T23:31:50.763392Z","end":"2026-04-01T23:31:51.12804Z","steps":["trace[488076324] 'agreement among raft nodes before linearized reading' (duration: 364.382601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.128091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.94042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-01T23:31:51.128103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.265542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T23:31:51.128154Z","caller":"traceutil/trace.go:171","msg":"trace[1258115730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2640; }","duration":"249.052872ms","start":"2026-04-01T23:31:50.879087Z","end":"2026-04-01T23:31:51.12814Z","steps":["trace[1258115730] 'agreement among raft nodes before linearized reading' (duration: 248.850317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.128152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.085622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-01T23:31:51.128205Z","caller":"traceutil/trace.go:171","msg":"trace[1336783685] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2640; }","duration":"249.160195ms","start":"2026-04-01T23:31:50.879026Z","end":"2026-04-01T23:31:51.128187Z","steps":["trace[1336783685] 'agreement among raft nodes before linearized reading' (duration: 248.96134ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:31:51.127997Z","caller":"traceutil/trace.go:171","msg":"trace[32705476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2640; }","duration":"363.285754ms","start":"2026-04-01T23:31:50.764699Z","end":"2026-04-01T23:31:51.127985Z","steps":["trace[32705476] 'agreement among raft nodes before linearized reading' (duration: 363.14232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.128171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.763367Z","time spent":"364.775301ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-01T23:31:51.128222Z","caller":"traceutil/trace.go:171","msg":"trace[1249277523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2640; }","duration":"255.389544ms","start":"2026-04-01T23:31:50.872766Z","end":"2026-04-01T23:31:51.128155Z","steps":["trace[1249277523] 'agreement among raft nodes before linearized reading' (duration: 255.123578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.128283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.764676Z","time spent":"363.5822ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-01T23:31:51.128106Z","caller":"traceutil/trace.go:171","msg":"trace[1760284156] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2640; }","duration":"327.933562ms","start":"2026-04-01T23:31:50.800155Z","end":"2026-04-01T23:31:51.128089Z","steps":["trace[1760284156] 'agreement among raft nodes before linearized reading' (duration: 327.761818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:31:51.128339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:31:50.800145Z","time spent":"328.185508ms","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-01T23:32:25.535355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.458212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:32:25.535458Z","caller":"traceutil/trace.go:171","msg":"trace[1460109527] linearizableReadLoop","detail":"{readStateIndex:2958; appliedIndex:2957; }","duration":"242.581202ms","start":"2026-04-01T23:32:25.292861Z","end":"2026-04-01T23:32:25.535442Z","steps":["trace[1460109527] 'read index received' (duration: 102.890114ms)","trace[1460109527] 'applied index is now lower than readState.Index' (duration: 139.689838ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:32:25.535505Z","caller":"traceutil/trace.go:171","msg":"trace[133020739] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"292.156104ms","start":"2026-04-01T23:32:25.243308Z","end":"2026-04-01T23:32:25.535464Z","steps":["trace[133020739] 'process raft request' (duration: 152.505837ms)","trace[133020739] 'compare' (duration: 139.34497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:32:25.535636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.786728ms","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-01T23:32:25.535674Z","caller":"traceutil/trace.go:171","msg":"trace[980749702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2847; }","duration":"225.8709ms","start":"2026-04-01T23:32:25.309793Z","end":"2026-04-01T23:32:25.535664Z","steps":["trace[980749702] 'agreement among raft nodes before linearized reading' (duration: 225.768207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:25.535622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.764656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-01T23:32:25.535852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.178661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:32:25.535933Z","caller":"traceutil/trace.go:171","msg":"trace[1915219565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2847; }","duration":"212.357065ms","start":"2026-04-01T23:32:25.32356Z","end":"2026-04-01T23:32:25.535917Z","steps":["trace[1915219565] 'agreement among raft nodes before linearized reading' (duration: 212.17345ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:25.535874Z","caller":"traceutil/trace.go:171","msg":"trace[565737402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2847; }","duration":"243.054342ms","start":"2026-04-01T23:32:25.2928Z","end":"2026-04-01T23:32:25.535854Z","steps":["trace[565737402] 'agreement among raft nodes before linearized reading' (duration: 242.694624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:25.535929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.538227ms","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-01T23:32:25.536004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.906818ms","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-01T23:32:25.536034Z","caller":"traceutil/trace.go:171","msg":"trace[991031944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2847; }","duration":"224.675131ms","start":"2026-04-01T23:32:25.311338Z","end":"2026-04-01T23:32:25.536013Z","steps":["trace[991031944] 'agreement among raft nodes before linearized reading' (duration: 224.400355ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:25.536074Z","caller":"traceutil/trace.go:171","msg":"trace[1117422293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2847; }","duration":"213.00015ms","start":"2026-04-01T23:32:25.32306Z","end":"2026-04-01T23:32:25.53606Z","steps":["trace[1117422293] 'agreement among raft nodes before linearized reading' (duration: 212.612431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:25.536008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.19462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:32:25.536235Z","caller":"traceutil/trace.go:171","msg":"trace[658782394] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2847; }","duration":"140.461766ms","start":"2026-04-01T23:32:25.395755Z","end":"2026-04-01T23:32:25.536216Z","steps":["trace[658782394] 'agreement among raft nodes before linearized reading' (duration: 140.139878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:32:25.53658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.783261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-01T23:32:25.536674Z","caller":"traceutil/trace.go:171","msg":"trace[1241050821] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2847; }","duration":"211.925524ms","start":"2026-04-01T23:32:25.324727Z","end":"2026-04-01T23:32:25.536652Z","steps":["trace[1241050821] 'agreement among raft nodes before linearized reading' (duration: 210.88824ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:25.655745Z","caller":"traceutil/trace.go:171","msg":"trace[1193314704] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"115.248331ms","start":"2026-04-01T23:32:25.540466Z","end":"2026-04-01T23:32:25.655714Z","steps":["trace[1193314704] 'process raft request' (duration: 114.811099ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:25.656228Z","caller":"traceutil/trace.go:171","msg":"trace[1972482676] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"115.319552ms","start":"2026-04-01T23:32:25.540868Z","end":"2026-04-01T23:32:25.656187Z","steps":["trace[1972482676] 'process raft request' (duration: 114.81095ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:25.656389Z","caller":"traceutil/trace.go:171","msg":"trace[1681032975] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"115.116047ms","start":"2026-04-01T23:32:25.541158Z","end":"2026-04-01T23:32:25.656274Z","steps":["trace[1681032975] 'process raft request' (duration: 115.066896ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:25.656636Z","caller":"traceutil/trace.go:171","msg":"trace[924457971] transaction","detail":"{read_only:false; response_revision:2851; number_of_response:1; }","duration":"115.756602ms","start":"2026-04-01T23:32:25.540868Z","end":"2026-04-01T23:32:25.656624Z","steps":["trace[924457971] 'process raft request' (duration: 115.168848ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:32:25.656854Z","caller":"traceutil/trace.go:171","msg":"trace[596844256] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"115.823224ms","start":"2026-04-01T23:32:25.541022Z","end":"2026-04-01T23:32:25.656845Z","steps":["trace[596844256] 'process raft request' (duration: 115.134887ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:21.019962Z","caller":"traceutil/trace.go:171","msg":"trace[111552817] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"192.409914ms","start":"2026-04-01T23:36:20.827528Z","end":"2026-04-01T23:36:21.019938Z","steps":["trace[111552817] 'process raft request' (duration: 192.285571ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:21.097829Z","caller":"traceutil/trace.go:171","msg":"trace[1070429342] linearizableReadLoop","detail":"{readStateIndex:5242; appliedIndex:5241; }","duration":"268.662111ms","start":"2026-04-01T23:36:20.829137Z","end":"2026-04-01T23:36:21.097799Z","steps":["trace[1070429342] 'read index received' (duration: 190.824605ms)","trace[1070429342] 'applied index is now lower than readState.Index' (duration: 77.833946ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:21.097886Z","caller":"traceutil/trace.go:171","msg":"trace[2049610925] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"270.25714ms","start":"2026-04-01T23:36:20.827595Z","end":"2026-04-01T23:36:21.097852Z","steps":["trace[2049610925] 'process raft request' (duration: 270.080246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:21.098041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.885926ms","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-01T23:36:21.098074Z","caller":"traceutil/trace.go:171","msg":"trace[761816341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5050; }","duration":"268.962768ms","start":"2026-04-01T23:36:20.829105Z","end":"2026-04-01T23:36:21.098068Z","steps":["trace[761816341] 'agreement among raft nodes before linearized reading' (duration: 268.813715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:21.098068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.337882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-01T23:36:21.098111Z","caller":"traceutil/trace.go:171","msg":"trace[1751949971] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5050; }","duration":"265.407143ms","start":"2026-04-01T23:36:20.83269Z","end":"2026-04-01T23:36:21.098097Z","steps":["trace[1751949971] 'agreement among raft nodes before linearized reading' (duration: 265.258729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:21.09829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.577902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:36:21.098539Z","caller":"traceutil/trace.go:171","msg":"trace[1064576803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5050; }","duration":"148.845979ms","start":"2026-04-01T23:36:20.949669Z","end":"2026-04-01T23:36:21.098515Z","steps":["trace[1064576803] 'agreement among raft nodes before linearized reading' (duration: 148.381218ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:21.222819Z","caller":"traceutil/trace.go:171","msg":"trace[1695218420] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"120.066089ms","start":"2026-04-01T23:36:21.102724Z","end":"2026-04-01T23:36:21.22279Z","steps":["trace[1695218420] 'process raft request' (duration: 104.272221ms)","trace[1695218420] 'compare' (duration: 15.677645ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:21.227436Z","caller":"traceutil/trace.go:171","msg":"trace[1249584719] linearizableReadLoop","detail":"{readStateIndex:5244; appliedIndex:5242; }","duration":"107.756943ms","start":"2026-04-01T23:36:21.119654Z","end":"2026-04-01T23:36:21.227411Z","steps":["trace[1249584719] 'read index received' (duration: 87.430866ms)","trace[1249584719] 'applied index is now lower than readState.Index' (duration: 20.324507ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:21.227581Z","caller":"traceutil/trace.go:171","msg":"trace[1724436321] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"124.194527ms","start":"2026-04-01T23:36:21.103366Z","end":"2026-04-01T23:36:21.22756Z","steps":["trace[1724436321] 'process raft request' (duration: 123.90002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:21.227609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.939087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:36:21.227675Z","caller":"traceutil/trace.go:171","msg":"trace[563122823] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5052; }","duration":"108.058451ms","start":"2026-04-01T23:36:21.1196Z","end":"2026-04-01T23:36:21.227658Z","steps":["trace[563122823] 'agreement among raft nodes before linearized reading' (duration: 107.944528ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:29.235797Z","caller":"traceutil/trace.go:171","msg":"trace[1636301306] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"162.102027ms","start":"2026-04-01T23:36:29.073666Z","end":"2026-04-01T23:36:29.235768Z","steps":["trace[1636301306] 'process raft request' (duration: 129.908925ms)","trace[1636301306] 'compare' (duration: 31.999188ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:29.235978Z","caller":"traceutil/trace.go:171","msg":"trace[1900360227] linearizableReadLoop","detail":"{readStateIndex:5412; appliedIndex:5410; }","duration":"120.255474ms","start":"2026-04-01T23:36:29.1157Z","end":"2026-04-01T23:36:29.235955Z","steps":["trace[1900360227] 'read index received' (duration: 87.923628ms)","trace[1900360227] 'applied index is now lower than readState.Index' (duration: 32.330266ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:29.236194Z","caller":"traceutil/trace.go:171","msg":"trace[1905076310] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"159.051343ms","start":"2026-04-01T23:36:29.077021Z","end":"2026-04-01T23:36:29.236072Z","steps":["trace[1905076310] 'process raft request' (duration: 158.684915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:29.236529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.272004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:10 size:380246"} {"level":"info","ts":"2026-04-01T23:36:29.236587Z","caller":"traceutil/trace.go:171","msg":"trace[47062234] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:10; response_revision:5214; }","duration":"163.386957ms","start":"2026-04-01T23:36:29.073188Z","end":"2026-04-01T23:36:29.236575Z","steps":["trace[47062234] 'agreement among raft nodes before linearized reading' (duration: 162.869315ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:29.244351Z","caller":"traceutil/trace.go:171","msg":"trace[1894360323] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"123.846389ms","start":"2026-04-01T23:36:29.120483Z","end":"2026-04-01T23:36:29.24433Z","steps":["trace[1894360323] 'process raft request' (duration: 123.686966ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:29.24453Z","caller":"traceutil/trace.go:171","msg":"trace[798498537] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"120.199442ms","start":"2026-04-01T23:36:29.124313Z","end":"2026-04-01T23:36:29.244512Z","steps":["trace[798498537] 'process raft request' (duration: 119.968506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:29.244657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.937475ms","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-01T23:36:29.244749Z","caller":"traceutil/trace.go:171","msg":"trace[1619673552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5216; }","duration":"120.066069ms","start":"2026-04-01T23:36:29.124661Z","end":"2026-04-01T23:36:29.244727Z","steps":["trace[1619673552] 'agreement among raft nodes before linearized reading' (duration: 119.834813ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:38.556714Z","caller":"traceutil/trace.go:171","msg":"trace[521679047] linearizableReadLoop","detail":"{readStateIndex:5583; appliedIndex:5582; }","duration":"110.043358ms","start":"2026-04-01T23:36:38.446647Z","end":"2026-04-01T23:36:38.556691Z","steps":["trace[521679047] 'read index received' (duration: 109.821193ms)","trace[521679047] 'applied index is now lower than readState.Index' (duration: 221.295µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:38.556768Z","caller":"traceutil/trace.go:171","msg":"trace[580710303] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"114.7048ms","start":"2026-04-01T23:36:38.442029Z","end":"2026-04-01T23:36:38.556734Z","steps":["trace[580710303] 'process raft request' (duration: 114.507226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:38.556948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.282074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-01T23:36:38.556992Z","caller":"traceutil/trace.go:171","msg":"trace[1462194480] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5383; }","duration":"110.381786ms","start":"2026-04-01T23:36:38.4466Z","end":"2026-04-01T23:36:38.556981Z","steps":["trace[1462194480] 'agreement among raft nodes before linearized reading' (duration: 110.227583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:49.917543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.179051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T23:36:49.917623Z","caller":"traceutil/trace.go:171","msg":"trace[986734903] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5569; }","duration":"172.313284ms","start":"2026-04-01T23:36:49.745296Z","end":"2026-04-01T23:36:49.91761Z","steps":["trace[986734903] 'count revisions from in-memory index tree' (duration: 172.10631ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:50.259999Z","caller":"traceutil/trace.go:171","msg":"trace[1698260584] linearizableReadLoop","detail":"{readStateIndex:5775; appliedIndex:5774; }","duration":"258.658683ms","start":"2026-04-01T23:36:50.001308Z","end":"2026-04-01T23:36:50.259967Z","steps":["trace[1698260584] 'read index received' (duration: 258.366636ms)","trace[1698260584] 'applied index is now lower than readState.Index' (duration: 290.397µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:50.260125Z","caller":"traceutil/trace.go:171","msg":"trace[849637008] transaction","detail":"{read_only:false; response_revision:5570; number_of_response:1; }","duration":"273.577189ms","start":"2026-04-01T23:36:49.9865Z","end":"2026-04-01T23:36:50.260077Z","steps":["trace[849637008] 'process raft request' (duration: 273.226011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:50.260237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.370231ms","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-01T23:36:50.260298Z","caller":"traceutil/trace.go:171","msg":"trace[1199474241] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5570; }","duration":"125.509544ms","start":"2026-04-01T23:36:50.134778Z","end":"2026-04-01T23:36:50.260287Z","steps":["trace[1199474241] 'agreement among raft nodes before linearized reading' (duration: 125.374521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:50.260302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.95874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-01T23:36:50.260423Z","caller":"traceutil/trace.go:171","msg":"trace[1239724721] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5570; }","duration":"259.118734ms","start":"2026-04-01T23:36:50.001268Z","end":"2026-04-01T23:36:50.260387Z","steps":["trace[1239724721] 'agreement among raft nodes before linearized reading' (duration: 258.828997ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:50.376736Z","caller":"traceutil/trace.go:171","msg":"trace[498847970] transaction","detail":"{read_only:false; response_revision:5572; number_of_response:1; }","duration":"110.020723ms","start":"2026-04-01T23:36:50.266661Z","end":"2026-04-01T23:36:50.376681Z","steps":["trace[498847970] 'process raft request' (duration: 109.713536ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:50.376847Z","caller":"traceutil/trace.go:171","msg":"trace[22423141] linearizableReadLoop","detail":"{readStateIndex:5779; appliedIndex:5775; }","duration":"109.01728ms","start":"2026-04-01T23:36:50.267812Z","end":"2026-04-01T23:36:50.376829Z","steps":["trace[22423141] 'read index received' (duration: 52.906456ms)","trace[22423141] 'applied index is now lower than readState.Index' (duration: 56.109854ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:36:50.376781Z","caller":"traceutil/trace.go:171","msg":"trace[2102935753] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"110.095895ms","start":"2026-04-01T23:36:50.266657Z","end":"2026-04-01T23:36:50.376753Z","steps":["trace[2102935753] 'process raft request' (duration: 109.924501ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:50.376919Z","caller":"traceutil/trace.go:171","msg":"trace[921264757] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"108.920397ms","start":"2026-04-01T23:36:50.267948Z","end":"2026-04-01T23:36:50.376868Z","steps":["trace[921264757] 'process raft request' (duration: 108.728782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:36:50.37709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.256915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-01T23:36:50.377141Z","caller":"traceutil/trace.go:171","msg":"trace[1581857716] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5577; }","duration":"109.345518ms","start":"2026-04-01T23:36:50.267782Z","end":"2026-04-01T23:36:50.377127Z","steps":["trace[1581857716] 'agreement among raft nodes before linearized reading' (duration: 109.170664ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:50.376843Z","caller":"traceutil/trace.go:171","msg":"trace[367417192] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"109.645494ms","start":"2026-04-01T23:36:50.267157Z","end":"2026-04-01T23:36:50.376803Z","steps":["trace[367417192] 'process raft request' (duration: 109.47644ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:36:50.37771Z","caller":"traceutil/trace.go:171","msg":"trace[469880328] transaction","detail":"{read_only:false; response_revision:5576; number_of_response:1; }","duration":"100.146167ms","start":"2026-04-01T23:36:50.276715Z","end":"2026-04-01T23:36:50.376861Z","steps":["trace[469880328] 'process raft request' (duration: 100.005213ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:37:48.972122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-04-01T23:37:49.04144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"67.913747ms","hash":2528644414,"current-db-size-bytes":57466880,"current-db-size":"58 MB","current-db-size-in-use-bytes":33648640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-01T23:37:49.041519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2528644414,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-04-01T23:42:26.026693Z","caller":"traceutil/trace.go:171","msg":"trace[1403306574] linearizableReadLoop","detail":"{readStateIndex:8446; appliedIndex:8445; }","duration":"122.543394ms","start":"2026-04-01T23:42:25.904125Z","end":"2026-04-01T23:42:26.026668Z","steps":["trace[1403306574] 'read index received' (duration: 122.35809ms)","trace[1403306574] 'applied index is now lower than readState.Index' (duration: 184.164µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:42:26.026776Z","caller":"traceutil/trace.go:171","msg":"trace[373906098] transaction","detail":"{read_only:false; response_revision:8163; number_of_response:1; }","duration":"123.334044ms","start":"2026-04-01T23:42:25.9034Z","end":"2026-04-01T23:42:26.026734Z","steps":["trace[373906098] 'process raft request' (duration: 123.110298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:42:26.027031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.875463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414469"} {"level":"info","ts":"2026-04-01T23:42:26.027087Z","caller":"traceutil/trace.go:171","msg":"trace[1957600184] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8163; }","duration":"122.983015ms","start":"2026-04-01T23:42:25.904093Z","end":"2026-04-01T23:42:26.027076Z","steps":["trace[1957600184] 'agreement among raft nodes before linearized reading' (duration: 122.727778ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:42:39.530159Z","caller":"traceutil/trace.go:171","msg":"trace[1362128210] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"255.661522ms","start":"2026-04-01T23:42:39.274418Z","end":"2026-04-01T23:42:39.530079Z","steps":["trace[1362128210] 'process raft request' (duration: 255.427836ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:42:40.770209Z","caller":"traceutil/trace.go:171","msg":"trace[871170291] linearizableReadLoop","detail":"{readStateIndex:8525; appliedIndex:8524; }","duration":"102.672222ms","start":"2026-04-01T23:42:40.667509Z","end":"2026-04-01T23:42:40.770181Z","steps":["trace[871170291] 'read index received' (duration: 102.435606ms)","trace[871170291] 'applied index is now lower than readState.Index' (duration: 235.466µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:42:40.770311Z","caller":"traceutil/trace.go:171","msg":"trace[837584770] transaction","detail":"{read_only:false; response_revision:8238; number_of_response:1; }","duration":"121.240084ms","start":"2026-04-01T23:42:40.649024Z","end":"2026-04-01T23:42:40.770264Z","steps":["trace[837584770] 'process raft request' (duration: 120.969757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:42:40.770449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.911997ms","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-01T23:42:40.770522Z","caller":"traceutil/trace.go:171","msg":"trace[565418345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8238; }","duration":"103.007559ms","start":"2026-04-01T23:42:40.667497Z","end":"2026-04-01T23:42:40.770505Z","steps":["trace[565418345] 'agreement among raft nodes before linearized reading' (duration: 102.788554ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:42:48.979116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6089} {"level":"info","ts":"2026-04-01T23:42:49.073234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6089,"took":"92.009809ms","hash":1466126309,"current-db-size-bytes":57466880,"current-db-size":"58 MB","current-db-size-in-use-bytes":23330816,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-01T23:42:49.073307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1466126309,"revision":6089,"compact-revision":2978} {"level":"info","ts":"2026-04-01T23:43:07.068884Z","caller":"traceutil/trace.go:171","msg":"trace[1845010927] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"314.949351ms","start":"2026-04-01T23:43:06.753911Z","end":"2026-04-01T23:43:07.068861Z","steps":["trace[1845010927] 'process raft request' (duration: 314.793207ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:43:07.068975Z","caller":"traceutil/trace.go:171","msg":"trace[1952552981] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8728; }","duration":"184.349185ms","start":"2026-04-01T23:43:06.884575Z","end":"2026-04-01T23:43:07.068924Z","steps":["trace[1952552981] 'read index received' (duration: 184.325865ms)","trace[1952552981] 'applied index is now lower than readState.Index' (duration: 19.76µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:43:07.069042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:43:06.753884Z","time spent":"315.080563ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:43:07.069327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.721824ms","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-01T23:43:07.069378Z","caller":"traceutil/trace.go:171","msg":"trace[736489530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8433; }","duration":"184.824866ms","start":"2026-04-01T23:43:06.884536Z","end":"2026-04-01T23:43:07.069361Z","steps":["trace[736489530] 'agreement among raft nodes before linearized reading' (duration: 184.588861ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:43:07.327225Z","caller":"traceutil/trace.go:171","msg":"trace[499323090] linearizableReadLoop","detail":"{readStateIndex:8729; appliedIndex:8728; }","duration":"258.125829ms","start":"2026-04-01T23:43:07.069082Z","end":"2026-04-01T23:43:07.327208Z","steps":["trace[499323090] 'read index received' (duration: 258.062627ms)","trace[499323090] 'applied index is now lower than readState.Index' (duration: 62.382µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:43:07.327288Z","caller":"traceutil/trace.go:171","msg":"trace[901789933] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"295.60969ms","start":"2026-04-01T23:43:07.031663Z","end":"2026-04-01T23:43:07.327272Z","steps":["trace[901789933] 'process raft request' (duration: 295.438096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:43:07.327382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.256706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:43:07.327451Z","caller":"traceutil/trace.go:171","msg":"trace[1646960004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8434; }","duration":"378.353828ms","start":"2026-04-01T23:43:06.949081Z","end":"2026-04-01T23:43:07.327435Z","steps":["trace[1646960004] 'agreement among raft nodes before linearized reading' (duration: 378.196165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:43:07.327484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:43:06.949074Z","time spent":"378.40478ms","remote":"127.0.0.1:34130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-01T23:43:07.327402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.22623ms","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-01T23:43:07.327529Z","caller":"traceutil/trace.go:171","msg":"trace[1362992357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8434; }","duration":"440.389594ms","start":"2026-04-01T23:43:06.887132Z","end":"2026-04-01T23:43:07.327522Z","steps":["trace[1362992357] 'agreement among raft nodes before linearized reading' (duration: 440.154249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:43:07.327551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:43:06.887122Z","time spent":"440.423235ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T23:43:07.329464Z","caller":"traceutil/trace.go:171","msg":"trace[2120497640] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"256.07498ms","start":"2026-04-01T23:43:07.073373Z","end":"2026-04-01T23:43:07.329448Z","steps":["trace[2120497640] 'process raft request' (duration: 255.886576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:43:07.331281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.314971ms","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-01T23:43:07.331314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.652374ms","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-01T23:43:07.331322Z","caller":"traceutil/trace.go:171","msg":"trace[366161656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8435; }","duration":"170.394793ms","start":"2026-04-01T23:43:07.160919Z","end":"2026-04-01T23:43:07.331314Z","steps":["trace[366161656] 'agreement among raft nodes before linearized reading' (duration: 170.291601ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:43:07.331339Z","caller":"traceutil/trace.go:171","msg":"trace[81176663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8435; }","duration":"145.679545ms","start":"2026-04-01T23:43:07.185653Z","end":"2026-04-01T23:43:07.331333Z","steps":["trace[81176663] 'agreement among raft nodes before linearized reading' (duration: 145.603793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:43:20.275214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.779732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-01T23:43:20.275315Z","caller":"traceutil/trace.go:171","msg":"trace[429983579] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8545; }","duration":"264.672717ms","start":"2026-04-01T23:43:20.010624Z","end":"2026-04-01T23:43:20.275296Z","steps":["trace[429983579] 'process raft request' (duration: 130.652289ms)","trace[429983579] 'compare' (duration: 133.408224ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:43:20.372536Z","caller":"traceutil/trace.go:171","msg":"trace[1208921644] linearizableReadLoop","detail":"{readStateIndex:8847; appliedIndex:8846; }","duration":"224.037314ms","start":"2026-04-01T23:43:20.148473Z","end":"2026-04-01T23:43:20.37251Z","steps":["trace[1208921644] 'read index received' (duration: 223.905241ms)","trace[1208921644] 'applied index is now lower than readState.Index' (duration: 131.053µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:43:20.372582Z","caller":"traceutil/trace.go:171","msg":"trace[1206645375] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"359.67312ms","start":"2026-04-01T23:43:20.012884Z","end":"2026-04-01T23:43:20.372557Z","steps":["trace[1206645375] 'process raft request' (duration: 359.503396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:43:20.372768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:43:20.012874Z","time spent":"359.759252ms","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:43:20.372825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.339591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ftgp6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-01T23:43:20.372899Z","caller":"traceutil/trace.go:171","msg":"trace[867185026] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ftgp6; range_end:; response_count:1; response_revision:8546; }","duration":"224.426663ms","start":"2026-04-01T23:43:20.148456Z","end":"2026-04-01T23:43:20.372883Z","steps":["trace[867185026] 'agreement among raft nodes before linearized reading' (duration: 224.151926ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:43:21.270218Z","caller":"traceutil/trace.go:171","msg":"trace[347851354] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"190.620551ms","start":"2026-04-01T23:43:21.079569Z","end":"2026-04-01T23:43:21.270189Z","steps":["trace[347851354] 'process raft request' (duration: 182.731324ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:43:21.270214Z","caller":"traceutil/trace.go:171","msg":"trace[288706132] linearizableReadLoop","detail":"{readStateIndex:8852; appliedIndex:8851; }","duration":"111.72906ms","start":"2026-04-01T23:43:21.158456Z","end":"2026-04-01T23:43:21.270185Z","steps":["trace[288706132] 'read index received' (duration: 103.851193ms)","trace[288706132] 'applied index is now lower than readState.Index' (duration: 7.876767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:43:21.270453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.968556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-04-01T23:43:21.27054Z","caller":"traceutil/trace.go:171","msg":"trace[2092270648] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8551; }","duration":"112.109119ms","start":"2026-04-01T23:43:21.158419Z","end":"2026-04-01T23:43:21.270528Z","steps":["trace[2092270648] 'agreement among raft nodes before linearized reading' (duration: 111.881734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:43:21.838948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.317629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-01T23:43:21.83905Z","caller":"traceutil/trace.go:171","msg":"trace[1314660736] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8559; }","duration":"116.511614ms","start":"2026-04-01T23:43:21.722515Z","end":"2026-04-01T23:43:21.839027Z","steps":["trace[1314660736] 'range keys from in-memory index tree' (duration: 116.106284ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:43:21.985705Z","caller":"traceutil/trace.go:171","msg":"trace[505520441] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"128.855676ms","start":"2026-04-01T23:43:21.856819Z","end":"2026-04-01T23:43:21.985675Z","steps":["trace[505520441] 'process raft request' (duration: 87.994586ms)","trace[505520441] 'compare' (duration: 40.690176ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:44:26.288387Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000255a.wal"} {"level":"info","ts":"2026-04-01T23:44:30.824078Z","caller":"traceutil/trace.go:171","msg":"trace[908187989] transaction","detail":"{read_only:false; response_revision:9265; number_of_response:1; }","duration":"166.650723ms","start":"2026-04-01T23:44:30.657403Z","end":"2026-04-01T23:44:30.824054Z","steps":["trace[908187989] 'process raft request' (duration: 166.500419ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:45:26.085373Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-01T23:45:26.089848Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-01T23:45:26.090076Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-01T23:46:56.033471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.600479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-01T23:46:56.033623Z","caller":"traceutil/trace.go:171","msg":"trace[2120363476] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:10349; }","duration":"111.820634ms","start":"2026-04-01T23:46:55.921761Z","end":"2026-04-01T23:46:56.033582Z","steps":["trace[2120363476] 'range keys from in-memory index tree' (duration: 111.341904ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:46:56.718504Z","caller":"traceutil/trace.go:171","msg":"trace[6124042] transaction","detail":"{read_only:false; response_revision:10357; number_of_response:1; }","duration":"148.101907ms","start":"2026-04-01T23:46:56.57036Z","end":"2026-04-01T23:46:56.718462Z","steps":["trace[6124042] 'process raft request' (duration: 147.989594ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:46:56.718599Z","caller":"traceutil/trace.go:171","msg":"trace[619664801] transaction","detail":"{read_only:false; response_revision:10358; number_of_response:1; }","duration":"147.624026ms","start":"2026-04-01T23:46:56.570949Z","end":"2026-04-01T23:46:56.718573Z","steps":["trace[619664801] 'process raft request' (duration: 147.457422ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:46:56.71853Z","caller":"traceutil/trace.go:171","msg":"trace[1793640752] transaction","detail":"{read_only:false; response_revision:10356; number_of_response:1; }","duration":"148.193928ms","start":"2026-04-01T23:46:56.570303Z","end":"2026-04-01T23:46:56.718497Z","steps":["trace[1793640752] 'process raft request' (duration: 147.78988ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:46:57.716682Z","caller":"traceutil/trace.go:171","msg":"trace[65999554] transaction","detail":"{read_only:false; response_revision:10363; number_of_response:1; }","duration":"221.897639ms","start":"2026-04-01T23:46:57.494682Z","end":"2026-04-01T23:46:57.71658Z","steps":["trace[65999554] 'process raft request' (duration: 221.713545ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:47:02.564417Z","caller":"traceutil/trace.go:171","msg":"trace[760377156] transaction","detail":"{read_only:false; response_revision:10411; number_of_response:1; }","duration":"102.841843ms","start":"2026-04-01T23:47:02.461546Z","end":"2026-04-01T23:47:02.564388Z","steps":["trace[760377156] 'process raft request' (duration: 102.682649ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:47:48.985163Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8291} {"level":"info","ts":"2026-04-01T23:47:49.071687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8291,"took":"84.300103ms","hash":301741584,"current-db-size-bytes":57466880,"current-db-size":"58 MB","current-db-size-in-use-bytes":24408064,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-01T23:47:49.071748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":301741584,"revision":8291,"compact-revision":6089} {"level":"info","ts":"2026-04-01T23:52:48.993978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10676} {"level":"info","ts":"2026-04-01T23:52:49.073843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10676,"took":"77.87522ms","hash":979670444,"current-db-size-bytes":57466880,"current-db-size":"58 MB","current-db-size-in-use-bytes":20828160,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-01T23:52:49.073906Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":979670444,"revision":10676,"compact-revision":8291}