{"level":"warn","ts":"2026-04-08T13:02:22.356858Z","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-08T13:02:22.356999Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.75: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.19.213.75:2380","--initial-cluster=instance=https://199.19.213.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.75: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-08T13:02:22.357098Z","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-08T13:02:22.357151Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-08T13:02:22.357181Z","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-08T13:02:22.357995Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"]} {"level":"info","ts":"2026-04-08T13:02:22.358117Z","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.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.75: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-08T13:02:22.362585Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.175315ms"} {"level":"info","ts":"2026-04-08T13:02:22.36904Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"42c325ad8bcf8012","cluster-id":"1e2ab336c559df5e"} {"level":"info","ts":"2026-04-08T13:02:22.369185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T13:02:22.369225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 0"} {"level":"info","ts":"2026-04-08T13:02:22.369246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 42c325ad8bcf8012 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T13:02:22.369258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 1"} {"level":"info","ts":"2026-04-08T13:02:22.369304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"warn","ts":"2026-04-08T13:02:22.376276Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T13:02:22.378467Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T13:02:22.379551Z","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-08T13:02:22.382417Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"42c325ad8bcf8012","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T13:02:22.382606Z","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-08T13:02:22.382696Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"42c325ad8bcf8012","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T13:02:22.382785Z","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-08T13:02:22.382844Z","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-08T13:02:22.383775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"info","ts":"2026-04-08T13:02:22.38602Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","added-peer-id":"42c325ad8bcf8012","added-peer-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-08T13:02:22.390134Z","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-08T13:02:22.390341Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"42c325ad8bcf8012","initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T13:02:22.390373Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T13:02:22.390473Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-08T13:02:22.390543Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-08T13:02:22.669788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T13:02:22.670004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T13:02:22.670125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgPreVoteResp from 42c325ad8bcf8012 at term 1"} {"level":"info","ts":"2026-04-08T13:02:22.670237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became candidate at term 2"} {"level":"info","ts":"2026-04-08T13:02:22.670315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgVoteResp from 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-08T13:02:22.67043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became leader at term 2"} {"level":"info","ts":"2026-04-08T13:02:22.670551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 42c325ad8bcf8012 elected leader 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-08T13:02:22.671894Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:02:22.672451Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"42c325ad8bcf8012","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.75:2379]}","request-path":"/0/members/42c325ad8bcf8012/attributes","cluster-id":"1e2ab336c559df5e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T13:02:22.672794Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T13:02:22.67286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T13:02:22.673176Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:02:22.673383Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T13:02:22.673464Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:02:22.673482Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T13:02:22.673519Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T13:02:22.676209Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.75:2379"} {"level":"info","ts":"2026-04-08T13:02:22.677432Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T13:02:38.450406Z","caller":"traceutil/trace.go:171","msg":"trace[1340655093] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"309.855258ms","start":"2026-04-08T13:02:38.140523Z","end":"2026-04-08T13:02:38.450379Z","steps":["trace[1340655093] 'process raft request' (duration: 309.683623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:38.450972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:02:38.140487Z","time spent":"310.006552ms","remote":"127.0.0.1:48926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:02:38.45287Z","caller":"traceutil/trace.go:171","msg":"trace[1709157670] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"186.64413ms","start":"2026-04-08T13:02:38.266191Z","end":"2026-04-08T13:02:38.452835Z","steps":["trace[1709157670] 'read index received' (duration: 184.012538ms)","trace[1709157670] 'applied index is now lower than readState.Index' (duration: 2.630222ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:38.45292Z","caller":"traceutil/trace.go:171","msg":"trace[1849666626] transaction","detail":"{read_only:false; number_of_response:1; response_revision:366; }","duration":"243.312309ms","start":"2026-04-08T13:02:38.209578Z","end":"2026-04-08T13:02:38.452891Z","steps":["trace[1849666626] 'process raft request' (duration: 243.151714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:38.453076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.861717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-08T13:02:38.453131Z","caller":"traceutil/trace.go:171","msg":"trace[1547866728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:366; }","duration":"186.98304ms","start":"2026-04-08T13:02:38.266137Z","end":"2026-04-08T13:02:38.453121Z","steps":["trace[1547866728] 'agreement among raft nodes before linearized reading' (duration: 186.807255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:55.325114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.123728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:02:55.32523Z","caller":"traceutil/trace.go:171","msg":"trace[1868956683] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"281.101195ms","start":"2026-04-08T13:02:55.04411Z","end":"2026-04-08T13:02:55.325212Z","steps":["trace[1868956683] 'process raft request' (duration: 147.66574ms)","trace[1868956683] 'compare' (duration: 132.969763ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:55.339187Z","caller":"traceutil/trace.go:171","msg":"trace[1202627171] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"113.878649ms","start":"2026-04-08T13:02:55.225289Z","end":"2026-04-08T13:02:55.339167Z","steps":["trace[1202627171] 'read index received' (duration: 113.56501ms)","trace[1202627171] 'applied index is now lower than readState.Index' (duration: 312.749µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:55.339544Z","caller":"traceutil/trace.go:171","msg":"trace[1061764961] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"267.51969ms","start":"2026-04-08T13:02:55.072013Z","end":"2026-04-08T13:02:55.339532Z","steps":["trace[1061764961] 'process raft request' (duration: 266.959065ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:55.339684Z","caller":"traceutil/trace.go:171","msg":"trace[1012417021] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"247.803599ms","start":"2026-04-08T13:02:55.091874Z","end":"2026-04-08T13:02:55.339677Z","steps":["trace[1012417021] 'process raft request' (duration: 247.258064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:55.339806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.508596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-08T13:02:55.339836Z","caller":"traceutil/trace.go:171","msg":"trace[1892890178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:531; }","duration":"114.582038ms","start":"2026-04-08T13:02:55.225247Z","end":"2026-04-08T13:02:55.339829Z","steps":["trace[1892890178] 'agreement among raft nodes before linearized reading' (duration: 114.513516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:55.340037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.183687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T13:02:55.340064Z","caller":"traceutil/trace.go:171","msg":"trace[982563129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:531; }","duration":"114.268149ms","start":"2026-04-08T13:02:55.22579Z","end":"2026-04-08T13:02:55.340058Z","steps":["trace[982563129] 'agreement among raft nodes before linearized reading' (duration: 114.222288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:01.942613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.293492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:03:01.942666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.22551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:03:01.942728Z","caller":"traceutil/trace.go:171","msg":"trace[1686507318] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:583; }","duration":"189.391355ms","start":"2026-04-08T13:03:01.753309Z","end":"2026-04-08T13:03:01.9427Z","steps":["trace[1686507318] 'range keys from in-memory index tree' (duration: 189.151148ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:01.94278Z","caller":"traceutil/trace.go:171","msg":"trace[1318875798] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:583; }","duration":"189.381004ms","start":"2026-04-08T13:03:01.753373Z","end":"2026-04-08T13:03:01.942754Z","steps":["trace[1318875798] 'count revisions from in-memory index tree' (duration: 189.147818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:01.942886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.979969ms","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":"warn","ts":"2026-04-08T13:03:01.942925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.319862ms","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-08T13:03:01.942993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.517933ms","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-08T13:03:01.943028Z","caller":"traceutil/trace.go:171","msg":"trace[1255445189] 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:583; }","duration":"118.179265ms","start":"2026-04-08T13:03:01.82479Z","end":"2026-04-08T13:03:01.942969Z","steps":["trace[1255445189] 'count revisions from in-memory index tree' (duration: 117.777384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:01.943093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.304344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:03:01.942535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.795113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:03:01.943165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.878951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:03:01.943218Z","caller":"traceutil/trace.go:171","msg":"trace[1416895514] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:583; }","duration":"188.944802ms","start":"2026-04-08T13:03:01.754258Z","end":"2026-04-08T13:03:01.943203Z","steps":["trace[1416895514] 'range keys from in-memory index tree' (duration: 188.838899ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:01.943235Z","caller":"traceutil/trace.go:171","msg":"trace[288446624] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:583; }","duration":"135.595695ms","start":"2026-04-08T13:03:01.807589Z","end":"2026-04-08T13:03:01.943185Z","steps":["trace[288446624] 'range keys from in-memory index tree' (duration: 134.65906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:01.943048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.799896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:03:01.943523Z","caller":"traceutil/trace.go:171","msg":"trace[301966952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"265.30002ms","start":"2026-04-08T13:03:01.678184Z","end":"2026-04-08T13:03:01.943484Z","steps":["trace[301966952] 'range keys from in-memory index tree' (duration: 264.686523ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:01.942993Z","caller":"traceutil/trace.go:171","msg":"trace[1584865881] 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:583; }","duration":"117.420784ms","start":"2026-04-08T13:03:01.825556Z","end":"2026-04-08T13:03:01.942976Z","steps":["trace[1584865881] 'range keys from in-memory index tree' (duration: 117.277201ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:01.943058Z","caller":"traceutil/trace.go:171","msg":"trace[639915964] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"374.599915ms","start":"2026-04-08T13:03:01.568432Z","end":"2026-04-08T13:03:01.943032Z","steps":["trace[639915964] 'range keys from in-memory index tree' (duration: 374.458752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:01.943725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:01.56841Z","time spent":"375.293094ms","remote":"127.0.0.1:55146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-08T13:03:01.942901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.111532ms","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-08T13:03:01.94381Z","caller":"traceutil/trace.go:171","msg":"trace[1957147570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:583; }","duration":"375.093059ms","start":"2026-04-08T13:03:01.568697Z","end":"2026-04-08T13:03:01.94379Z","steps":["trace[1957147570] 'count revisions from in-memory index tree' (duration: 373.991039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:01.943873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:03:01.568666Z","time spent":"375.187241ms","remote":"127.0.0.1:55146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:03:01.943109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.19541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:03:01.943998Z","caller":"traceutil/trace.go:171","msg":"trace[1166336509] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:583; }","duration":"137.114056ms","start":"2026-04-08T13:03:01.806854Z","end":"2026-04-08T13:03:01.943968Z","steps":["trace[1166336509] 'count revisions from in-memory index tree' (duration: 136.047576ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:01.943143Z","caller":"traceutil/trace.go:171","msg":"trace[414897044] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:583; }","duration":"136.388956ms","start":"2026-04-08T13:03:01.80674Z","end":"2026-04-08T13:03:01.943129Z","steps":["trace[414897044] 'range keys from in-memory index tree' (duration: 136.235982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:03:01.943752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.158574ms","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-08T13:03:01.944957Z","caller":"traceutil/trace.go:171","msg":"trace[358824247] 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:583; }","duration":"120.137408ms","start":"2026-04-08T13:03:01.824802Z","end":"2026-04-08T13:03:01.94494Z","steps":["trace[358824247] 'range keys from in-memory index tree' (duration: 117.967669ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:19.194572Z","caller":"traceutil/trace.go:171","msg":"trace[1373844372] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"170.227757ms","start":"2026-04-08T13:03:19.024301Z","end":"2026-04-08T13:03:19.194528Z","steps":["trace[1373844372] 'process raft request' (duration: 170.034062ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:19.386083Z","caller":"traceutil/trace.go:171","msg":"trace[1868032213] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"180.666449ms","start":"2026-04-08T13:03:19.205372Z","end":"2026-04-08T13:03:19.386038Z","steps":["trace[1868032213] 'process raft request' (duration: 174.492553ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:10.482113Z","caller":"traceutil/trace.go:171","msg":"trace[1131706964] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"149.413056ms","start":"2026-04-08T13:04:10.332656Z","end":"2026-04-08T13:04:10.482069Z","steps":["trace[1131706964] 'process raft request' (duration: 149.304533ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:33.75779Z","caller":"traceutil/trace.go:171","msg":"trace[200937105] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"337.421374ms","start":"2026-04-08T13:04:33.420312Z","end":"2026-04-08T13:04:33.757734Z","steps":["trace[200937105] 'process raft request' (duration: 336.8997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:33.75811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:04:33.420271Z","time spent":"337.705851ms","remote":"127.0.0.1:48928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9334,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:04:33.955988Z","caller":"traceutil/trace.go:171","msg":"trace[901716928] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"192.490868ms","start":"2026-04-08T13:04:33.763439Z","end":"2026-04-08T13:04:33.95593Z","steps":["trace[901716928] 'process raft request' (duration: 192.189159ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:33.956183Z","caller":"traceutil/trace.go:171","msg":"trace[1289375315] linearizableReadLoop","detail":"{readStateIndex:1854; appliedIndex:1850; }","duration":"155.960163ms","start":"2026-04-08T13:04:33.800213Z","end":"2026-04-08T13:04:33.956173Z","steps":["trace[1289375315] 'read index received' (duration: 42.530596ms)","trace[1289375315] 'applied index is now lower than readState.Index' (duration: 113.428297ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:33.956287Z","caller":"traceutil/trace.go:171","msg":"trace[1633155463] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"191.585173ms","start":"2026-04-08T13:04:33.764683Z","end":"2026-04-08T13:04:33.956268Z","steps":["trace[1633155463] 'process raft request' (duration: 191.219153ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:33.956005Z","caller":"traceutil/trace.go:171","msg":"trace[239751873] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"191.552532ms","start":"2026-04-08T13:04:33.76442Z","end":"2026-04-08T13:04:33.955973Z","steps":["trace[239751873] 'process raft request' (duration: 191.409208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:04:33.956404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.181869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-08T13:04:33.956849Z","caller":"traceutil/trace.go:171","msg":"trace[908639791] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1800; }","duration":"156.679852ms","start":"2026-04-08T13:04:33.800122Z","end":"2026-04-08T13:04:33.956801Z","steps":["trace[908639791] 'agreement among raft nodes before linearized reading' (duration: 156.105207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:17.318003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.196693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-08T13:05:17.318125Z","caller":"traceutil/trace.go:171","msg":"trace[947866176] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"212.376248ms","start":"2026-04-08T13:05:17.105723Z","end":"2026-04-08T13:05:17.318099Z","steps":["trace[947866176] 'range keys from in-memory index tree' (duration: 211.914465ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:05:17.476037Z","caller":"traceutil/trace.go:171","msg":"trace[605130451] linearizableReadLoop","detail":"{readStateIndex:2213; appliedIndex:2212; }","duration":"138.226359ms","start":"2026-04-08T13:05:17.337786Z","end":"2026-04-08T13:05:17.476012Z","steps":["trace[605130451] 'read index received' (duration: 138.046904ms)","trace[605130451] 'applied index is now lower than readState.Index' (duration: 178.385µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:05:17.476149Z","caller":"traceutil/trace.go:171","msg":"trace[830809938] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"147.800126ms","start":"2026-04-08T13:05:17.328318Z","end":"2026-04-08T13:05:17.476118Z","steps":["trace[830809938] 'process raft request' (duration: 147.546599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:17.4763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.047843ms","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-08T13:05:17.476354Z","caller":"traceutil/trace.go:171","msg":"trace[1860824681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2142; }","duration":"110.179966ms","start":"2026-04-08T13:05:17.366142Z","end":"2026-04-08T13:05:17.476322Z","steps":["trace[1860824681] 'agreement among raft nodes before linearized reading' (duration: 110.056883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:17.47638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.554478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a4637186b0b625\" ","response":"range_response_count:1 size:845"} {"level":"info","ts":"2026-04-08T13:05:17.47649Z","caller":"traceutil/trace.go:171","msg":"trace[195854494] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a4637186b0b625; range_end:; response_count:1; response_revision:2142; }","duration":"138.744493ms","start":"2026-04-08T13:05:17.337721Z","end":"2026-04-08T13:05:17.476465Z","steps":["trace[195854494] 'agreement among raft nodes before linearized reading' (duration: 138.419204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:05:17.476527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.040669ms","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-08T13:05:17.476707Z","caller":"traceutil/trace.go:171","msg":"trace[501629053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2142; }","duration":"111.227034ms","start":"2026-04-08T13:05:17.365421Z","end":"2026-04-08T13:05:17.476648Z","steps":["trace[501629053] 'agreement among raft nodes before linearized reading' (duration: 110.752041ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:06:35.409219Z","caller":"traceutil/trace.go:171","msg":"trace[981451672] linearizableReadLoop","detail":"{readStateIndex:2830; appliedIndex:2829; }","duration":"160.657966ms","start":"2026-04-08T13:06:35.248528Z","end":"2026-04-08T13:06:35.409186Z","steps":["trace[981451672] 'read index received' (duration: 160.330157ms)","trace[981451672] 'applied index is now lower than readState.Index' (duration: 326.529µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:06:35.409274Z","caller":"traceutil/trace.go:171","msg":"trace[789697349] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"236.782124ms","start":"2026-04-08T13:06:35.172456Z","end":"2026-04-08T13:06:35.409238Z","steps":["trace[789697349] 'process raft request' (duration: 236.535127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:06:35.409393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.846141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:06:35.409481Z","caller":"traceutil/trace.go:171","msg":"trace[1123933935] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2726; }","duration":"160.970474ms","start":"2026-04-08T13:06:35.248478Z","end":"2026-04-08T13:06:35.409449Z","steps":["trace[1123933935] 'agreement among raft nodes before linearized reading' (duration: 160.83727ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:07.796324Z","caller":"traceutil/trace.go:171","msg":"trace[49646005] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"129.303008ms","start":"2026-04-08T13:09:07.666984Z","end":"2026-04-08T13:09:07.796287Z","steps":["trace[49646005] 'process raft request' (duration: 128.773354ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:07.809069Z","caller":"traceutil/trace.go:171","msg":"trace[1952699550] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"140.794758ms","start":"2026-04-08T13:09:07.668255Z","end":"2026-04-08T13:09:07.809049Z","steps":["trace[1952699550] 'process raft request' (duration: 140.47286ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:09:17.863048Z","caller":"traceutil/trace.go:171","msg":"trace[1238047924] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"111.024045ms","start":"2026-04-08T13:09:17.751986Z","end":"2026-04-08T13:09:17.86301Z","steps":["trace[1238047924] 'process raft request' (duration: 72.082064ms)","trace[1238047924] 'compare' (duration: 38.655633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:18.499315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.242992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:09:18.499477Z","caller":"traceutil/trace.go:171","msg":"trace[54606869] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"472.047683ms","start":"2026-04-08T13:09:18.027402Z","end":"2026-04-08T13:09:18.49945Z","steps":["trace[54606869] 'process raft request' (duration: 281.544804ms)","trace[54606869] 'compare' (duration: 190.078638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:09:18.499555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:18.027369Z","time spent":"472.151426ms","remote":"127.0.0.1:48928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4982,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:09:18.553926Z","caller":"traceutil/trace.go:171","msg":"trace[1605573933] linearizableReadLoop","detail":"{readStateIndex:5053; appliedIndex:5051; }","duration":"453.205095ms","start":"2026-04-08T13:09:18.100688Z","end":"2026-04-08T13:09:18.553893Z","steps":["trace[1605573933] 'read index received' (duration: 208.241577ms)","trace[1605573933] 'applied index is now lower than readState.Index' (duration: 244.962058ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:18.554069Z","caller":"traceutil/trace.go:171","msg":"trace[348066865] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"506.009221ms","start":"2026-04-08T13:09:18.048012Z","end":"2026-04-08T13:09:18.554021Z","steps":["trace[348066865] 'process raft request' (duration: 505.723613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:18.554125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.412671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:09:18.554169Z","caller":"traceutil/trace.go:171","msg":"trace[881608412] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4850; }","duration":"453.541744ms","start":"2026-04-08T13:09:18.100615Z","end":"2026-04-08T13:09:18.554157Z","steps":["trace[881608412] 'agreement among raft nodes before linearized reading' (duration: 453.426841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:18.554216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:18.100541Z","time spent":"453.663847ms","remote":"127.0.0.1:48910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":4,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:09:18.554284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:18.047991Z","time spent":"506.153864ms","remote":"127.0.0.1:49032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:09:18.568265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.066082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:09:18.568353Z","caller":"traceutil/trace.go:171","msg":"trace[1448633823] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4853; }","duration":"453.194735ms","start":"2026-04-08T13:09:18.115124Z","end":"2026-04-08T13:09:18.568319Z","steps":["trace[1448633823] 'agreement among raft nodes before linearized reading' (duration: 452.99363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:18.568387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:18.115101Z","time spent":"453.277948ms","remote":"127.0.0.1:42258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-04-08T13:09:18.568643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.524319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-08T13:09:18.568706Z","caller":"traceutil/trace.go:171","msg":"trace[1631247687] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4853; }","duration":"342.632283ms","start":"2026-04-08T13:09:18.226055Z","end":"2026-04-08T13:09:18.568687Z","steps":["trace[1631247687] 'agreement among raft nodes before linearized reading' (duration: 342.443598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:09:18.568747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:09:18.226029Z","time spent":"342.709134ms","remote":"127.0.0.1:48864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-08T13:12:22.711658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3213} {"level":"info","ts":"2026-04-08T13:12:22.802783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3213,"took":"89.044214ms","hash":547538614,"current-db-size-bytes":60280832,"current-db-size":"60 MB","current-db-size-in-use-bytes":33464320,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-08T13:12:22.802859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":547538614,"revision":3213,"compact-revision":-1} {"level":"info","ts":"2026-04-08T13:12:53.537042Z","caller":"traceutil/trace.go:171","msg":"trace[592551585] transaction","detail":"{read_only:false; response_revision:6784; number_of_response:1; }","duration":"170.637858ms","start":"2026-04-08T13:12:53.366369Z","end":"2026-04-08T13:12:53.537007Z","steps":["trace[592551585] 'process raft request' (duration: 170.429192ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:12.214621Z","caller":"traceutil/trace.go:171","msg":"trace[1785139549] transaction","detail":"{read_only:false; response_revision:7998; number_of_response:1; }","duration":"180.370944ms","start":"2026-04-08T13:15:12.034215Z","end":"2026-04-08T13:15:12.214586Z","steps":["trace[1785139549] 'process raft request' (duration: 180.098357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:12.816985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.932161ms","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-08T13:15:12.817189Z","caller":"traceutil/trace.go:171","msg":"trace[2069495272] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:7998; }","duration":"442.176738ms","start":"2026-04-08T13:15:12.374982Z","end":"2026-04-08T13:15:12.817158Z","steps":["trace[2069495272] 'range keys from in-memory index tree' (duration: 441.902901ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:12.817293Z","caller":"traceutil/trace.go:171","msg":"trace[1418913673] linearizableReadLoop","detail":"{readStateIndex:8357; appliedIndex:8355; }","duration":"713.4589ms","start":"2026-04-08T13:15:12.103813Z","end":"2026-04-08T13:15:12.817272Z","steps":["trace[1418913673] 'read index received' (duration: 110.561431ms)","trace[1418913673] 'applied index is now lower than readState.Index' (duration: 602.896359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:12.81772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.890881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-04-08T13:15:12.817688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.211577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:15:12.817765Z","caller":"traceutil/trace.go:171","msg":"trace[1760647632] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8000; }","duration":"713.980914ms","start":"2026-04-08T13:15:12.103771Z","end":"2026-04-08T13:15:12.817752Z","steps":["trace[1760647632] 'agreement among raft nodes before linearized reading' (duration: 713.660705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:12.817793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:12.103759Z","time spent":"714.027975ms","remote":"127.0.0.1:48864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-08T13:15:12.817868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.36309ms","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-08T13:15:12.817926Z","caller":"traceutil/trace.go:171","msg":"trace[814967167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8000; }","duration":"171.479633ms","start":"2026-04-08T13:15:12.646431Z","end":"2026-04-08T13:15:12.817911Z","steps":["trace[814967167] 'agreement among raft nodes before linearized reading' (duration: 171.088953ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:12.817392Z","caller":"traceutil/trace.go:171","msg":"trace[88720089] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"716.693308ms","start":"2026-04-08T13:15:12.10061Z","end":"2026-04-08T13:15:12.817303Z","steps":["trace[88720089] 'process raft request' (duration: 696.88707ms)","trace[88720089] 'compare' (duration: 19.200201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:12.81809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.896806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-08T13:15:12.817816Z","caller":"traceutil/trace.go:171","msg":"trace[880661943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8000; }","duration":"139.378462ms","start":"2026-04-08T13:15:12.678404Z","end":"2026-04-08T13:15:12.817783Z","steps":["trace[880661943] 'agreement among raft nodes before linearized reading' (duration: 139.146585ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:12.8182Z","caller":"traceutil/trace.go:171","msg":"trace[1055982620] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8000; }","duration":"140.06833ms","start":"2026-04-08T13:15:12.678105Z","end":"2026-04-08T13:15:12.818173Z","steps":["trace[1055982620] 'agreement among raft nodes before linearized reading' (duration: 139.733431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:12.81865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.384751ms","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-08T13:15:12.818591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:12.100555Z","time spent":"717.51631ms","remote":"127.0.0.1:49032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:15:12.818726Z","caller":"traceutil/trace.go:171","msg":"trace[163031372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8000; }","duration":"171.487764ms","start":"2026-04-08T13:15:12.647221Z","end":"2026-04-08T13:15:12.818708Z","steps":["trace[163031372] 'agreement among raft nodes before linearized reading' (duration: 171.253887ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:13.318679Z","caller":"traceutil/trace.go:171","msg":"trace[23322403] linearizableReadLoop","detail":"{readStateIndex:8365; appliedIndex:8364; }","duration":"238.640616ms","start":"2026-04-08T13:15:13.080007Z","end":"2026-04-08T13:15:13.318647Z","steps":["trace[23322403] 'read index received' (duration: 238.476481ms)","trace[23322403] 'applied index is now lower than readState.Index' (duration: 162.685µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:13.31892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.886052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-08T13:15:13.318962Z","caller":"traceutil/trace.go:171","msg":"trace[1464303550] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8007; }","duration":"238.991865ms","start":"2026-04-08T13:15:13.07996Z","end":"2026-04-08T13:15:13.318952Z","steps":["trace[1464303550] 'agreement among raft nodes before linearized reading' (duration: 238.8055ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:13.318887Z","caller":"traceutil/trace.go:171","msg":"trace[362693444] transaction","detail":"{read_only:false; response_revision:8007; number_of_response:1; }","duration":"262.985336ms","start":"2026-04-08T13:15:13.055792Z","end":"2026-04-08T13:15:13.318777Z","steps":["trace[362693444] 'process raft request' (duration: 262.677108ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:13.333272Z","caller":"traceutil/trace.go:171","msg":"trace[1180203852] transaction","detail":"{read_only:false; response_revision:8008; number_of_response:1; }","duration":"104.823665ms","start":"2026-04-08T13:15:13.228412Z","end":"2026-04-08T13:15:13.333235Z","steps":["trace[1180203852] 'process raft request' (duration: 104.630589ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:14.500879Z","caller":"traceutil/trace.go:171","msg":"trace[533823456] transaction","detail":"{read_only:false; response_revision:8015; number_of_response:1; }","duration":"155.785247ms","start":"2026-04-08T13:15:14.34506Z","end":"2026-04-08T13:15:14.500846Z","steps":["trace[533823456] 'process raft request' (duration: 155.599342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:15.056833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.622522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:15:15.057108Z","caller":"traceutil/trace.go:171","msg":"trace[2063992862] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"232.500529ms","start":"2026-04-08T13:15:14.824592Z","end":"2026-04-08T13:15:15.057093Z","steps":["trace[2063992862] 'process raft request' (duration: 232.458287ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:15.057416Z","caller":"traceutil/trace.go:171","msg":"trace[459245219] transaction","detail":"{read_only:false; response_revision:8016; number_of_response:1; }","duration":"543.807686ms","start":"2026-04-08T13:15:14.513565Z","end":"2026-04-08T13:15:15.057372Z","steps":["trace[459245219] 'process raft request' (duration: 352.539276ms)","trace[459245219] 'compare' (duration: 190.199531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:15.057659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:14.513538Z","time spent":"544.092633ms","remote":"127.0.0.1:48928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:15:15.057789Z","caller":"traceutil/trace.go:171","msg":"trace[878764354] linearizableReadLoop","detail":"{readStateIndex:8374; appliedIndex:8373; }","duration":"448.007246ms","start":"2026-04-08T13:15:14.609773Z","end":"2026-04-08T13:15:15.05778Z","steps":["trace[878764354] 'read index received' (duration: 256.344036ms)","trace[878764354] 'applied index is now lower than readState.Index' (duration: 191.66154ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:15:15.057972Z","caller":"traceutil/trace.go:171","msg":"trace[218903461] transaction","detail":"{read_only:false; response_revision:8017; number_of_response:1; }","duration":"283.78297ms","start":"2026-04-08T13:15:14.774179Z","end":"2026-04-08T13:15:15.057962Z","steps":["trace[218903461] 'process raft request' (duration: 282.767363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:15.058034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.455599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T13:15:15.058079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.266974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-08T13:15:15.058181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.914136ms","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-08T13:15:15.058183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.800097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-04-08T13:15:15.058134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.547372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:15:15.058216Z","caller":"traceutil/trace.go:171","msg":"trace[26323936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8018; }","duration":"223.990768ms","start":"2026-04-08T13:15:14.834217Z","end":"2026-04-08T13:15:15.058208Z","steps":["trace[26323936] 'agreement among raft nodes before linearized reading' (duration: 223.868755ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:15.058227Z","caller":"traceutil/trace.go:171","msg":"trace[174885712] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8018; }","duration":"190.858109ms","start":"2026-04-08T13:15:14.867355Z","end":"2026-04-08T13:15:15.058213Z","steps":["trace[174885712] 'agreement among raft nodes before linearized reading' (duration: 190.695885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:15.058224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.492018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:1 size:7503"} {"level":"info","ts":"2026-04-08T13:15:15.058261Z","caller":"traceutil/trace.go:171","msg":"trace[1936207664] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:1; response_revision:8018; }","duration":"232.55063ms","start":"2026-04-08T13:15:14.825703Z","end":"2026-04-08T13:15:15.058253Z","steps":["trace[1936207664] 'agreement among raft nodes before linearized reading' (duration: 232.457158ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:15.058263Z","caller":"traceutil/trace.go:171","msg":"trace[1450039353] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8018; }","duration":"186.723277ms","start":"2026-04-08T13:15:14.871508Z","end":"2026-04-08T13:15:15.058231Z","steps":["trace[1450039353] 'agreement among raft nodes before linearized reading' (duration: 186.47264ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:15.058197Z","caller":"traceutil/trace.go:171","msg":"trace[1545409182] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:8018; }","duration":"448.414438ms","start":"2026-04-08T13:15:14.609756Z","end":"2026-04-08T13:15:15.058171Z","steps":["trace[1545409182] 'agreement among raft nodes before linearized reading' (duration: 448.166271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:15.058334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:14.609678Z","time spent":"448.643384ms","remote":"127.0.0.1:49240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":22,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-08T13:15:15.05814Z","caller":"traceutil/trace.go:171","msg":"trace[548151672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8018; }","duration":"378.620744ms","start":"2026-04-08T13:15:14.679488Z","end":"2026-04-08T13:15:15.058109Z","steps":["trace[548151672] 'agreement among raft nodes before linearized reading' (duration: 378.45781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:15.058398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:14.679445Z","time spent":"378.947603ms","remote":"127.0.0.1:48770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T13:15:15.058279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.508428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T13:15:15.058561Z","caller":"traceutil/trace.go:171","msg":"trace[1978614276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8018; }","duration":"222.804105ms","start":"2026-04-08T13:15:14.835745Z","end":"2026-04-08T13:15:15.058549Z","steps":["trace[1978614276] 'agreement among raft nodes before linearized reading' (duration: 222.459376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:15.058525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.141808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-08T13:15:15.05865Z","caller":"traceutil/trace.go:171","msg":"trace[446517686] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8018; }","duration":"194.309752ms","start":"2026-04-08T13:15:14.864323Z","end":"2026-04-08T13:15:15.058633Z","steps":["trace[446517686] 'agreement among raft nodes before linearized reading' (duration: 193.645534ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:28.313021Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021c1.wal"} {"level":"warn","ts":"2026-04-08T13:15:31.977626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.750428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"warn","ts":"2026-04-08T13:15:31.977721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.325793ms","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-08T13:15:31.977796Z","caller":"traceutil/trace.go:171","msg":"trace[382295726] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8299; }","duration":"154.985345ms","start":"2026-04-08T13:15:31.822784Z","end":"2026-04-08T13:15:31.977769Z","steps":["trace[382295726] 'range keys from in-memory index tree' (duration: 154.406858ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:31.977833Z","caller":"traceutil/trace.go:171","msg":"trace[1928934044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8299; }","duration":"110.493257ms","start":"2026-04-08T13:15:31.867301Z","end":"2026-04-08T13:15:31.977794Z","steps":["trace[1928934044] 'range keys from in-memory index tree' (duration: 110.096386ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:43.349972Z","caller":"traceutil/trace.go:171","msg":"trace[1233037342] linearizableReadLoop","detail":"{readStateIndex:8746; appliedIndex:8745; }","duration":"133.702928ms","start":"2026-04-08T13:15:43.216234Z","end":"2026-04-08T13:15:43.349937Z","steps":["trace[1233037342] 'read index received' (duration: 133.599346ms)","trace[1233037342] 'applied index is now lower than readState.Index' (duration: 102.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:15:43.350137Z","caller":"traceutil/trace.go:171","msg":"trace[1814799097] transaction","detail":"{read_only:false; response_revision:8373; number_of_response:1; }","duration":"136.274198ms","start":"2026-04-08T13:15:43.213825Z","end":"2026-04-08T13:15:43.350099Z","steps":["trace[1814799097] 'process raft request' (duration: 135.918398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:43.350318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.062258ms","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-08T13:15:43.350418Z","caller":"traceutil/trace.go:171","msg":"trace[1541043813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8373; }","duration":"134.261133ms","start":"2026-04-08T13:15:43.216135Z","end":"2026-04-08T13:15:43.350396Z","steps":["trace[1541043813] 'agreement among raft nodes before linearized reading' (duration: 133.971405ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:43.358962Z","caller":"traceutil/trace.go:171","msg":"trace[700641948] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"116.614795ms","start":"2026-04-08T13:15:43.242328Z","end":"2026-04-08T13:15:43.358943Z","steps":["trace[700641948] 'process raft request' (duration: 116.458211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:44.404963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.628994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:15:44.405214Z","caller":"traceutil/trace.go:171","msg":"trace[596048881] linearizableReadLoop","detail":"{readStateIndex:8757; appliedIndex:8754; }","duration":"346.884682ms","start":"2026-04-08T13:15:44.058305Z","end":"2026-04-08T13:15:44.40519Z","steps":["trace[596048881] 'read index received' (duration: 28.056711ms)","trace[596048881] 'applied index is now lower than readState.Index' (duration: 318.826731ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:15:44.405361Z","caller":"traceutil/trace.go:171","msg":"trace[1238335930] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"359.633379ms","start":"2026-04-08T13:15:44.045691Z","end":"2026-04-08T13:15:44.405324Z","steps":["trace[1238335930] 'process raft request' (duration: 256.517491ms)","trace[1238335930] 'compare' (duration: 102.175762ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:15:44.405367Z","caller":"traceutil/trace.go:171","msg":"trace[339356733] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"358.376444ms","start":"2026-04-08T13:15:44.046935Z","end":"2026-04-08T13:15:44.405311Z","steps":["trace[339356733] 'process raft request' (duration: 358.164829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:44.405474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.17262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5664c\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-04-08T13:15:44.40548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:44.045683Z","time spent":"359.751092ms","remote":"127.0.0.1:49032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:15:44.405534Z","caller":"traceutil/trace.go:171","msg":"trace[125003491] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5664c; range_end:; response_count:1; response_revision:8384; }","duration":"347.338915ms","start":"2026-04-08T13:15:44.058178Z","end":"2026-04-08T13:15:44.405517Z","steps":["trace[125003491] 'agreement among raft nodes before linearized reading' (duration: 347.090538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:44.40558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:44.05816Z","time spent":"347.408836ms","remote":"127.0.0.1:48928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-5664c\" "} {"level":"warn","ts":"2026-04-08T13:15:44.405638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:44.046906Z","time spent":"358.5872ms","remote":"127.0.0.1:49032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T13:15:45.228531Z","caller":"traceutil/trace.go:171","msg":"trace[1989373384] transaction","detail":"{read_only:false; response_revision:8385; number_of_response:1; }","duration":"138.677553ms","start":"2026-04-08T13:15:45.089816Z","end":"2026-04-08T13:15:45.228494Z","steps":["trace[1989373384] 'process raft request' (duration: 138.481708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:45.534613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.059761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T13:15:45.535199Z","caller":"traceutil/trace.go:171","msg":"trace[1927640107] linearizableReadLoop","detail":"{readStateIndex:8759; appliedIndex:8758; }","duration":"180.263511ms","start":"2026-04-08T13:15:45.354911Z","end":"2026-04-08T13:15:45.535174Z","steps":["trace[1927640107] 'read index received' (duration: 39.437469ms)","trace[1927640107] 'applied index is now lower than readState.Index' (duration: 140.823792ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:15:45.53524Z","caller":"traceutil/trace.go:171","msg":"trace[431918] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"360.223885ms","start":"2026-04-08T13:15:45.174956Z","end":"2026-04-08T13:15:45.53518Z","steps":["trace[431918] 'process raft request' (duration: 219.458225ms)","trace[431918] 'compare' (duration: 139.895226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:45.535452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:45.174926Z","time spent":"360.408209ms","remote":"127.0.0.1:49032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:15:45.535529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.848449ms","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-08T13:15:45.535635Z","caller":"traceutil/trace.go:171","msg":"trace[2068824035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8386; }","duration":"156.014854ms","start":"2026-04-08T13:15:45.379596Z","end":"2026-04-08T13:15:45.535611Z","steps":["trace[2068824035] 'agreement among raft nodes before linearized reading' (duration: 155.731996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:45.535536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.606581ms","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-08T13:15:45.535787Z","caller":"traceutil/trace.go:171","msg":"trace[1831125909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8386; }","duration":"180.928189ms","start":"2026-04-08T13:15:45.354849Z","end":"2026-04-08T13:15:45.535777Z","steps":["trace[1831125909] 'agreement among raft nodes before linearized reading' (duration: 180.437616ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:12.498277Z","caller":"traceutil/trace.go:171","msg":"trace[176061973] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"143.287408ms","start":"2026-04-08T13:16:12.354952Z","end":"2026-04-08T13:16:12.498239Z","steps":["trace[176061973] 'process raft request' (duration: 142.536487ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:26.709988Z","caller":"traceutil/trace.go:171","msg":"trace[1788238115] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"137.332196ms","start":"2026-04-08T13:16:26.572626Z","end":"2026-04-08T13:16:26.709958Z","steps":["trace[1788238115] 'process raft request' (duration: 136.958606ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:26.813379Z","caller":"traceutil/trace.go:171","msg":"trace[1665559641] linearizableReadLoop","detail":"{readStateIndex:9160; appliedIndex:9158; }","duration":"103.56388ms","start":"2026-04-08T13:16:26.709789Z","end":"2026-04-08T13:16:26.813353Z","steps":["trace[1665559641] 'read index received' (duration: 103.408816ms)","trace[1665559641] 'applied index is now lower than readState.Index' (duration: 153.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:16:26.813566Z","caller":"traceutil/trace.go:171","msg":"trace[2046166248] transaction","detail":"{read_only:false; response_revision:8764; number_of_response:1; }","duration":"116.294606ms","start":"2026-04-08T13:16:26.697211Z","end":"2026-04-08T13:16:26.813505Z","steps":["trace[2046166248] 'process raft request' (duration: 116.06815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:26.813629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.656248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5664c\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-08T13:16:26.813482Z","caller":"traceutil/trace.go:171","msg":"trace[802013853] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"123.201953ms","start":"2026-04-08T13:16:26.690233Z","end":"2026-04-08T13:16:26.813435Z","steps":["trace[802013853] 'process raft request' (duration: 122.877384ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:26.813796Z","caller":"traceutil/trace.go:171","msg":"trace[1484190966] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5664c; range_end:; response_count:1; response_revision:8764; }","duration":"143.883205ms","start":"2026-04-08T13:16:26.669898Z","end":"2026-04-08T13:16:26.813781Z","steps":["trace[1484190966] 'agreement among raft nodes before linearized reading' (duration: 143.570726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:16:26.813762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.411466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-j6d2k\" ","response":"range_response_count:1 size:4614"} {"level":"info","ts":"2026-04-08T13:16:26.813895Z","caller":"traceutil/trace.go:171","msg":"trace[1552938424] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-j6d2k; range_end:; response_count:1; response_revision:8764; }","duration":"124.631172ms","start":"2026-04-08T13:16:26.689242Z","end":"2026-04-08T13:16:26.813873Z","steps":["trace[1552938424] 'agreement among raft nodes before linearized reading' (duration: 124.279572ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:26.881585Z","caller":"traceutil/trace.go:171","msg":"trace[1321965109] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"167.453274ms","start":"2026-04-08T13:16:26.714091Z","end":"2026-04-08T13:16:26.881544Z","steps":["trace[1321965109] 'process raft request' (duration: 167.244399ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:26.881682Z","caller":"traceutil/trace.go:171","msg":"trace[1805948041] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"167.433074ms","start":"2026-04-08T13:16:26.7142Z","end":"2026-04-08T13:16:26.881633Z","steps":["trace[1805948041] 'process raft request' (duration: 167.275999ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:17:22.721229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6517} {"level":"info","ts":"2026-04-08T13:17:22.822848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6517,"took":"99.530901ms","hash":3760070540,"current-db-size-bytes":60280832,"current-db-size":"60 MB","current-db-size-in-use-bytes":26419200,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-08T13:17:22.822941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3760070540,"revision":6517,"compact-revision":3213} {"level":"info","ts":"2026-04-08T13:17:42.152441Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"42c325ad8bcf8012","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T13:17:42.157537Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T13:17:42.157659Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T13:19:45.241254Z","caller":"traceutil/trace.go:171","msg":"trace[328177502] linearizableReadLoop","detail":"{readStateIndex:11145; appliedIndex:11144; }","duration":"104.309848ms","start":"2026-04-08T13:19:45.13691Z","end":"2026-04-08T13:19:45.24122Z","steps":["trace[328177502] 'read index received' (duration: 104.01509ms)","trace[328177502] 'applied index is now lower than readState.Index' (duration: 293.708µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:19:45.241324Z","caller":"traceutil/trace.go:171","msg":"trace[1935792654] transaction","detail":"{read_only:false; response_revision:10659; number_of_response:1; }","duration":"134.118566ms","start":"2026-04-08T13:19:45.107177Z","end":"2026-04-08T13:19:45.241296Z","steps":["trace[1935792654] 'process raft request' (duration: 133.831878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:19:45.241602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.665218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-08T13:19:45.241721Z","caller":"traceutil/trace.go:171","msg":"trace[432422332] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:10659; }","duration":"104.851583ms","start":"2026-04-08T13:19:45.136841Z","end":"2026-04-08T13:19:45.241693Z","steps":["trace[432422332] 'agreement among raft nodes before linearized reading' (duration: 104.497783ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:22:22.729626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9409} {"level":"info","ts":"2026-04-08T13:22:22.870388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9409,"took":"138.029454ms","hash":4102543586,"current-db-size-bytes":60280832,"current-db-size":"60 MB","current-db-size-in-use-bytes":25604096,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-08T13:22:22.870497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4102543586,"revision":9409,"compact-revision":6517}