{"level":"warn","ts":"2026-06-04T11:59:06.626213Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:06.626308Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-06-04T11:59:06.626373Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:06.626382Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-06-04T11:59:06.626399Z","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-06-04T11:59:06.627Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-06-04T11:59:06.627078Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-06-04T11:59:06.629243Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.969438ms"} {"level":"info","ts":"2026-06-04T11:59:06.634845Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-06-04T11:59:06.634923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-06-04T11:59:06.634945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-06-04T11:59:06.634958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-04T11:59:06.634974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-06-04T11:59:06.635012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-06-04T11:59:06.64411Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-04T11:59:06.646284Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-04T11:59:06.64756Z","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-06-04T11:59:06.650502Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-04T11:59:06.650626Z","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-06-04T11:59:06.650702Z","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-06-04T11:59:06.650718Z","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-06-04T11:59:06.650782Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-04T11:59:06.651805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-06-04T11:59:06.65191Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-06-04T11:59:06.654046Z","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-06-04T11:59:06.654139Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-06-04T11:59:06.654162Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-06-04T11:59:06.654336Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-04T11:59:06.654383Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-04T11:59:07.135853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-06-04T11:59:07.135921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-06-04T11:59:07.135966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-06-04T11:59:07.13599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-06-04T11:59:07.136006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-06-04T11:59:07.136026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-06-04T11:59:07.136054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-06-04T11:59:07.137331Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-06-04T11:59:07.137334Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:07.137338Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:07.137418Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:07.137685Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-04T11:59:07.13778Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-04T11:59:07.141381Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:07.141788Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:07.14192Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:07.144875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-06-04T11:59:07.145357Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-04T11:59:45.403763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.012079ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-04T11:59:45.404498Z","caller":"traceutil/trace.go:171","msg":"trace[101286174] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"103.92687ms","start":"2026-06-04T11:59:45.300531Z","end":"2026-06-04T11:59:45.404458Z","steps":["trace[101286174] 'process raft request' (duration: 103.763286ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:51.488886Z","caller":"traceutil/trace.go:171","msg":"trace[1635965515] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"137.845355ms","start":"2026-06-04T11:59:51.351008Z","end":"2026-06-04T11:59:51.488853Z","steps":["trace[1635965515] 'process raft request' (duration: 137.66486ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:52.202065Z","caller":"traceutil/trace.go:171","msg":"trace[827213707] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"337.862019ms","start":"2026-06-04T11:59:51.864174Z","end":"2026-06-04T11:59:52.202036Z","steps":["trace[827213707] 'process raft request' (duration: 337.688105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:59:52.202843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:59:51.864132Z","time spent":"338.023534ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T11:59:52.268589Z","caller":"traceutil/trace.go:171","msg":"trace[2142884735] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"383.581286ms","start":"2026-06-04T11:59:51.884973Z","end":"2026-06-04T11:59:52.268554Z","steps":["trace[2142884735] 'process raft request' (duration: 376.737721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:59:52.268783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:59:51.884939Z","time spent":"383.7439ms","remote":"127.0.0.1:58518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:00:09.684512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.130615ms","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-06-04T12:00:09.68467Z","caller":"traceutil/trace.go:171","msg":"trace[1095856035] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:766; }","duration":"117.33408ms","start":"2026-06-04T12:00:09.567314Z","end":"2026-06-04T12:00:09.684648Z","steps":["trace[1095856035] 'range keys from in-memory index tree' (duration: 117.118145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:01:28.177685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.418601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:01:28.17781Z","caller":"traceutil/trace.go:171","msg":"trace[255426053] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1916; }","duration":"189.268597ms","start":"2026-06-04T12:01:27.988516Z","end":"2026-06-04T12:01:28.177785Z","steps":["trace[255426053] 'read index received' (duration: 49.241µs)","trace[255426053] 'applied index is now lower than readState.Index' (duration: 189.217946ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:01:28.177903Z","caller":"traceutil/trace.go:171","msg":"trace[602950101] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"364.644387ms","start":"2026-06-04T12:01:27.813246Z","end":"2026-06-04T12:01:28.17789Z","steps":["trace[602950101] 'process raft request' (duration: 123.905909ms)","trace[602950101] 'compare' (duration: 240.044331ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:01:28.177987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:01:27.813227Z","time spent":"364.72444ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:01:28.178233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.042874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a1650f03-c886-42c9-a3e1-664178fc137e\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-06-04T12:01:28.178332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.800019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T12:01:28.178388Z","caller":"traceutil/trace.go:171","msg":"trace[517829935] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a1650f03-c886-42c9-a3e1-664178fc137e; range_end:; response_count:1; response_revision:1846; }","duration":"168.253149ms","start":"2026-06-04T12:01:28.0101Z","end":"2026-06-04T12:01:28.178354Z","steps":["trace[517829935] 'agreement among raft nodes before linearized reading' (duration: 167.834259ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:28.178438Z","caller":"traceutil/trace.go:171","msg":"trace[1036229098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1846; }","duration":"189.970204ms","start":"2026-06-04T12:01:27.98844Z","end":"2026-06-04T12:01:28.17841Z","steps":["trace[1036229098] 'agreement among raft nodes before linearized reading' (duration: 189.684047ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:28.478138Z","caller":"traceutil/trace.go:171","msg":"trace[581404168] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"114.763918ms","start":"2026-06-04T12:01:28.363341Z","end":"2026-06-04T12:01:28.478105Z","steps":["trace[581404168] 'process raft request' (duration: 114.536683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:18.213908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.138369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:02:18.214047Z","caller":"traceutil/trace.go:171","msg":"trace[1139214648] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2168; }","duration":"262.302003ms","start":"2026-06-04T12:02:17.951716Z","end":"2026-06-04T12:02:18.214018Z","steps":["trace[1139214648] 'count revisions from in-memory index tree' (duration: 261.949064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:23.779989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.010965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-04T12:02:23.78005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.452311ms","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-06-04T12:02:23.780065Z","caller":"traceutil/trace.go:171","msg":"trace[1562711772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2194; }","duration":"294.137848ms","start":"2026-06-04T12:02:23.485911Z","end":"2026-06-04T12:02:23.780049Z","steps":["trace[1562711772] 'range keys from in-memory index tree' (duration: 293.868962ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:23.780078Z","caller":"traceutil/trace.go:171","msg":"trace[270450712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2194; }","duration":"283.513922ms","start":"2026-06-04T12:02:23.496555Z","end":"2026-06-04T12:02:23.780069Z","steps":["trace[270450712] 'range keys from in-memory index tree' (duration: 283.363209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:23.780175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.831146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:02:23.780254Z","caller":"traceutil/trace.go:171","msg":"trace[345490915] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"200.865194ms","start":"2026-06-04T12:02:23.579376Z","end":"2026-06-04T12:02:23.780241Z","steps":["trace[345490915] 'read index received' (duration: 62.941775ms)","trace[345490915] 'applied index is now lower than readState.Index' (duration: 137.922408ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:23.78028Z","caller":"traceutil/trace.go:171","msg":"trace[1582850419] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"230.910246ms","start":"2026-06-04T12:02:23.549358Z","end":"2026-06-04T12:02:23.780268Z","steps":["trace[1582850419] 'process raft request' (duration: 92.924156ms)","trace[1582850419] 'compare' (duration: 137.519289ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:02:23.780459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.058918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-04T12:02:23.780562Z","caller":"traceutil/trace.go:171","msg":"trace[420834600] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2195; }","duration":"201.194572ms","start":"2026-06-04T12:02:23.579343Z","end":"2026-06-04T12:02:23.780537Z","steps":["trace[420834600] 'agreement among raft nodes before linearized reading' (duration: 200.958136ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:24.276749Z","caller":"traceutil/trace.go:171","msg":"trace[1839718600] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"492.578024ms","start":"2026-06-04T12:02:23.78415Z","end":"2026-06-04T12:02:24.276728Z","steps":["trace[1839718600] 'process raft request' (duration: 475.978024ms)","trace[1839718600] 'compare' (duration: 16.503537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:02:24.276869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:23.784131Z","time spent":"492.674555ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:02:24.531538Z","caller":"traceutil/trace.go:171","msg":"trace[87340364] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"746.675528ms","start":"2026-06-04T12:02:23.784833Z","end":"2026-06-04T12:02:24.531508Z","steps":["trace[87340364] 'process raft request' (duration: 693.207331ms)","trace[87340364] 'compare' (duration: 53.246031ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:02:24.53172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:23.784819Z","time spent":"746.80381ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:02:24.531718Z","caller":"traceutil/trace.go:171","msg":"trace[683045605] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2279; }","duration":"679.416209ms","start":"2026-06-04T12:02:23.852268Z","end":"2026-06-04T12:02:24.531684Z","steps":["trace[683045605] 'read index received' (duration: 407.875954ms)","trace[683045605] 'applied index is now lower than readState.Index' (duration: 271.534575ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:24.531764Z","caller":"traceutil/trace.go:171","msg":"trace[1299617788] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"746.511973ms","start":"2026-06-04T12:02:23.78522Z","end":"2026-06-04T12:02:24.531732Z","steps":["trace[1299617788] 'process raft request' (duration: 746.223276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.531901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.68854ms","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-06-04T12:02:24.531974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:23.785205Z","time spent":"746.669807ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:02:24.531946Z","caller":"traceutil/trace.go:171","msg":"trace[1602760480] 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:2198; }","duration":"424.760911ms","start":"2026-06-04T12:02:24.10717Z","end":"2026-06-04T12:02:24.531931Z","steps":["trace[1602760480] 'agreement among raft nodes before linearized reading' (duration: 424.677429ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.532066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:24.107152Z","time spent":"424.900005ms","remote":"127.0.0.1:47396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"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-06-04T12:02:24.532061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.707935ms","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-06-04T12:02:24.532167Z","caller":"traceutil/trace.go:171","msg":"trace[2039941067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2198; }","duration":"679.938381ms","start":"2026-06-04T12:02:23.85221Z","end":"2026-06-04T12:02:24.532149Z","steps":["trace[2039941067] 'agreement among raft nodes before linearized reading' (duration: 679.619113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.532214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:23.852194Z","time spent":"680.005133ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:02:24.532298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.96909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b5df163c3edd94\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-04T12:02:24.532367Z","caller":"traceutil/trace.go:171","msg":"trace[1590257445] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b5df163c3edd94; range_end:; response_count:1; response_revision:2198; }","duration":"170.038422ms","start":"2026-06-04T12:02:24.362309Z","end":"2026-06-04T12:02:24.532347Z","steps":["trace[1590257445] 'agreement among raft nodes before linearized reading' (duration: 169.867878ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:03:44.845375Z","caller":"traceutil/trace.go:171","msg":"trace[1435615083] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"102.436496ms","start":"2026-06-04T12:03:44.742908Z","end":"2026-06-04T12:03:44.845345Z","steps":["trace[1435615083] 'process raft request' (duration: 102.222059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.360333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.321252ms","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-06-04T12:03:45.360445Z","caller":"traceutil/trace.go:171","msg":"trace[1036935189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2826; }","duration":"427.482986ms","start":"2026-06-04T12:03:44.932934Z","end":"2026-06-04T12:03:45.360416Z","steps":["trace[1036935189] 'range keys from in-memory index tree' (duration: 427.110107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.360503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:44.932911Z","time spent":"427.578158ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-04T12:03:45.360335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.253408ms","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-06-04T12:03:45.360628Z","caller":"traceutil/trace.go:171","msg":"trace[140055081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2826; }","duration":"429.596906ms","start":"2026-06-04T12:03:44.931001Z","end":"2026-06-04T12:03:45.360598Z","steps":["trace[140055081] 'range keys from in-memory index tree' (duration: 429.026663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.36064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.14485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-06-04T12:03:45.36072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:44.930977Z","time spent":"429.71277ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-04T12:03:45.360879Z","caller":"traceutil/trace.go:171","msg":"trace[1378015164] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"228.992779ms","start":"2026-06-04T12:03:45.131871Z","end":"2026-06-04T12:03:45.360864Z","steps":["trace[1378015164] 'process raft request' (duration: 228.913427ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:03:45.360866Z","caller":"traceutil/trace.go:171","msg":"trace[1907640300] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"354.728813ms","start":"2026-06-04T12:03:45.006094Z","end":"2026-06-04T12:03:45.360823Z","steps":["trace[1907640300] 'process raft request' (duration: 22.318405ms)","trace[1907640300] 'compare' (duration: 331.69227ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:03:45.360894Z","caller":"traceutil/trace.go:171","msg":"trace[236518746] linearizableReadLoop","detail":"{readStateIndex:2935; appliedIndex:2934; }","duration":"354.17963ms","start":"2026-06-04T12:03:45.006696Z","end":"2026-06-04T12:03:45.360875Z","steps":["trace[236518746] 'read index received' (duration: 21.657749ms)","trace[236518746] 'applied index is now lower than readState.Index' (duration: 332.520281ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:03:45.361045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:45.006065Z","time spent":"354.883677ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:03:45.361073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.376124ms","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-06-04T12:03:45.361125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.759579ms","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-06-04T12:03:45.36113Z","caller":"traceutil/trace.go:171","msg":"trace[782099160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2828; }","duration":"354.479526ms","start":"2026-06-04T12:03:45.006634Z","end":"2026-06-04T12:03:45.361113Z","steps":["trace[782099160] 'agreement among raft nodes before linearized reading' (duration: 354.343933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.361142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.539801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:03:45.361162Z","caller":"traceutil/trace.go:171","msg":"trace[722577105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2828; }","duration":"353.821781ms","start":"2026-06-04T12:03:45.007327Z","end":"2026-06-04T12:03:45.361149Z","steps":["trace[722577105] 'agreement among raft nodes before linearized reading' (duration: 353.670908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.361171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:45.006598Z","time spent":"354.561769ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-04T12:03:45.361196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:45.007315Z","time spent":"353.873032ms","remote":"127.0.0.1:58676","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-06-04T12:03:45.36119Z","caller":"traceutil/trace.go:171","msg":"trace[840379583] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2828; }","duration":"123.622313ms","start":"2026-06-04T12:03:45.237554Z","end":"2026-06-04T12:03:45.361177Z","steps":["trace[840379583] 'agreement among raft nodes before linearized reading' (duration: 123.49444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.361357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.25638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:03:45.361415Z","caller":"traceutil/trace.go:171","msg":"trace[1476066393] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2828; }","duration":"210.369132ms","start":"2026-06-04T12:03:45.15103Z","end":"2026-06-04T12:03:45.3614Z","steps":["trace[1476066393] 'agreement among raft nodes before linearized reading' (duration: 210.142617ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:29.185232Z","caller":"traceutil/trace.go:171","msg":"trace[1385494105] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"117.624032ms","start":"2026-06-04T12:07:29.067592Z","end":"2026-06-04T12:07:29.185216Z","steps":["trace[1385494105] 'process raft request' (duration: 117.52055ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:29.185413Z","caller":"traceutil/trace.go:171","msg":"trace[1560277306] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4878; }","duration":"117.292503ms","start":"2026-06-04T12:07:29.068103Z","end":"2026-06-04T12:07:29.185395Z","steps":["trace[1560277306] 'read index received' (duration: 117.285543ms)","trace[1560277306] 'applied index is now lower than readState.Index' (duration: 4.95µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:07:29.185574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.453418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0.18b5df642dc288b7\" ","response":"range_response_count:1 size:1203"} {"level":"info","ts":"2026-06-04T12:07:29.185619Z","caller":"traceutil/trace.go:171","msg":"trace[1097027900] range","detail":"{range_begin:/registry/events/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0.18b5df642dc288b7; range_end:; response_count:1; response_revision:4700; }","duration":"117.528639ms","start":"2026-06-04T12:07:29.068077Z","end":"2026-06-04T12:07:29.185605Z","steps":["trace[1097027900] 'agreement among raft nodes before linearized reading' (duration: 117.403346ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:29.1906Z","caller":"traceutil/trace.go:171","msg":"trace[1789371909] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"109.702101ms","start":"2026-06-04T12:07:29.080885Z","end":"2026-06-04T12:07:29.190587Z","steps":["trace[1789371909] 'process raft request' (duration: 109.584859ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:29.190664Z","caller":"traceutil/trace.go:171","msg":"trace[1414965013] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"119.628988ms","start":"2026-06-04T12:07:29.070997Z","end":"2026-06-04T12:07:29.190626Z","steps":["trace[1414965013] 'process raft request' (duration: 119.366802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:29.19059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.000934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:07:29.190815Z","caller":"traceutil/trace.go:171","msg":"trace[1006032094] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:4702; }","duration":"119.25879ms","start":"2026-06-04T12:07:29.071548Z","end":"2026-06-04T12:07:29.190807Z","steps":["trace[1006032094] 'agreement among raft nodes before linearized reading' (duration: 118.974643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:29.191206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.318461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-06-04T12:07:29.191237Z","caller":"traceutil/trace.go:171","msg":"trace[57150322] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4702; }","duration":"116.365121ms","start":"2026-06-04T12:07:29.074863Z","end":"2026-06-04T12:07:29.191228Z","steps":["trace[57150322] 'agreement among raft nodes before linearized reading' (duration: 115.88883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:51.060299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.251053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:07:51.060466Z","caller":"traceutil/trace.go:171","msg":"trace[1053070455] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"303.35781ms","start":"2026-06-04T12:07:50.757077Z","end":"2026-06-04T12:07:51.060435Z","steps":["trace[1053070455] 'process raft request' (duration: 195.864901ms)","trace[1053070455] 'compare' (duration: 107.082129ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:07:51.060552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:07:50.75705Z","time spent":"303.466493ms","remote":"127.0.0.1:58488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:07:51.184177Z","caller":"traceutil/trace.go:171","msg":"trace[376022732] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"425.394987ms","start":"2026-06-04T12:07:50.758754Z","end":"2026-06-04T12:07:51.184149Z","steps":["trace[376022732] 'process raft request' (duration: 425.310245ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:51.1842Z","caller":"traceutil/trace.go:171","msg":"trace[1000464643] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"425.781337ms","start":"2026-06-04T12:07:50.758382Z","end":"2026-06-04T12:07:51.184163Z","steps":["trace[1000464643] 'process raft request' (duration: 425.552882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:51.184349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:07:50.758738Z","time spent":"425.519281ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:07:51.18438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:07:50.75836Z","time spent":"425.922611ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:07:51.184442Z","caller":"traceutil/trace.go:171","msg":"trace[523069381] linearizableReadLoop","detail":"{readStateIndex:5448; appliedIndex:5444; }","duration":"376.595191ms","start":"2026-06-04T12:07:50.807825Z","end":"2026-06-04T12:07:51.184421Z","steps":["trace[523069381] 'read index received' (duration: 145.128129ms)","trace[523069381] 'applied index is now lower than readState.Index' (duration: 231.465882ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:07:51.184513Z","caller":"traceutil/trace.go:171","msg":"trace[357301117] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"423.698898ms","start":"2026-06-04T12:07:50.760799Z","end":"2026-06-04T12:07:51.184497Z","steps":["trace[357301117] 'process raft request' (duration: 423.311158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:51.184588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:07:50.760784Z","time spent":"423.77059ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:07:51.184691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.826368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:07:51.184751Z","caller":"traceutil/trace.go:171","msg":"trace[94778300] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:5253; }","duration":"376.962061ms","start":"2026-06-04T12:07:50.807772Z","end":"2026-06-04T12:07:51.184734Z","steps":["trace[94778300] 'agreement among raft nodes before linearized reading' (duration: 376.801877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:51.184797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:07:50.807747Z","time spent":"377.035862ms","remote":"127.0.0.1:36922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:07:51.184855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.166199ms","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-06-04T12:07:51.184872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.730765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cc2zk\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-06-04T12:07:51.184919Z","caller":"traceutil/trace.go:171","msg":"trace[404242101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5253; }","duration":"265.284712ms","start":"2026-06-04T12:07:50.919616Z","end":"2026-06-04T12:07:51.184901Z","steps":["trace[404242101] 'agreement among raft nodes before linearized reading' (duration: 265.072037ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:51.184955Z","caller":"traceutil/trace.go:171","msg":"trace[2082074653] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cc2zk; range_end:; response_count:1; response_revision:5253; }","duration":"199.870199ms","start":"2026-06-04T12:07:50.985064Z","end":"2026-06-04T12:07:51.184935Z","steps":["trace[2082074653] 'agreement among raft nodes before linearized reading' (duration: 199.628293ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:51.184622Z","caller":"traceutil/trace.go:171","msg":"trace[2066619369] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"316.577906ms","start":"2026-06-04T12:07:50.868015Z","end":"2026-06-04T12:07:51.184593Z","steps":["trace[2066619369] 'process raft request' (duration: 316.31982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:51.185552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:07:50.867994Z","time spent":"317.494889ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:07:58.427685Z","caller":"traceutil/trace.go:171","msg":"trace[458481134] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"168.827935ms","start":"2026-06-04T12:07:58.25883Z","end":"2026-06-04T12:07:58.427658Z","steps":["trace[458481134] 'process raft request' (duration: 168.652091ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:58.472278Z","caller":"traceutil/trace.go:171","msg":"trace[370190717] linearizableReadLoop","detail":"{readStateIndex:5627; appliedIndex:5625; }","duration":"108.527384ms","start":"2026-06-04T12:07:58.363726Z","end":"2026-06-04T12:07:58.472253Z","steps":["trace[370190717] 'read index received' (duration: 63.753094ms)","trace[370190717] 'applied index is now lower than readState.Index' (duration: 44.77341ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:07:58.472468Z","caller":"traceutil/trace.go:171","msg":"trace[524702838] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"132.589019ms","start":"2026-06-04T12:07:58.339848Z","end":"2026-06-04T12:07:58.472437Z","steps":["trace[524702838] 'process raft request' (duration: 132.261001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:58.472579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.833251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b8213fad-3220-4086-a052-d2021789dbdb\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-04T12:07:58.47264Z","caller":"traceutil/trace.go:171","msg":"trace[2000684015] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b8213fad-3220-4086-a052-d2021789dbdb; range_end:; response_count:1; response_revision:5427; }","duration":"108.948854ms","start":"2026-06-04T12:07:58.363674Z","end":"2026-06-04T12:07:58.472623Z","steps":["trace[2000684015] 'agreement among raft nodes before linearized reading' (duration: 108.708248ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:58.636715Z","caller":"traceutil/trace.go:171","msg":"trace[888304948] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"156.984713ms","start":"2026-06-04T12:07:58.479691Z","end":"2026-06-04T12:07:58.636676Z","steps":["trace[888304948] 'process raft request' (duration: 134.506375ms)","trace[888304948] 'compare' (duration: 22.240312ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:08:05.62446Z","caller":"traceutil/trace.go:171","msg":"trace[1897645325] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"101.460945ms","start":"2026-06-04T12:08:05.522965Z","end":"2026-06-04T12:08:05.624426Z","steps":["trace[1897645325] 'process raft request' (duration: 100.766339ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:09:07.17115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2948} {"level":"info","ts":"2026-06-04T12:09:07.238369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2948,"took":"65.614558ms","hash":4010582669,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":33964032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-04T12:09:07.238444Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4010582669,"revision":2948,"compact-revision":-1} {"level":"info","ts":"2026-06-04T12:14:05.337327Z","caller":"traceutil/trace.go:171","msg":"trace[494263158] transaction","detail":"{read_only:false; response_revision:8239; number_of_response:1; }","duration":"150.072204ms","start":"2026-06-04T12:14:05.187206Z","end":"2026-06-04T12:14:05.337278Z","steps":["trace[494263158] 'process raft request' (duration: 96.896044ms)","trace[494263158] 'compare' (duration: 52.981775ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:05.343206Z","caller":"traceutil/trace.go:171","msg":"trace[2113701326] transaction","detail":"{read_only:false; response_revision:8240; number_of_response:1; }","duration":"155.885432ms","start":"2026-06-04T12:14:05.187287Z","end":"2026-06-04T12:14:05.343172Z","steps":["trace[2113701326] 'process raft request' (duration: 155.743329ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:05.343295Z","caller":"traceutil/trace.go:171","msg":"trace[816427198] transaction","detail":"{read_only:false; response_revision:8241; number_of_response:1; }","duration":"155.79989ms","start":"2026-06-04T12:14:05.187456Z","end":"2026-06-04T12:14:05.343256Z","steps":["trace[816427198] 'process raft request' (duration: 155.655117ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:05.344335Z","caller":"traceutil/trace.go:171","msg":"trace[719932059] linearizableReadLoop","detail":"{readStateIndex:8530; appliedIndex:8527; }","duration":"136.009876ms","start":"2026-06-04T12:14:05.208313Z","end":"2026-06-04T12:14:05.344323Z","steps":["trace[719932059] 'read index received' (duration: 75.81628ms)","trace[719932059] 'applied index is now lower than readState.Index' (duration: 60.191676ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:14:05.344471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.148441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-06-04T12:14:05.344502Z","caller":"traceutil/trace.go:171","msg":"trace[1962467490] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8241; }","duration":"136.218842ms","start":"2026-06-04T12:14:05.208272Z","end":"2026-06-04T12:14:05.344491Z","steps":["trace[1962467490] 'agreement among raft nodes before linearized reading' (duration: 136.098198ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:07.180396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6034} {"level":"info","ts":"2026-06-04T12:14:07.272816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6034,"took":"89.574669ms","hash":3248018546,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":23392256,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-04T12:14:07.272896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3248018546,"revision":6034,"compact-revision":2948} {"level":"warn","ts":"2026-06-04T12:14:15.762764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.610399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:14:15.762939Z","caller":"traceutil/trace.go:171","msg":"trace[1252238218] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8297; }","duration":"244.709783ms","start":"2026-06-04T12:14:15.518119Z","end":"2026-06-04T12:14:15.762829Z","steps":["trace[1252238218] 'count revisions from in-memory index tree' (duration: 244.508407ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:16.711686Z","caller":"traceutil/trace.go:171","msg":"trace[313535874] linearizableReadLoop","detail":"{readStateIndex:8595; appliedIndex:8594; }","duration":"140.890104ms","start":"2026-06-04T12:14:16.57076Z","end":"2026-06-04T12:14:16.71165Z","steps":["trace[313535874] 'read index received' (duration: 140.475174ms)","trace[313535874] 'applied index is now lower than readState.Index' (duration: 413.35µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:16.711777Z","caller":"traceutil/trace.go:171","msg":"trace[1287692735] transaction","detail":"{read_only:false; response_revision:8301; number_of_response:1; }","duration":"201.46923ms","start":"2026-06-04T12:14:16.510241Z","end":"2026-06-04T12:14:16.71171Z","steps":["trace[1287692735] 'process raft request' (duration: 201.145963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:16.711992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.201291ms","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-06-04T12:14:16.712047Z","caller":"traceutil/trace.go:171","msg":"trace[1804342663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8301; }","duration":"141.335854ms","start":"2026-06-04T12:14:16.570698Z","end":"2026-06-04T12:14:16.712034Z","steps":["trace[1804342663] 'agreement among raft nodes before linearized reading' (duration: 141.093768ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:17.564169Z","caller":"traceutil/trace.go:171","msg":"trace[861472713] linearizableReadLoop","detail":"{readStateIndex:8601; appliedIndex:8598; }","duration":"126.315077ms","start":"2026-06-04T12:14:17.437825Z","end":"2026-06-04T12:14:17.56414Z","steps":["trace[861472713] 'read index received' (duration: 52.499604ms)","trace[861472713] 'applied index is now lower than readState.Index' (duration: 73.814183ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:17.564323Z","caller":"traceutil/trace.go:171","msg":"trace[1650423976] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"128.013616ms","start":"2026-06-04T12:14:17.436235Z","end":"2026-06-04T12:14:17.564249Z","steps":["trace[1650423976] 'process raft request' (duration: 96.930313ms)","trace[1650423976] 'compare' (duration: 30.765905ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:17.564483Z","caller":"traceutil/trace.go:171","msg":"trace[1991789633] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"127.352601ms","start":"2026-06-04T12:14:17.437102Z","end":"2026-06-04T12:14:17.564455Z","steps":["trace[1991789633] 'process raft request' (duration: 126.983942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:17.564704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.865789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223812"} {"level":"info","ts":"2026-06-04T12:14:17.564764Z","caller":"traceutil/trace.go:171","msg":"trace[747109884] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8307; }","duration":"126.972692ms","start":"2026-06-04T12:14:17.437778Z","end":"2026-06-04T12:14:17.564751Z","steps":["trace[747109884] 'agreement among raft nodes before linearized reading' (duration: 126.490201ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:17.966607Z","caller":"traceutil/trace.go:171","msg":"trace[1620424806] transaction","detail":"{read_only:false; response_revision:8308; number_of_response:1; }","duration":"192.497025ms","start":"2026-06-04T12:14:17.774068Z","end":"2026-06-04T12:14:17.966565Z","steps":["trace[1620424806] 'process raft request' (duration: 175.815837ms)","trace[1620424806] 'compare' (duration: 16.283579ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:14:45.552806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.33931ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-04T12:14:45.552947Z","caller":"traceutil/trace.go:171","msg":"trace[1243588275] linearizableReadLoop","detail":"{readStateIndex:8811; appliedIndex:8810; }","duration":"220.929704ms","start":"2026-06-04T12:14:45.331978Z","end":"2026-06-04T12:14:45.552908Z","steps":["trace[1243588275] 'read index received' (duration: 52.39923ms)","trace[1243588275] 'applied index is now lower than readState.Index' (duration: 168.526164ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:14:45.553312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.613094ms","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-06-04T12:14:45.553358Z","caller":"traceutil/trace.go:171","msg":"trace[499927974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8509; }","duration":"157.774168ms","start":"2026-06-04T12:14:45.395572Z","end":"2026-06-04T12:14:45.553346Z","steps":["trace[499927974] 'agreement among raft nodes before linearized reading' (duration: 157.506361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:45.553309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.313464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414394"} {"level":"info","ts":"2026-06-04T12:14:45.553449Z","caller":"traceutil/trace.go:171","msg":"trace[1347120604] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8509; }","duration":"221.505589ms","start":"2026-06-04T12:14:45.331924Z","end":"2026-06-04T12:14:45.55343Z","steps":["trace[1347120604] 'agreement among raft nodes before linearized reading' (duration: 221.113579ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:47.745717Z","caller":"traceutil/trace.go:171","msg":"trace[1970352392] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"106.83723ms","start":"2026-06-04T12:14:47.638853Z","end":"2026-06-04T12:14:47.745691Z","steps":["trace[1970352392] 'process raft request' (duration: 96.111265ms)","trace[1970352392] 'compare' (duration: 10.615883ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:57.825142Z","caller":"traceutil/trace.go:171","msg":"trace[1186081869] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"156.836814ms","start":"2026-06-04T12:14:57.668271Z","end":"2026-06-04T12:14:57.825108Z","steps":["trace[1186081869] 'process raft request' (duration: 145.301208ms)","trace[1186081869] 'compare' (duration: 11.403033ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:57.82533Z","caller":"traceutil/trace.go:171","msg":"trace[1579244075] linearizableReadLoop","detail":"{readStateIndex:8920; appliedIndex:8919; }","duration":"148.141516ms","start":"2026-06-04T12:14:57.677177Z","end":"2026-06-04T12:14:57.825319Z","steps":["trace[1579244075] 'read index received' (duration: 136.498138ms)","trace[1579244075] 'applied index is now lower than readState.Index' (duration: 11.641378ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:14:57.825532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.347321ms","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-06-04T12:14:57.825576Z","caller":"traceutil/trace.go:171","msg":"trace[992501016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8614; }","duration":"148.438934ms","start":"2026-06-04T12:14:57.677126Z","end":"2026-06-04T12:14:57.825565Z","steps":["trace[992501016] 'agreement among raft nodes before linearized reading' (duration: 148.273929ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:58.437687Z","caller":"traceutil/trace.go:171","msg":"trace[130616903] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"130.577188ms","start":"2026-06-04T12:14:58.307082Z","end":"2026-06-04T12:14:58.437659Z","steps":["trace[130616903] 'process raft request' (duration: 114.865292ms)","trace[130616903] 'compare' (duration: 15.48101ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:58.957492Z","caller":"traceutil/trace.go:171","msg":"trace[1950478761] transaction","detail":"{read_only:false; response_revision:8626; number_of_response:1; }","duration":"122.329251ms","start":"2026-06-04T12:14:58.835113Z","end":"2026-06-04T12:14:58.957442Z","steps":["trace[1950478761] 'process raft request' (duration: 32.27377ms)","trace[1950478761] 'compare' (duration: 89.808985ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:59.139101Z","caller":"traceutil/trace.go:171","msg":"trace[1656689944] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8934; }","duration":"103.930931ms","start":"2026-06-04T12:14:59.035144Z","end":"2026-06-04T12:14:59.139075Z","steps":["trace[1656689944] 'read index received' (duration: 103.774507ms)","trace[1656689944] 'applied index is now lower than readState.Index' (duration: 154.954µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:59.139302Z","caller":"traceutil/trace.go:171","msg":"trace[2057905084] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"126.033219ms","start":"2026-06-04T12:14:59.01312Z","end":"2026-06-04T12:14:59.139153Z","steps":["trace[2057905084] 'process raft request' (duration: 125.806124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:14:59.139564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.394273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-04T12:14:59.139612Z","caller":"traceutil/trace.go:171","msg":"trace[1153906890] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8628; }","duration":"104.495635ms","start":"2026-06-04T12:14:59.035104Z","end":"2026-06-04T12:14:59.139599Z","steps":["trace[1153906890] 'agreement among raft nodes before linearized reading' (duration: 104.120446ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.02187Z","caller":"traceutil/trace.go:171","msg":"trace[1835404471] linearizableReadLoop","detail":"{readStateIndex:8942; appliedIndex:8940; }","duration":"117.890305ms","start":"2026-06-04T12:14:59.90396Z","end":"2026-06-04T12:15:00.02185Z","steps":["trace[1835404471] 'read index received' (duration: 60.590107ms)","trace[1835404471] 'applied index is now lower than readState.Index' (duration: 57.299208ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:00.021933Z","caller":"traceutil/trace.go:171","msg":"trace[310042391] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"118.246154ms","start":"2026-06-04T12:14:59.903651Z","end":"2026-06-04T12:15:00.021897Z","steps":["trace[310042391] 'process raft request' (duration: 118.068679ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.02209Z","caller":"traceutil/trace.go:171","msg":"trace[1815239279] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"117.884244ms","start":"2026-06-04T12:14:59.904178Z","end":"2026-06-04T12:15:00.022062Z","steps":["trace[1815239279] 'process raft request' (duration: 117.629178ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:00.022099Z","caller":"traceutil/trace.go:171","msg":"trace[415993549] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"118.49288ms","start":"2026-06-04T12:14:59.90358Z","end":"2026-06-04T12:15:00.022073Z","steps":["trace[415993549] 'process raft request' (duration: 61.015207ms)","trace[415993549] 'compare' (duration: 56.888978ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:15:00.022303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.321615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228457"} {"level":"info","ts":"2026-06-04T12:15:00.022355Z","caller":"traceutil/trace.go:171","msg":"trace[1945617525] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8636; }","duration":"118.402728ms","start":"2026-06-04T12:14:59.903931Z","end":"2026-06-04T12:15:00.022334Z","steps":["trace[1945617525] 'agreement among raft nodes before linearized reading' (duration: 118.008659ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:15:22.001372Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ff.wal"} {"level":"info","ts":"2026-06-04T12:16:53.111015Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:16:53.117218Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-04T12:16:53.117356Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-04T12:19:07.186025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8251} {"level":"info","ts":"2026-06-04T12:19:07.264245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8251,"took":"75.977135ms","hash":3572199551,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":26525696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-04T12:19:07.264312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3572199551,"revision":8251,"compact-revision":6034} {"level":"info","ts":"2026-06-04T12:24:07.193406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10744} {"level":"info","ts":"2026-06-04T12:24:07.268773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10744,"took":"73.500963ms","hash":3360090171,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":28004352,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-04T12:24:07.26883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3360090171,"revision":10744,"compact-revision":8251} {"level":"warn","ts":"2026-06-04T12:25:58.79521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.702281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-04T12:25:58.795311Z","caller":"traceutil/trace.go:171","msg":"trace[1445192868] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:13675; }","duration":"168.855795ms","start":"2026-06-04T12:25:58.626434Z","end":"2026-06-04T12:25:58.79529Z","steps":["trace[1445192868] 'range keys from in-memory index tree' (duration: 168.490287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:16.617628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.865285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:26:16.617698Z","caller":"traceutil/trace.go:171","msg":"trace[270681851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13786; }","duration":"163.978838ms","start":"2026-06-04T12:26:16.453703Z","end":"2026-06-04T12:26:16.617682Z","steps":["trace[270681851] 'range keys from in-memory index tree' (duration: 163.756452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:16.618534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.911155ms","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-06-04T12:26:16.618643Z","caller":"traceutil/trace.go:171","msg":"trace[3474159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13786; }","duration":"206.158102ms","start":"2026-06-04T12:26:16.41246Z","end":"2026-06-04T12:26:16.618618Z","steps":["trace[3474159] 'range keys from in-memory index tree' (duration: 205.540256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:26.161727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.743031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:26:26.161804Z","caller":"traceutil/trace.go:171","msg":"trace[981938093] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:13954; }","duration":"262.867923ms","start":"2026-06-04T12:26:25.898919Z","end":"2026-06-04T12:26:26.161787Z","steps":["trace[981938093] 'count revisions from in-memory index tree' (duration: 262.660689ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:26:49.599308Z","caller":"traceutil/trace.go:171","msg":"trace[1873437163] transaction","detail":"{read_only:false; response_revision:14306; number_of_response:1; }","duration":"140.381753ms","start":"2026-06-04T12:26:49.458878Z","end":"2026-06-04T12:26:49.59926Z","steps":["trace[1873437163] 'process raft request' (duration: 90.586077ms)","trace[1873437163] 'compare' (duration: 49.659222ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:27:53.951462Z","caller":"traceutil/trace.go:171","msg":"trace[1290580285] linearizableReadLoop","detail":"{readStateIndex:15489; appliedIndex:15488; }","duration":"107.998233ms","start":"2026-06-04T12:27:53.843434Z","end":"2026-06-04T12:27:53.951433Z","steps":["trace[1290580285] 'read index received' (duration: 80.933043ms)","trace[1290580285] 'applied index is now lower than readState.Index' (duration: 27.0642ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:27:53.9516Z","caller":"traceutil/trace.go:171","msg":"trace[664221418] transaction","detail":"{read_only:false; response_revision:14978; number_of_response:1; }","duration":"130.701288ms","start":"2026-06-04T12:27:53.820884Z","end":"2026-06-04T12:27:53.951586Z","steps":["trace[664221418] 'process raft request' (duration: 103.475714ms)","trace[664221418] 'compare' (duration: 26.866215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:27:53.951949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.512775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:27:53.952022Z","caller":"traceutil/trace.go:171","msg":"trace[118447333] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:14978; }","duration":"108.635248ms","start":"2026-06-04T12:27:53.843369Z","end":"2026-06-04T12:27:53.952005Z","steps":["trace[118447333] 'agreement among raft nodes before linearized reading' (duration: 108.523535ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:07.198958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12930} {"level":"info","ts":"2026-06-04T12:29:07.272358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12930,"took":"71.122696ms","hash":1810599180,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":31289344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-04T12:29:07.272421Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1810599180,"revision":12930,"compact-revision":10744} {"level":"info","ts":"2026-06-04T12:32:42.816862Z","caller":"traceutil/trace.go:171","msg":"trace[629869571] transaction","detail":"{read_only:false; response_revision:17107; number_of_response:1; }","duration":"372.960946ms","start":"2026-06-04T12:32:42.443874Z","end":"2026-06-04T12:32:42.816835Z","steps":["trace[629869571] 'process raft request' (duration: 372.815873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:42.817002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:32:42.443851Z","time spent":"373.084509ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:32:42.817102Z","caller":"traceutil/trace.go:171","msg":"trace[1869985899] linearizableReadLoop","detail":"{readStateIndex:17688; appliedIndex:17686; }","duration":"197.753874ms","start":"2026-06-04T12:32:42.619325Z","end":"2026-06-04T12:32:42.817079Z","steps":["trace[1869985899] 'read index received' (duration: 197.299043ms)","trace[1869985899] 'applied index is now lower than readState.Index' (duration: 454.021µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:32:42.817343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.00464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:32:42.817238Z","caller":"traceutil/trace.go:171","msg":"trace[1489093655] transaction","detail":"{read_only:false; response_revision:17108; number_of_response:1; }","duration":"264.28085ms","start":"2026-06-04T12:32:42.552923Z","end":"2026-06-04T12:32:42.817204Z","steps":["trace[1489093655] 'process raft request' (duration: 264.050025ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:42.817384Z","caller":"traceutil/trace.go:171","msg":"trace[757461500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17108; }","duration":"198.089552ms","start":"2026-06-04T12:32:42.619283Z","end":"2026-06-04T12:32:42.817372Z","steps":["trace[757461500] 'agreement among raft nodes before linearized reading' (duration: 197.911708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:42.817543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.269862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-controller-default-jn4qh\" ","response":"range_response_count:1 size:14735"} {"level":"info","ts":"2026-06-04T12:32:42.817624Z","caller":"traceutil/trace.go:171","msg":"trace[643370182] range","detail":"{range_begin:/registry/pods/openstack/ovn-controller-default-jn4qh; range_end:; response_count:1; response_revision:17108; }","duration":"119.353483ms","start":"2026-06-04T12:32:42.698236Z","end":"2026-06-04T12:32:42.81759Z","steps":["trace[643370182] 'agreement among raft nodes before linearized reading' (duration: 119.179799ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.31211Z","caller":"traceutil/trace.go:171","msg":"trace[885540211] transaction","detail":"{read_only:false; response_revision:17109; number_of_response:1; }","duration":"484.395708ms","start":"2026-06-04T12:32:42.827693Z","end":"2026-06-04T12:32:43.312088Z","steps":["trace[885540211] 'process raft request' (duration: 480.497205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.312234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:32:42.827679Z","time spent":"484.48624ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:32:43.314704Z","caller":"traceutil/trace.go:171","msg":"trace[103490828] transaction","detail":"{read_only:false; response_revision:17110; number_of_response:1; }","duration":"485.739261ms","start":"2026-06-04T12:32:42.828938Z","end":"2026-06-04T12:32:43.314677Z","steps":["trace[103490828] 'process raft request' (duration: 485.555567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.314833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:32:42.82893Z","time spent":"485.835223ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15143,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:32:43.314942Z","caller":"traceutil/trace.go:171","msg":"trace[502082539] transaction","detail":"{read_only:false; response_revision:17111; number_of_response:1; }","duration":"243.90386ms","start":"2026-06-04T12:32:43.071029Z","end":"2026-06-04T12:32:43.314933Z","steps":["trace[502082539] 'process raft request' (duration: 243.588373ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.315169Z","caller":"traceutil/trace.go:171","msg":"trace[421371791] linearizableReadLoop","detail":"{readStateIndex:17690; appliedIndex:17688; }","duration":"353.689513ms","start":"2026-06-04T12:32:42.961206Z","end":"2026-06-04T12:32:43.314895Z","steps":["trace[421371791] 'read index received' (duration: 346.989453ms)","trace[421371791] 'applied index is now lower than readState.Index' (duration: 6.69848ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:32:43.315393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.076447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cc2zk\" ","response":"range_response_count:1 size:6521"} {"level":"warn","ts":"2026-06-04T12:32:43.315403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.195967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-06-04T12:32:43.315469Z","caller":"traceutil/trace.go:171","msg":"trace[19787623] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cc2zk; range_end:; response_count:1; response_revision:17111; }","duration":"149.170789ms","start":"2026-06-04T12:32:43.16627Z","end":"2026-06-04T12:32:43.315441Z","steps":["trace[19787623] 'agreement among raft nodes before linearized reading' (duration: 149.038016ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.315476Z","caller":"traceutil/trace.go:171","msg":"trace[980220662] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17111; }","duration":"354.271849ms","start":"2026-06-04T12:32:42.961187Z","end":"2026-06-04T12:32:43.315459Z","steps":["trace[980220662] 'agreement among raft nodes before linearized reading' (duration: 354.034503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.315529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:32:42.961102Z","time spent":"354.416152ms","remote":"127.0.0.1:58594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-04T12:32:43.315545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.486795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-twght\" ","response":"range_response_count:1 size:15432"} {"level":"info","ts":"2026-06-04T12:32:43.315575Z","caller":"traceutil/trace.go:171","msg":"trace[228162935] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-twght; range_end:; response_count:1; response_revision:17111; }","duration":"274.513585ms","start":"2026-06-04T12:32:43.041052Z","end":"2026-06-04T12:32:43.315565Z","steps":["trace[228162935] 'agreement among raft nodes before linearized reading' (duration: 274.443383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.315476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.839088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:32:43.315729Z","caller":"traceutil/trace.go:171","msg":"trace[1739021654] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:17111; }","duration":"226.115704ms","start":"2026-06-04T12:32:43.089601Z","end":"2026-06-04T12:32:43.315717Z","steps":["trace[1739021654] 'agreement among raft nodes before linearized reading' (duration: 225.852537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.315434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.473386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-b8tsd\" ","response":"range_response_count:1 size:15441"} {"level":"info","ts":"2026-06-04T12:32:43.316046Z","caller":"traceutil/trace.go:171","msg":"trace[1234934298] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-b8tsd; range_end:; response_count:1; response_revision:17111; }","duration":"308.115461ms","start":"2026-06-04T12:32:43.007914Z","end":"2026-06-04T12:32:43.316029Z","steps":["trace[1234934298] 'agreement among raft nodes before linearized reading' (duration: 307.425015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.316113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:32:43.007895Z","time spent":"308.189062ms","remote":"127.0.0.1:58616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":15464,"request content":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-b8tsd\" "} {"level":"info","ts":"2026-06-04T12:33:51.889988Z","caller":"traceutil/trace.go:171","msg":"trace[1144765818] linearizableReadLoop","detail":"{readStateIndex:18246; appliedIndex:18245; }","duration":"209.2727ms","start":"2026-06-04T12:33:51.680696Z","end":"2026-06-04T12:33:51.889969Z","steps":["trace[1144765818] 'read index received' (duration: 209.136206ms)","trace[1144765818] 'applied index is now lower than readState.Index' (duration: 135.394µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:33:51.890041Z","caller":"traceutil/trace.go:171","msg":"trace[1795218163] transaction","detail":"{read_only:false; response_revision:17648; number_of_response:1; }","duration":"248.963952ms","start":"2026-06-04T12:33:51.641049Z","end":"2026-06-04T12:33:51.890013Z","steps":["trace[1795218163] 'process raft request' (duration: 248.804627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:33:51.890117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.420094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:33:51.890149Z","caller":"traceutil/trace.go:171","msg":"trace[967933470] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17648; }","duration":"209.495435ms","start":"2026-06-04T12:33:51.680643Z","end":"2026-06-04T12:33:51.890139Z","steps":["trace[967933470] 'agreement among raft nodes before linearized reading' (duration: 209.433614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:33:51.890302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.582234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:33:51.890392Z","caller":"traceutil/trace.go:171","msg":"trace[440182892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17648; }","duration":"178.724048ms","start":"2026-06-04T12:33:51.71165Z","end":"2026-06-04T12:33:51.890374Z","steps":["trace[440182892] 'agreement among raft nodes before linearized reading' (duration: 178.478812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:33:52.179935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.019608ms","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-06-04T12:33:52.179998Z","caller":"traceutil/trace.go:171","msg":"trace[1489153779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17649; }","duration":"190.134681ms","start":"2026-06-04T12:33:51.98985Z","end":"2026-06-04T12:33:52.179985Z","steps":["trace[1489153779] 'range keys from in-memory index tree' (duration: 189.858564ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:33:52.420141Z","caller":"traceutil/trace.go:171","msg":"trace[831052472] transaction","detail":"{read_only:false; response_revision:17650; number_of_response:1; }","duration":"236.308618ms","start":"2026-06-04T12:33:52.183808Z","end":"2026-06-04T12:33:52.420117Z","steps":["trace[831052472] 'process raft request' (duration: 236.188075ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:33:52.443428Z","caller":"traceutil/trace.go:171","msg":"trace[1275552077] transaction","detail":"{read_only:false; response_revision:17651; number_of_response:1; }","duration":"258.678953ms","start":"2026-06-04T12:33:52.184723Z","end":"2026-06-04T12:33:52.443402Z","steps":["trace[1275552077] 'process raft request' (duration: 258.51247ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:33:52.443737Z","caller":"traceutil/trace.go:171","msg":"trace[1581141359] linearizableReadLoop","detail":"{readStateIndex:18250; appliedIndex:18247; }","duration":"252.415944ms","start":"2026-06-04T12:33:52.19131Z","end":"2026-06-04T12:33:52.443726Z","steps":["trace[1581141359] 'read index received' (duration: 228.623974ms)","trace[1581141359] 'applied index is now lower than readState.Index' (duration: 23.79093ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:33:52.443661Z","caller":"traceutil/trace.go:171","msg":"trace[1874816227] transaction","detail":"{read_only:false; response_revision:17652; number_of_response:1; }","duration":"257.309342ms","start":"2026-06-04T12:33:52.186323Z","end":"2026-06-04T12:33:52.443633Z","steps":["trace[1874816227] 'process raft request' (duration: 257.029415ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:33:52.443769Z","caller":"traceutil/trace.go:171","msg":"trace[979133362] transaction","detail":"{read_only:false; response_revision:17653; number_of_response:1; }","duration":"150.360406ms","start":"2026-06-04T12:33:52.293382Z","end":"2026-06-04T12:33:52.443743Z","steps":["trace[979133362] 'process raft request' (duration: 150.155291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:33:52.444025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.152679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-04T12:33:52.444073Z","caller":"traceutil/trace.go:171","msg":"trace[1823598830] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17653; }","duration":"147.285422ms","start":"2026-06-04T12:33:52.296774Z","end":"2026-06-04T12:33:52.44406Z","steps":["trace[1823598830] 'agreement among raft nodes before linearized reading' (duration: 147.034146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:33:52.444564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.235193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:943108"} {"level":"info","ts":"2026-06-04T12:33:52.444635Z","caller":"traceutil/trace.go:171","msg":"trace[299203121] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17653; }","duration":"253.369797ms","start":"2026-06-04T12:33:52.191251Z","end":"2026-06-04T12:33:52.444621Z","steps":["trace[299203121] 'agreement among raft nodes before linearized reading' (duration: 252.530386ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:07.203964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15448} {"level":"info","ts":"2026-06-04T12:34:07.300588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15448,"took":"92.057298ms","hash":2687751840,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":33370112,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-04T12:34:07.300685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2687751840,"revision":15448,"compact-revision":12930} {"level":"info","ts":"2026-06-04T12:37:22.908153Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:37:22.913708Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-04T12:37:22.913835Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-04T12:39:07.211658Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17963} {"level":"info","ts":"2026-06-04T12:39:07.314685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17963,"took":"99.141629ms","hash":879877339,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":29696000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-04T12:39:07.314736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":879877339,"revision":17963,"compact-revision":15448} {"level":"info","ts":"2026-06-04T12:39:19.789305Z","caller":"traceutil/trace.go:171","msg":"trace[2009643261] transaction","detail":"{read_only:false; response_revision:20145; number_of_response:1; }","duration":"161.7315ms","start":"2026-06-04T12:39:19.627543Z","end":"2026-06-04T12:39:19.789274Z","steps":["trace[2009643261] 'process raft request' (duration: 161.555415ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:39:20.135047Z","caller":"traceutil/trace.go:171","msg":"trace[263732685] transaction","detail":"{read_only:false; response_revision:20146; number_of_response:1; }","duration":"103.571535ms","start":"2026-06-04T12:39:20.031438Z","end":"2026-06-04T12:39:20.13501Z","steps":["trace[263732685] 'process raft request' (duration: 103.242037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:39:20.416929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.485284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:39:20.417136Z","caller":"traceutil/trace.go:171","msg":"trace[50286927] linearizableReadLoop","detail":"{readStateIndex:20831; appliedIndex:20829; }","duration":"258.057221ms","start":"2026-06-04T12:39:20.159062Z","end":"2026-06-04T12:39:20.417119Z","steps":["trace[50286927] 'read index received' (duration: 101.30073ms)","trace[50286927] 'applied index is now lower than readState.Index' (duration: 156.755691ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:39:20.417194Z","caller":"traceutil/trace.go:171","msg":"trace[897859910] transaction","detail":"{read_only:false; response_revision:20148; number_of_response:1; }","duration":"276.969834ms","start":"2026-06-04T12:39:20.140217Z","end":"2026-06-04T12:39:20.417187Z","steps":["trace[897859910] 'process raft request' (duration: 276.827321ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:39:20.4173Z","caller":"traceutil/trace.go:171","msg":"trace[398255096] transaction","detail":"{read_only:false; response_revision:20147; number_of_response:1; }","duration":"277.150869ms","start":"2026-06-04T12:39:20.140089Z","end":"2026-06-04T12:39:20.41724Z","steps":["trace[398255096] 'process raft request' (duration: 120.257805ms)","trace[398255096] 'compare' (duration: 156.346131ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:39:20.417608Z","caller":"traceutil/trace.go:171","msg":"trace[536386671] transaction","detail":"{read_only:false; response_revision:20149; number_of_response:1; }","duration":"160.626183ms","start":"2026-06-04T12:39:20.256971Z","end":"2026-06-04T12:39:20.417597Z","steps":["trace[536386671] 'process raft request' (duration: 160.118491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:39:20.417929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.87375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-04T12:39:20.417969Z","caller":"traceutil/trace.go:171","msg":"trace[1039027773] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:20149; }","duration":"258.959112ms","start":"2026-06-04T12:39:20.159Z","end":"2026-06-04T12:39:20.417959Z","steps":["trace[1039027773] 'agreement among raft nodes before linearized reading' (duration: 258.802348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:39:20.448246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.93933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-04T12:39:20.448362Z","caller":"traceutil/trace.go:171","msg":"trace[981058142] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20150; }","duration":"166.119934ms","start":"2026-06-04T12:39:20.282209Z","end":"2026-06-04T12:39:20.448329Z","steps":["trace[981058142] 'agreement among raft nodes before linearized reading' (duration: 165.672934ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:39:20.583825Z","caller":"traceutil/trace.go:171","msg":"trace[1375414819] transaction","detail":"{read_only:false; response_revision:20151; number_of_response:1; }","duration":"130.142382ms","start":"2026-06-04T12:39:20.453652Z","end":"2026-06-04T12:39:20.583795Z","steps":["trace[1375414819] 'process raft request' (duration: 129.997958ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:39:20.718749Z","caller":"traceutil/trace.go:171","msg":"trace[528872239] transaction","detail":"{read_only:false; response_revision:20152; number_of_response:1; }","duration":"133.959203ms","start":"2026-06-04T12:39:20.584763Z","end":"2026-06-04T12:39:20.718722Z","steps":["trace[528872239] 'process raft request' (duration: 124.856474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:39:20.985449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.225157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:39:20.98559Z","caller":"traceutil/trace.go:171","msg":"trace[621700474] linearizableReadLoop","detail":"{readStateIndex:20837; appliedIndex:20836; }","duration":"202.103758ms","start":"2026-06-04T12:39:20.783464Z","end":"2026-06-04T12:39:20.985568Z","steps":["trace[621700474] 'read index received' (duration: 73.601026ms)","trace[621700474] 'applied index is now lower than readState.Index' (duration: 128.501252ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:39:20.985644Z","caller":"traceutil/trace.go:171","msg":"trace[14537482] transaction","detail":"{read_only:false; response_revision:20153; number_of_response:1; }","duration":"257.971208ms","start":"2026-06-04T12:39:20.727637Z","end":"2026-06-04T12:39:20.985608Z","steps":["trace[14537482] 'process raft request' (duration: 129.472236ms)","trace[14537482] 'compare' (duration: 127.96569ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:39:20.987175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.700126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-04T12:39:20.987235Z","caller":"traceutil/trace.go:171","msg":"trace[2019218529] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20153; }","duration":"203.807149ms","start":"2026-06-04T12:39:20.783414Z","end":"2026-06-04T12:39:20.987222Z","steps":["trace[2019218529] 'agreement among raft nodes before linearized reading' (duration: 202.219571ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:43:05.201725Z","caller":"traceutil/trace.go:171","msg":"trace[1787664411] transaction","detail":"{read_only:false; response_revision:22512; number_of_response:1; }","duration":"306.204096ms","start":"2026-06-04T12:43:04.895496Z","end":"2026-06-04T12:43:05.2017Z","steps":["trace[1787664411] 'process raft request' (duration: 306.055723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:43:05.201902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:43:04.895476Z","time spent":"306.34364ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:43:05.202699Z","caller":"traceutil/trace.go:171","msg":"trace[392811194] transaction","detail":"{read_only:false; response_revision:22513; number_of_response:1; }","duration":"118.056892ms","start":"2026-06-04T12:43:05.084608Z","end":"2026-06-04T12:43:05.202665Z","steps":["trace[392811194] 'process raft request' (duration: 117.776015ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:43:05.202823Z","caller":"traceutil/trace.go:171","msg":"trace[1595267347] transaction","detail":"{read_only:false; response_revision:22514; number_of_response:1; }","duration":"111.795101ms","start":"2026-06-04T12:43:05.091012Z","end":"2026-06-04T12:43:05.202807Z","steps":["trace[1595267347] 'process raft request' (duration: 111.594217ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:44:07.217812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20079} {"level":"info","ts":"2026-06-04T12:44:07.321244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20079,"took":"99.996809ms","hash":3662382585,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":40910848,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-06-04T12:44:07.321346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3662382585,"revision":20079,"compact-revision":17963} {"level":"info","ts":"2026-06-04T12:46:29.018803Z","caller":"traceutil/trace.go:171","msg":"trace[600044494] linearizableReadLoop","detail":"{readStateIndex:25423; appliedIndex:25422; }","duration":"182.829303ms","start":"2026-06-04T12:46:28.835946Z","end":"2026-06-04T12:46:29.018776Z","steps":["trace[600044494] 'read index received' (duration: 182.560527ms)","trace[600044494] 'applied index is now lower than readState.Index' (duration: 267.826µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:29.018862Z","caller":"traceutil/trace.go:171","msg":"trace[724849196] transaction","detail":"{read_only:false; response_revision:24615; number_of_response:1; }","duration":"217.584286ms","start":"2026-06-04T12:46:28.801236Z","end":"2026-06-04T12:46:29.01882Z","steps":["trace[724849196] 'process raft request' (duration: 217.374061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:29.019025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.0656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-06-04T12:46:29.019069Z","caller":"traceutil/trace.go:171","msg":"trace[101996191] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24615; }","duration":"183.169583ms","start":"2026-06-04T12:46:28.835888Z","end":"2026-06-04T12:46:29.019058Z","steps":["trace[101996191] 'agreement among raft nodes before linearized reading' (duration: 182.995138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:29.501674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.455768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:29.501872Z","caller":"traceutil/trace.go:171","msg":"trace[946758891] linearizableReadLoop","detail":"{readStateIndex:25424; appliedIndex:25423; }","duration":"469.63293ms","start":"2026-06-04T12:46:29.032215Z","end":"2026-06-04T12:46:29.501847Z","steps":["trace[946758891] 'read index received' (duration: 265.852334ms)","trace[946758891] 'applied index is now lower than readState.Index' (duration: 203.777176ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:29.501863Z","caller":"traceutil/trace.go:171","msg":"trace[1251136346] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"471.512205ms","start":"2026-06-04T12:46:29.030278Z","end":"2026-06-04T12:46:29.50179Z","steps":["trace[1251136346] 'process raft request' (duration: 267.854812ms)","trace[1251136346] 'compare' (duration: 203.338556ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:29.502117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:29.030255Z","time spent":"471.7595ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:29.502122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.886526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:684"} {"level":"info","ts":"2026-06-04T12:46:29.502195Z","caller":"traceutil/trace.go:171","msg":"trace[962481322] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:24616; }","duration":"469.989588ms","start":"2026-06-04T12:46:29.032186Z","end":"2026-06-04T12:46:29.502176Z","steps":["trace[962481322] 'agreement among raft nodes before linearized reading' (duration: 469.762603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:29.50224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:29.032155Z","time spent":"470.074961ms","remote":"127.0.0.1:58594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":707,"request content":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" "} {"level":"info","ts":"2026-06-04T12:46:29.502392Z","caller":"traceutil/trace.go:171","msg":"trace[265822790] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"235.048855ms","start":"2026-06-04T12:46:29.267307Z","end":"2026-06-04T12:46:29.502356Z","steps":["trace[265822790] 'process raft request' (duration: 234.554994ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:29.830084Z","caller":"traceutil/trace.go:171","msg":"trace[503684879] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"114.554547ms","start":"2026-06-04T12:46:29.715502Z","end":"2026-06-04T12:46:29.830057Z","steps":["trace[503684879] 'process raft request' (duration: 114.500646ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:29.830504Z","caller":"traceutil/trace.go:171","msg":"trace[1666608192] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"170.541189ms","start":"2026-06-04T12:46:29.659947Z","end":"2026-06-04T12:46:29.830488Z","steps":["trace[1666608192] 'process raft request' (duration: 120.10973ms)","trace[1666608192] 'compare' (duration: 49.780144ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:29.830636Z","caller":"traceutil/trace.go:171","msg":"trace[1146813911] linearizableReadLoop","detail":"{readStateIndex:25426; appliedIndex:25425; }","duration":"146.071812ms","start":"2026-06-04T12:46:29.684538Z","end":"2026-06-04T12:46:29.83061Z","steps":["trace[1146813911] 'read index received' (duration: 95.480409ms)","trace[1146813911] 'applied index is now lower than readState.Index' (duration: 50.589183ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:29.830864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.326317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-04T12:46:29.830907Z","caller":"traceutil/trace.go:171","msg":"trace[345464656] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24619; }","duration":"146.383479ms","start":"2026-06-04T12:46:29.68451Z","end":"2026-06-04T12:46:29.830894Z","steps":["trace[345464656] 'agreement among raft nodes before linearized reading' (duration: 146.201404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:29.830964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.188722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:46:29.831007Z","caller":"traceutil/trace.go:171","msg":"trace[775510353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24617; }","duration":"201.257904ms","start":"2026-06-04T12:46:29.629739Z","end":"2026-06-04T12:46:29.830997Z","steps":["trace[775510353] 'range keys from in-memory index tree' (duration: 199.172275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:29.830919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.606345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-04T12:46:29.831301Z","caller":"traceutil/trace.go:171","msg":"trace[1558453125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24617; }","duration":"215.041195ms","start":"2026-06-04T12:46:29.616248Z","end":"2026-06-04T12:46:29.831289Z","steps":["trace[1558453125] 'range keys from in-memory index tree' (duration: 212.688319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:30.125331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.369687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:30.125405Z","caller":"traceutil/trace.go:171","msg":"trace[1088454609] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"285.299439ms","start":"2026-06-04T12:46:29.840091Z","end":"2026-06-04T12:46:30.12539Z","steps":["trace[1088454609] 'process raft request' (duration: 131.81566ms)","trace[1088454609] 'compare' (duration: 153.285805ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:30.126593Z","caller":"traceutil/trace.go:171","msg":"trace[1378382564] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"242.799612ms","start":"2026-06-04T12:46:29.883788Z","end":"2026-06-04T12:46:30.126587Z","steps":["trace[1378382564] 'process raft request' (duration: 242.776441ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:30.126759Z","caller":"traceutil/trace.go:171","msg":"trace[2130612572] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"285.650299ms","start":"2026-06-04T12:46:29.841103Z","end":"2026-06-04T12:46:30.126754Z","steps":["trace[2130612572] 'process raft request' (duration: 285.402353ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:30.126822Z","caller":"traceutil/trace.go:171","msg":"trace[993955904] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"283.774373ms","start":"2026-06-04T12:46:29.843043Z","end":"2026-06-04T12:46:30.126818Z","steps":["trace[993955904] 'process raft request' (duration: 283.499157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:30.414762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.196417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:30.414905Z","caller":"traceutil/trace.go:171","msg":"trace[916151324] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"172.429114ms","start":"2026-06-04T12:46:30.242465Z","end":"2026-06-04T12:46:30.414895Z","steps":["trace[916151324] 'process raft request' (duration: 172.408003ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:30.41506Z","caller":"traceutil/trace.go:171","msg":"trace[1215462978] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"387.388767ms","start":"2026-06-04T12:46:30.02763Z","end":"2026-06-04T12:46:30.415019Z","steps":["trace[1215462978] 'process raft request' (duration: 252.879752ms)","trace[1215462978] 'compare' (duration: 132.936737ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:30.415286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:30.027612Z","time spent":"387.583132ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:30.415333Z","caller":"traceutil/trace.go:171","msg":"trace[859501219] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"362.660664ms","start":"2026-06-04T12:46:30.052663Z","end":"2026-06-04T12:46:30.415324Z","steps":["trace[859501219] 'process raft request' (duration: 362.169952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:30.415382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:30.052639Z","time spent":"362.716317ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:30.415579Z","caller":"traceutil/trace.go:171","msg":"trace[1022126323] linearizableReadLoop","detail":"{readStateIndex:25433; appliedIndex:25431; }","duration":"232.685229ms","start":"2026-06-04T12:46:30.182884Z","end":"2026-06-04T12:46:30.415569Z","steps":["trace[1022126323] 'read index received' (duration: 97.617471ms)","trace[1022126323] 'applied index is now lower than readState.Index' (duration: 135.066948ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:30.415958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.068458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-04T12:46:30.415999Z","caller":"traceutil/trace.go:171","msg":"trace[1493013455] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:24626; }","duration":"233.14579ms","start":"2026-06-04T12:46:30.182842Z","end":"2026-06-04T12:46:30.415988Z","steps":["trace[1493013455] 'agreement among raft nodes before linearized reading' (duration: 232.810172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:30.416303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.91102ms","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-06-04T12:46:30.416346Z","caller":"traceutil/trace.go:171","msg":"trace[500751951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24626; }","duration":"138.985011ms","start":"2026-06-04T12:46:30.277351Z","end":"2026-06-04T12:46:30.416336Z","steps":["trace[500751951] 'agreement among raft nodes before linearized reading' (duration: 138.871348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:35.710256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.583513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:35.710356Z","caller":"traceutil/trace.go:171","msg":"trace[1400974903] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"186.159753ms","start":"2026-06-04T12:46:35.524171Z","end":"2026-06-04T12:46:35.710331Z","steps":["trace[1400974903] 'process raft request' (duration: 62.413796ms)","trace[1400974903] 'compare' (duration: 122.748703ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:36.007639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.554941ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-04T12:46:36.007824Z","caller":"traceutil/trace.go:171","msg":"trace[692146713] linearizableReadLoop","detail":"{readStateIndex:25473; appliedIndex:25472; }","duration":"232.236398ms","start":"2026-06-04T12:46:35.775575Z","end":"2026-06-04T12:46:36.007811Z","steps":["trace[692146713] 'read index received' (duration: 41.991µs)","trace[692146713] 'applied index is now lower than readState.Index' (duration: 232.193537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:36.007938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.356001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-06-04T12:46:36.007966Z","caller":"traceutil/trace.go:171","msg":"trace[22098853] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:24663; }","duration":"232.430813ms","start":"2026-06-04T12:46:35.775527Z","end":"2026-06-04T12:46:36.007958Z","steps":["trace[22098853] 'agreement among raft nodes before linearized reading' (duration: 232.32652ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:36.009331Z","caller":"traceutil/trace.go:171","msg":"trace[742044899] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"152.347884ms","start":"2026-06-04T12:46:35.856958Z","end":"2026-06-04T12:46:36.009306Z","steps":["trace[742044899] 'process raft request' (duration: 150.767175ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:36.060056Z","caller":"traceutil/trace.go:171","msg":"trace[631547785] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"196.835039ms","start":"2026-06-04T12:46:35.863183Z","end":"2026-06-04T12:46:36.060018Z","steps":["trace[631547785] 'process raft request' (duration: 181.912242ms)","trace[631547785] 'compare' (duration: 13.886513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:36.383632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.34065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:36.383836Z","caller":"traceutil/trace.go:171","msg":"trace[418170450] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"232.746381ms","start":"2026-06-04T12:46:36.15105Z","end":"2026-06-04T12:46:36.383797Z","steps":["trace[418170450] 'process raft request' (duration: 78.162414ms)","trace[418170450] 'compare' (duration: 154.026303ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:36.383733Z","caller":"traceutil/trace.go:171","msg":"trace[1007550388] linearizableReadLoop","detail":"{readStateIndex:25476; appliedIndex:25475; }","duration":"227.979195ms","start":"2026-06-04T12:46:36.155738Z","end":"2026-06-04T12:46:36.383717Z","steps":["trace[1007550388] 'read index received' (duration: 73.384849ms)","trace[1007550388] 'applied index is now lower than readState.Index' (duration: 154.592866ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:36.384367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.605731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-04T12:46:36.384448Z","caller":"traceutil/trace.go:171","msg":"trace[1972379498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24666; }","duration":"228.734784ms","start":"2026-06-04T12:46:36.155699Z","end":"2026-06-04T12:46:36.384434Z","steps":["trace[1972379498] 'agreement among raft nodes before linearized reading' (duration: 228.512529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.384492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.266542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-04T12:46:36.384568Z","caller":"traceutil/trace.go:171","msg":"trace[695834149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24666; }","duration":"225.364454ms","start":"2026-06-04T12:46:36.159163Z","end":"2026-06-04T12:46:36.384528Z","steps":["trace[695834149] 'agreement among raft nodes before linearized reading' (duration: 225.169029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.384819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.288118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:36.384867Z","caller":"traceutil/trace.go:171","msg":"trace[1539153659] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24666; }","duration":"131.365799ms","start":"2026-06-04T12:46:36.253488Z","end":"2026-06-04T12:46:36.384854Z","steps":["trace[1539153659] 'agreement among raft nodes before linearized reading' (duration: 131.288807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.701281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.157469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:36.701395Z","caller":"traceutil/trace.go:171","msg":"trace[1560237636] linearizableReadLoop","detail":"{readStateIndex:25478; appliedIndex:25477; }","duration":"285.018284ms","start":"2026-06-04T12:46:36.416358Z","end":"2026-06-04T12:46:36.701377Z","steps":["trace[1560237636] 'read index received' (duration: 132.66261ms)","trace[1560237636] 'applied index is now lower than readState.Index' (duration: 152.354104ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:36.701653Z","caller":"traceutil/trace.go:171","msg":"trace[43536135] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"312.835631ms","start":"2026-06-04T12:46:36.388797Z","end":"2026-06-04T12:46:36.701633Z","steps":["trace[43536135] 'process raft request' (duration: 160.210261ms)","trace[43536135] 'compare' (duration: 151.826991ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:36.701763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:36.388781Z","time spent":"312.926743ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:36.702049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.683089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:36.702115Z","caller":"traceutil/trace.go:171","msg":"trace[986208747] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24668; }","duration":"285.781612ms","start":"2026-06-04T12:46:36.416316Z","end":"2026-06-04T12:46:36.702097Z","steps":["trace[986208747] 'agreement among raft nodes before linearized reading' (duration: 285.676989ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:36.713616Z","caller":"traceutil/trace.go:171","msg":"trace[906903817] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"274.049101ms","start":"2026-06-04T12:46:36.439548Z","end":"2026-06-04T12:46:36.713597Z","steps":["trace[906903817] 'process raft request' (duration: 273.686772ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:36.713848Z","caller":"traceutil/trace.go:171","msg":"trace[403699985] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"274.159934ms","start":"2026-06-04T12:46:36.439679Z","end":"2026-06-04T12:46:36.713839Z","steps":["trace[403699985] 'process raft request' (duration: 273.682872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.71416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.197684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:36.714211Z","caller":"traceutil/trace.go:171","msg":"trace[867361790] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"181.864319ms","start":"2026-06-04T12:46:36.532332Z","end":"2026-06-04T12:46:36.714196Z","steps":["trace[867361790] 'process raft request' (duration: 181.06201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.714315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.692447ms","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-06-04T12:46:36.714348Z","caller":"traceutil/trace.go:171","msg":"trace[296882393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24671; }","duration":"151.762859ms","start":"2026-06-04T12:46:36.562579Z","end":"2026-06-04T12:46:36.714341Z","steps":["trace[296882393] 'agreement among raft nodes before linearized reading' (duration: 151.663956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.714361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.292564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:36.714387Z","caller":"traceutil/trace.go:171","msg":"trace[2003866144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24671; }","duration":"258.351825ms","start":"2026-06-04T12:46:36.456026Z","end":"2026-06-04T12:46:36.714378Z","steps":["trace[2003866144] 'agreement among raft nodes before linearized reading' (duration: 258.313924ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:36.714218Z","caller":"traceutil/trace.go:171","msg":"trace[1482028979] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:24671; }","duration":"274.299695ms","start":"2026-06-04T12:46:36.439905Z","end":"2026-06-04T12:46:36.714205Z","steps":["trace[1482028979] 'agreement among raft nodes before linearized reading' (duration: 274.207373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.714479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.88911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-04T12:46:36.714518Z","caller":"traceutil/trace.go:171","msg":"trace[1958364352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24671; }","duration":"143.950242ms","start":"2026-06-04T12:46:36.570558Z","end":"2026-06-04T12:46:36.714508Z","steps":["trace[1958364352] 'agreement among raft nodes before linearized reading' (duration: 143.86229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:36.714157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.18261ms","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-06-04T12:46:36.714547Z","caller":"traceutil/trace.go:171","msg":"trace[442723830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24671; }","duration":"258.622071ms","start":"2026-06-04T12:46:36.45592Z","end":"2026-06-04T12:46:36.714542Z","steps":["trace[442723830] 'agreement among raft nodes before linearized reading' (duration: 258.112698ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:37.064406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.013764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:37.064599Z","caller":"traceutil/trace.go:171","msg":"trace[1476785256] linearizableReadLoop","detail":"{readStateIndex:25484; appliedIndex:25481; }","duration":"344.175671ms","start":"2026-06-04T12:46:36.720407Z","end":"2026-06-04T12:46:37.064583Z","steps":["trace[1476785256] 'read index received' (duration: 53.444221ms)","trace[1476785256] 'applied index is now lower than readState.Index' (duration: 290.73009ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:37.064708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.292945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:37.064679Z","caller":"traceutil/trace.go:171","msg":"trace[2052409844] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"346.923298ms","start":"2026-06-04T12:46:36.717712Z","end":"2026-06-04T12:46:37.064635Z","steps":["trace[2052409844] 'process raft request' (duration: 209.602865ms)","trace[2052409844] 'compare' (duration: 136.650506ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:37.064745Z","caller":"traceutil/trace.go:171","msg":"trace[280246437] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24674; }","duration":"344.340766ms","start":"2026-06-04T12:46:36.72039Z","end":"2026-06-04T12:46:37.064731Z","steps":["trace[280246437] 'agreement among raft nodes before linearized reading' (duration: 344.282204ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:37.064707Z","caller":"traceutil/trace.go:171","msg":"trace[1218083932] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"346.715252ms","start":"2026-06-04T12:46:36.717953Z","end":"2026-06-04T12:46:37.064668Z","steps":["trace[1218083932] 'process raft request' (duration: 346.568229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:37.064777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:36.720384Z","time spent":"344.385427ms","remote":"127.0.0.1:58690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-04T12:46:37.064893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:36.717948Z","time spent":"346.847935ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:37.065166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.142008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-04T12:46:37.065219Z","caller":"traceutil/trace.go:171","msg":"trace[751092336] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24674; }","duration":"132.22569ms","start":"2026-06-04T12:46:36.932982Z","end":"2026-06-04T12:46:37.065207Z","steps":["trace[751092336] 'agreement among raft nodes before linearized reading' (duration: 132.020875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:37.069932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:36.717681Z","time spent":"347.076911ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:37.469444Z","caller":"traceutil/trace.go:171","msg":"trace[569369635] linearizableReadLoop","detail":"{readStateIndex:25486; appliedIndex:25485; }","duration":"240.446845ms","start":"2026-06-04T12:46:37.228975Z","end":"2026-06-04T12:46:37.469422Z","steps":["trace[569369635] 'read index received' (duration: 240.24715ms)","trace[569369635] 'applied index is now lower than readState.Index' (duration: 198.635µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:37.469589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.594708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:37.469615Z","caller":"traceutil/trace.go:171","msg":"trace[140273855] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24676; }","duration":"240.67914ms","start":"2026-06-04T12:46:37.228927Z","end":"2026-06-04T12:46:37.469607Z","steps":["trace[140273855] 'agreement among raft nodes before linearized reading' (duration: 240.609249ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:37.469605Z","caller":"traceutil/trace.go:171","msg":"trace[370481532] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"274.340578ms","start":"2026-06-04T12:46:37.195194Z","end":"2026-06-04T12:46:37.469534Z","steps":["trace[370481532] 'process raft request' (duration: 274.093712ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:37.979314Z","caller":"traceutil/trace.go:171","msg":"trace[707294127] linearizableReadLoop","detail":"{readStateIndex:25487; appliedIndex:25486; }","duration":"166.363698ms","start":"2026-06-04T12:46:37.812916Z","end":"2026-06-04T12:46:37.979279Z","steps":["trace[707294127] 'read index received' (duration: 166.03898ms)","trace[707294127] 'applied index is now lower than readState.Index' (duration: 323.488µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:37.979412Z","caller":"traceutil/trace.go:171","msg":"trace[949202025] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"263.772074ms","start":"2026-06-04T12:46:37.7156Z","end":"2026-06-04T12:46:37.979372Z","steps":["trace[949202025] 'process raft request' (duration: 263.444607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:37.979598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.667685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-06-04T12:46:37.979643Z","caller":"traceutil/trace.go:171","msg":"trace[195857439] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24677; }","duration":"166.785398ms","start":"2026-06-04T12:46:37.812848Z","end":"2026-06-04T12:46:37.979633Z","steps":["trace[195857439] 'agreement among raft nodes before linearized reading' (duration: 166.567963ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:38.638883Z","caller":"traceutil/trace.go:171","msg":"trace[1114000031] linearizableReadLoop","detail":"{readStateIndex:25492; appliedIndex:25491; }","duration":"187.494014ms","start":"2026-06-04T12:46:38.451363Z","end":"2026-06-04T12:46:38.638857Z","steps":["trace[1114000031] 'read index received' (duration: 187.242278ms)","trace[1114000031] 'applied index is now lower than readState.Index' (duration: 250.476µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:38.639008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.633199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:38.638991Z","caller":"traceutil/trace.go:171","msg":"trace[192372355] transaction","detail":"{read_only:false; response_revision:24682; number_of_response:1; }","duration":"218.52107ms","start":"2026-06-04T12:46:38.420438Z","end":"2026-06-04T12:46:38.638959Z","steps":["trace[192372355] 'process raft request' (duration: 218.252372ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:38.639046Z","caller":"traceutil/trace.go:171","msg":"trace[616566876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24682; }","duration":"187.727131ms","start":"2026-06-04T12:46:38.451309Z","end":"2026-06-04T12:46:38.639037Z","steps":["trace[616566876] 'agreement among raft nodes before linearized reading' (duration: 187.641868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:38.837642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.327598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-04T12:46:38.837706Z","caller":"traceutil/trace.go:171","msg":"trace[955113546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24682; }","duration":"131.44196ms","start":"2026-06-04T12:46:38.70625Z","end":"2026-06-04T12:46:38.837692Z","steps":["trace[955113546] 'range keys from in-memory index tree' (duration: 131.156303ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:38.837922Z","caller":"traceutil/trace.go:171","msg":"trace[311782954] transaction","detail":"{read_only:false; response_revision:24683; number_of_response:1; }","duration":"118.821309ms","start":"2026-06-04T12:46:38.719074Z","end":"2026-06-04T12:46:38.837896Z","steps":["trace[311782954] 'process raft request' (duration: 51.832143ms)","trace[311782954] 'compare' (duration: 66.821201ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:38.901566Z","caller":"traceutil/trace.go:171","msg":"trace[625423416] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"181.715687ms","start":"2026-06-04T12:46:38.719829Z","end":"2026-06-04T12:46:38.901544Z","steps":["trace[625423416] 'process raft request' (duration: 181.524902ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:38.90181Z","caller":"traceutil/trace.go:171","msg":"trace[2059559912] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"181.565472ms","start":"2026-06-04T12:46:38.720231Z","end":"2026-06-04T12:46:38.901797Z","steps":["trace[2059559912] 'process raft request' (duration: 181.272366ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:38.90178Z","caller":"traceutil/trace.go:171","msg":"trace[1938618549] linearizableReadLoop","detail":"{readStateIndex:25495; appliedIndex:25493; }","duration":"120.803306ms","start":"2026-06-04T12:46:38.780945Z","end":"2026-06-04T12:46:38.901748Z","steps":["trace[1938618549] 'read index received' (duration: 120.460638ms)","trace[1938618549] 'applied index is now lower than readState.Index' (duration: 341.048µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:38.902072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.103473ms","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-06-04T12:46:38.902134Z","caller":"traceutil/trace.go:171","msg":"trace[92239199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24685; }","duration":"121.224616ms","start":"2026-06-04T12:46:38.780894Z","end":"2026-06-04T12:46:38.902119Z","steps":["trace[92239199] 'agreement among raft nodes before linearized reading' (duration: 120.98002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:39.084494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.036514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:39.084598Z","caller":"traceutil/trace.go:171","msg":"trace[1292794917] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"242.383412ms","start":"2026-06-04T12:46:38.842196Z","end":"2026-06-04T12:46:39.084579Z","steps":["trace[1292794917] 'process raft request' (duration: 103.188344ms)","trace[1292794917] 'compare' (duration: 138.89843ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:39.334562Z","caller":"traceutil/trace.go:171","msg":"trace[409292377] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"428.584966ms","start":"2026-06-04T12:46:38.905932Z","end":"2026-06-04T12:46:39.334517Z","steps":["trace[409292377] 'process raft request' (duration: 413.383581ms)","trace[409292377] 'compare' (duration: 14.57835ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:39.334635Z","caller":"traceutil/trace.go:171","msg":"trace[97742670] linearizableReadLoop","detail":"{readStateIndex:25497; appliedIndex:25495; }","duration":"408.255818ms","start":"2026-06-04T12:46:38.926209Z","end":"2026-06-04T12:46:39.334465Z","steps":["trace[97742670] 'read index received' (duration: 19.141509ms)","trace[97742670] 'applied index is now lower than readState.Index' (duration: 389.113139ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:39.334729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:38.905908Z","time spent":"428.734159ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:39.334745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.500724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:39.334894Z","caller":"traceutil/trace.go:171","msg":"trace[1601440041] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:24687; }","duration":"408.696529ms","start":"2026-06-04T12:46:38.926172Z","end":"2026-06-04T12:46:39.334868Z","steps":["trace[1601440041] 'agreement among raft nodes before linearized reading' (duration: 408.472484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:39.334964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.753713ms","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:6"} {"level":"warn","ts":"2026-06-04T12:46:39.334959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:38.926144Z","time spent":"408.795771ms","remote":"127.0.0.1:37932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:46:39.335005Z","caller":"traceutil/trace.go:171","msg":"trace[1693798148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:24687; }","duration":"366.874776ms","start":"2026-06-04T12:46:38.968121Z","end":"2026-06-04T12:46:39.334996Z","steps":["trace[1693798148] 'agreement among raft nodes before linearized reading' (duration: 366.759993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:39.335133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.53451ms","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-06-04T12:46:39.335205Z","caller":"traceutil/trace.go:171","msg":"trace[1290315678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24687; }","duration":"261.650782ms","start":"2026-06-04T12:46:39.073536Z","end":"2026-06-04T12:46:39.335187Z","steps":["trace[1290315678] 'agreement among raft nodes before linearized reading' (duration: 261.427427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:39.335148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:38.968101Z","time spent":"367.03625ms","remote":"127.0.0.1:47284","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":"warn","ts":"2026-06-04T12:46:39.335352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.046176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-04T12:46:39.335651Z","caller":"traceutil/trace.go:171","msg":"trace[14487864] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24687; }","duration":"253.232511ms","start":"2026-06-04T12:46:39.082201Z","end":"2026-06-04T12:46:39.335434Z","steps":["trace[14487864] 'agreement among raft nodes before linearized reading' (duration: 252.75743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:39.335782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.449228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-04T12:46:39.33584Z","caller":"traceutil/trace.go:171","msg":"trace[1573391316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24687; }","duration":"266.5344ms","start":"2026-06-04T12:46:39.069298Z","end":"2026-06-04T12:46:39.335832Z","steps":["trace[1573391316] 'agreement among raft nodes before linearized reading' (duration: 265.868304ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:39.605685Z","caller":"traceutil/trace.go:171","msg":"trace[459230767] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"264.961743ms","start":"2026-06-04T12:46:39.340692Z","end":"2026-06-04T12:46:39.605654Z","steps":["trace[459230767] 'process raft request' (duration: 196.194064ms)","trace[459230767] 'compare' (duration: 68.172054ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:39.606054Z","caller":"traceutil/trace.go:171","msg":"trace[297698638] linearizableReadLoop","detail":"{readStateIndex:25500; appliedIndex:25498; }","duration":"152.288401ms","start":"2026-06-04T12:46:39.453745Z","end":"2026-06-04T12:46:39.606034Z","steps":["trace[297698638] 'read index received' (duration: 83.131243ms)","trace[297698638] 'applied index is now lower than readState.Index' (duration: 69.156098ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:39.606221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.466816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:39.606355Z","caller":"traceutil/trace.go:171","msg":"trace[657328866] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"265.331912ms","start":"2026-06-04T12:46:39.340985Z","end":"2026-06-04T12:46:39.606317Z","steps":["trace[657328866] 'process raft request' (duration: 264.943483ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:39.606277Z","caller":"traceutil/trace.go:171","msg":"trace[422554846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24690; }","duration":"152.586709ms","start":"2026-06-04T12:46:39.453676Z","end":"2026-06-04T12:46:39.606263Z","steps":["trace[422554846] 'agreement among raft nodes before linearized reading' (duration: 152.441145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:40.174209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.765126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:40.174455Z","caller":"traceutil/trace.go:171","msg":"trace[1773420076] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"187.211069ms","start":"2026-06-04T12:46:39.987171Z","end":"2026-06-04T12:46:40.174382Z","steps":["trace[1773420076] 'process raft request' (duration: 41.163157ms)","trace[1773420076] 'compare' (duration: 145.369785ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:40.325657Z","caller":"traceutil/trace.go:171","msg":"trace[887336249] linearizableReadLoop","detail":"{readStateIndex:25504; appliedIndex:25502; }","duration":"142.21234ms","start":"2026-06-04T12:46:40.183418Z","end":"2026-06-04T12:46:40.325631Z","steps":["trace[887336249] 'read index received' (duration: 120.981661ms)","trace[887336249] 'applied index is now lower than readState.Index' (duration: 21.229329ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:40.325908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.467076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-04T12:46:40.325962Z","caller":"traceutil/trace.go:171","msg":"trace[352241653] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:24694; }","duration":"142.558488ms","start":"2026-06-04T12:46:40.18339Z","end":"2026-06-04T12:46:40.325948Z","steps":["trace[352241653] 'agreement among raft nodes before linearized reading' (duration: 142.350163ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:40.326381Z","caller":"traceutil/trace.go:171","msg":"trace[1964307134] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"242.498744ms","start":"2026-06-04T12:46:40.083859Z","end":"2026-06-04T12:46:40.326358Z","steps":["trace[1964307134] 'process raft request' (duration: 220.380034ms)","trace[1964307134] 'compare' (duration: 21.158768ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:40.326581Z","caller":"traceutil/trace.go:171","msg":"trace[330956512] transaction","detail":"{read_only:false; response_revision:24694; number_of_response:1; }","duration":"241.51979ms","start":"2026-06-04T12:46:40.085045Z","end":"2026-06-04T12:46:40.326564Z","steps":["trace[330956512] 'process raft request' (duration: 240.523667ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:40.619005Z","caller":"traceutil/trace.go:171","msg":"trace[387915793] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"214.032731ms","start":"2026-06-04T12:46:40.404949Z","end":"2026-06-04T12:46:40.618981Z","steps":["trace[387915793] 'process raft request' (duration: 213.713993ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:40.619013Z","caller":"traceutil/trace.go:171","msg":"trace[601570201] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"189.587735ms","start":"2026-06-04T12:46:40.429413Z","end":"2026-06-04T12:46:40.619Z","steps":["trace[601570201] 'process raft request' (duration: 189.344369ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:40.619113Z","caller":"traceutil/trace.go:171","msg":"trace[1543671770] linearizableReadLoop","detail":"{readStateIndex:25507; appliedIndex:25504; }","duration":"166.690276ms","start":"2026-06-04T12:46:40.452098Z","end":"2026-06-04T12:46:40.618788Z","steps":["trace[1543671770] 'read index received' (duration: 139.593097ms)","trace[1543671770] 'applied index is now lower than readState.Index' (duration: 27.096589ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:40.619152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.032394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:40.619218Z","caller":"traceutil/trace.go:171","msg":"trace[1251889882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24696; }","duration":"167.139746ms","start":"2026-06-04T12:46:40.452061Z","end":"2026-06-04T12:46:40.6192Z","steps":["trace[1251889882] 'agreement among raft nodes before linearized reading' (duration: 167.013743ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:40.855428Z","caller":"traceutil/trace.go:171","msg":"trace[594856258] linearizableReadLoop","detail":"{readStateIndex:25508; appliedIndex:25507; }","duration":"212.348472ms","start":"2026-06-04T12:46:40.64306Z","end":"2026-06-04T12:46:40.855408Z","steps":["trace[594856258] 'read index received' (duration: 115.033198ms)","trace[594856258] 'applied index is now lower than readState.Index' (duration: 97.314104ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:40.855521Z","caller":"traceutil/trace.go:171","msg":"trace[778660436] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"235.856345ms","start":"2026-06-04T12:46:40.619625Z","end":"2026-06-04T12:46:40.855481Z","steps":["trace[778660436] 'process raft request' (duration: 138.519711ms)","trace[778660436] 'compare' (duration: 97.143029ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:40.855646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.577517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:46:40.855684Z","caller":"traceutil/trace.go:171","msg":"trace[1780561350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24697; }","duration":"212.664599ms","start":"2026-06-04T12:46:40.643011Z","end":"2026-06-04T12:46:40.855676Z","steps":["trace[1780561350] 'agreement among raft nodes before linearized reading' (duration: 212.490955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:50.186868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.300645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:50.187192Z","caller":"traceutil/trace.go:171","msg":"trace[473633916] linearizableReadLoop","detail":"{readStateIndex:25577; appliedIndex:25575; }","duration":"211.988633ms","start":"2026-06-04T12:46:49.975186Z","end":"2026-06-04T12:46:50.187174Z","steps":["trace[473633916] 'read index received' (duration: 210.756473ms)","trace[473633916] 'applied index is now lower than readState.Index' (duration: 1.23116ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:50.187121Z","caller":"traceutil/trace.go:171","msg":"trace[464037434] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"523.564833ms","start":"2026-06-04T12:46:49.663516Z","end":"2026-06-04T12:46:50.187081Z","steps":["trace[464037434] 'process raft request' (duration: 261.969051ms)","trace[464037434] 'compare' (duration: 260.797573ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:50.187367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:49.663494Z","time spent":"523.789278ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:50.187454Z","caller":"traceutil/trace.go:171","msg":"trace[1326294080] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"240.588729ms","start":"2026-06-04T12:46:49.946813Z","end":"2026-06-04T12:46:50.187401Z","steps":["trace[1326294080] 'process raft request' (duration: 240.2233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:50.187662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.445444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:50.18776Z","caller":"traceutil/trace.go:171","msg":"trace[2124651325] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24765; }","duration":"212.588917ms","start":"2026-06-04T12:46:49.975145Z","end":"2026-06-04T12:46:50.187734Z","steps":["trace[2124651325] 'agreement among raft nodes before linearized reading' (duration: 212.390702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:50.728009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.647349ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-06-04T12:46:50.728143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.18864Z","time spent":"539.496295ms","remote":"127.0.0.1:58448","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-04T12:46:51.176286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.284211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:51.176774Z","caller":"traceutil/trace.go:171","msg":"trace[1827875582] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"971.066262ms","start":"2026-06-04T12:46:50.205666Z","end":"2026-06-04T12:46:51.176732Z","steps":["trace[1827875582] 'process raft request' (duration: 647.219869ms)","trace[1827875582] 'compare' (duration: 322.988363ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:51.176923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.205643Z","time spent":"971.203715ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:51.177048Z","caller":"traceutil/trace.go:171","msg":"trace[1499576437] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"802.691544ms","start":"2026-06-04T12:46:50.37434Z","end":"2026-06-04T12:46:51.177032Z","steps":["trace[1499576437] 'process raft request' (duration: 802.071569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.177102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.374312Z","time spent":"802.763586ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:51.177085Z","caller":"traceutil/trace.go:171","msg":"trace[1300667610] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"797.606663ms","start":"2026-06-04T12:46:50.379447Z","end":"2026-06-04T12:46:51.177054Z","steps":["trace[1300667610] 'process raft request' (duration: 797.426848ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.177212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.379423Z","time spent":"797.728586ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:51.177294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.499916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:46:51.177232Z","caller":"traceutil/trace.go:171","msg":"trace[404846412] linearizableReadLoop","detail":"{readStateIndex:25581; appliedIndex:25578; }","duration":"635.373573ms","start":"2026-06-04T12:46:50.541812Z","end":"2026-06-04T12:46:51.177186Z","steps":["trace[404846412] 'read index received' (duration: 311.348985ms)","trace[404846412] 'applied index is now lower than readState.Index' (duration: 324.018998ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:51.177321Z","caller":"traceutil/trace.go:171","msg":"trace[392150172] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24768; }","duration":"635.559438ms","start":"2026-06-04T12:46:50.541754Z","end":"2026-06-04T12:46:51.177314Z","steps":["trace[392150172] 'agreement among raft nodes before linearized reading' (duration: 635.507567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.177339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.541727Z","time spent":"635.607448ms","remote":"127.0.0.1:37008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:46:51.177767Z","caller":"traceutil/trace.go:171","msg":"trace[1236841639] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"448.38442ms","start":"2026-06-04T12:46:50.729362Z","end":"2026-06-04T12:46:51.177747Z","steps":["trace[1236841639] 'process raft request' (duration: 448.318358ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:51.177841Z","caller":"traceutil/trace.go:171","msg":"trace[684281984] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"545.921748ms","start":"2026-06-04T12:46:50.631893Z","end":"2026-06-04T12:46:51.177815Z","steps":["trace[684281984] 'process raft request' (duration: 545.099878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.177876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.729341Z","time spent":"448.481272ms","remote":"127.0.0.1:58448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:51.17798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.631874Z","time spent":"546.024531ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:51.178142Z","caller":"traceutil/trace.go:171","msg":"trace[632488079] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"517.569889ms","start":"2026-06-04T12:46:50.660559Z","end":"2026-06-04T12:46:51.178129Z","steps":["trace[632488079] 'process raft request' (duration: 517.060686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.178214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.660496Z","time spent":"517.683621ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:51.315168Z","caller":"traceutil/trace.go:171","msg":"trace[1864076317] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"409.318384ms","start":"2026-06-04T12:46:50.905821Z","end":"2026-06-04T12:46:51.31514Z","steps":["trace[1864076317] 'process raft request' (duration: 398.451034ms)","trace[1864076317] 'compare' (duration: 10.634954ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:51.315335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.905797Z","time spent":"409.459458ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:51.31546Z","caller":"traceutil/trace.go:171","msg":"trace[1747015250] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"369.378746ms","start":"2026-06-04T12:46:50.946023Z","end":"2026-06-04T12:46:51.315402Z","steps":["trace[1747015250] 'process raft request' (duration: 369.067758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.315604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.946003Z","time spent":"369.525299ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:51.31575Z","caller":"traceutil/trace.go:171","msg":"trace[27762667] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"363.106146ms","start":"2026-06-04T12:46:50.952628Z","end":"2026-06-04T12:46:51.315734Z","steps":["trace[27762667] 'process raft request' (duration: 362.694567ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:51.315751Z","caller":"traceutil/trace.go:171","msg":"trace[975871003] linearizableReadLoop","detail":"{readStateIndex:25587; appliedIndex:25584; }","duration":"138.378489ms","start":"2026-06-04T12:46:51.177337Z","end":"2026-06-04T12:46:51.315715Z","steps":["trace[975871003] 'read index received' (duration: 126.870113ms)","trace[975871003] 'applied index is now lower than readState.Index' (duration: 11.505726ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:51.31581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.952619Z","time spent":"363.160797ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:51.315863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.265776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-04T12:46:51.315932Z","caller":"traceutil/trace.go:171","msg":"trace[655825397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24774; }","duration":"186.378759ms","start":"2026-06-04T12:46:51.129537Z","end":"2026-06-04T12:46:51.315916Z","steps":["trace[655825397] 'agreement among raft nodes before linearized reading' (duration: 186.187964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.316082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.690914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-04T12:46:51.31616Z","caller":"traceutil/trace.go:171","msg":"trace[2141302678] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24774; }","duration":"135.820577ms","start":"2026-06-04T12:46:51.180318Z","end":"2026-06-04T12:46:51.316139Z","steps":["trace[2141302678] 'agreement among raft nodes before linearized reading' (duration: 135.559901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.316081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.932766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:46:51.316373Z","caller":"traceutil/trace.go:171","msg":"trace[502737758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24774; }","duration":"407.271604ms","start":"2026-06-04T12:46:50.909078Z","end":"2026-06-04T12:46:51.31635Z","steps":["trace[502737758] 'agreement among raft nodes before linearized reading' (duration: 406.786222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.316433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:50.909055Z","time spent":"407.359996ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:46:51.868429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.173577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:51.868673Z","caller":"traceutil/trace.go:171","msg":"trace[403909275] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"548.340497ms","start":"2026-06-04T12:46:51.320298Z","end":"2026-06-04T12:46:51.868639Z","steps":["trace[403909275] 'process raft request' (duration: 256.317696ms)","trace[403909275] 'compare' (duration: 289.853649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:51.868896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.32027Z","time spent":"548.518001ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:51.869063Z","caller":"traceutil/trace.go:171","msg":"trace[1583934776] linearizableReadLoop","detail":"{readStateIndex:25589; appliedIndex:25587; }","duration":"548.123322ms","start":"2026-06-04T12:46:51.320654Z","end":"2026-06-04T12:46:51.868778Z","steps":["trace[1583934776] 'read index received' (duration: 256.05463ms)","trace[1583934776] 'applied index is now lower than readState.Index' (duration: 292.067252ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:51.869113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.43581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-04T12:46:51.86918Z","caller":"traceutil/trace.go:171","msg":"trace[432436429] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24776; }","duration":"548.545561ms","start":"2026-06-04T12:46:51.320623Z","end":"2026-06-04T12:46:51.869168Z","steps":["trace[432436429] 'agreement among raft nodes before linearized reading' (duration: 548.300466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.869221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.320607Z","time spent":"548.604823ms","remote":"127.0.0.1:58448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-06-04T12:46:51.869394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.158865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-04T12:46:51.869408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.412883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-06-04T12:46:51.869413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.56503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:51.869453Z","caller":"traceutil/trace.go:171","msg":"trace[463381922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24776; }","duration":"471.257048ms","start":"2026-06-04T12:46:51.398179Z","end":"2026-06-04T12:46:51.869437Z","steps":["trace[463381922] 'agreement among raft nodes before linearized reading' (duration: 471.048853ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:51.869466Z","caller":"traceutil/trace.go:171","msg":"trace[1151192083] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24776; }","duration":"207.508415ms","start":"2026-06-04T12:46:51.661941Z","end":"2026-06-04T12:46:51.86945Z","steps":["trace[1151192083] 'agreement among raft nodes before linearized reading' (duration: 207.334651ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.869455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.879159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-04T12:46:51.869498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.398151Z","time spent":"471.33688ms","remote":"127.0.0.1:58676","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-06-04T12:46:51.86952Z","caller":"traceutil/trace.go:171","msg":"trace[1015743621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24776; }","duration":"387.987971ms","start":"2026-06-04T12:46:51.481515Z","end":"2026-06-04T12:46:51.869503Z","steps":["trace[1015743621] 'agreement among raft nodes before linearized reading' (duration: 387.769856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.869555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.010193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:46:51.869561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.616275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:46:51.869478Z","caller":"traceutil/trace.go:171","msg":"trace[1279468872] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24776; }","duration":"301.662522ms","start":"2026-06-04T12:46:51.567804Z","end":"2026-06-04T12:46:51.869467Z","steps":["trace[1279468872] 'agreement among raft nodes before linearized reading' (duration: 301.569819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.869574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.481492Z","time spent":"388.069054ms","remote":"127.0.0.1:58676","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":"info","ts":"2026-06-04T12:46:51.869599Z","caller":"traceutil/trace.go:171","msg":"trace[2088759424] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24776; }","duration":"151.699117ms","start":"2026-06-04T12:46:51.717889Z","end":"2026-06-04T12:46:51.869588Z","steps":["trace[2088759424] 'agreement among raft nodes before linearized reading' (duration: 151.631496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.869582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.461735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:46:51.869604Z","caller":"traceutil/trace.go:171","msg":"trace[622116266] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24776; }","duration":"172.079005ms","start":"2026-06-04T12:46:51.697495Z","end":"2026-06-04T12:46:51.869574Z","steps":["trace[622116266] 'agreement among raft nodes before linearized reading' (duration: 172.036594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.869622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.567785Z","time spent":"301.822246ms","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:46:51.869642Z","caller":"traceutil/trace.go:171","msg":"trace[1844522159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24776; }","duration":"416.587877ms","start":"2026-06-04T12:46:51.453039Z","end":"2026-06-04T12:46:51.869627Z","steps":["trace[1844522159] 'agreement among raft nodes before linearized reading' (duration: 416.473245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.86968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.453021Z","time spent":"416.652798ms","remote":"127.0.0.1:58400","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-06-04T12:46:51.869922Z","caller":"traceutil/trace.go:171","msg":"trace[988389173] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"548.280495ms","start":"2026-06-04T12:46:51.320591Z","end":"2026-06-04T12:46:51.868872Z","steps":["trace[988389173] 'process raft request' (duration: 548.000849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:51.870618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.320547Z","time spent":"549.934115ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:52.210833Z","caller":"traceutil/trace.go:171","msg":"trace[390400696] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"337.138652ms","start":"2026-06-04T12:46:51.873668Z","end":"2026-06-04T12:46:52.210807Z","steps":["trace[390400696] 'process raft request' (duration: 257.753309ms)","trace[390400696] 'compare' (duration: 79.285071ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:52.210968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.87365Z","time spent":"337.250846ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:52.212737Z","caller":"traceutil/trace.go:171","msg":"trace[126733587] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"336.843396ms","start":"2026-06-04T12:46:51.875863Z","end":"2026-06-04T12:46:52.212706Z","steps":["trace[126733587] 'process raft request' (duration: 336.59584ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:52.212762Z","caller":"traceutil/trace.go:171","msg":"trace[1571941228] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"337.577174ms","start":"2026-06-04T12:46:51.87516Z","end":"2026-06-04T12:46:52.212737Z","steps":["trace[1571941228] 'process raft request' (duration: 337.099443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:52.212899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.875152Z","time spent":"337.667526ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:52.212929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:51.875835Z","time spent":"336.977889ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:46:56.448213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.96917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:46:56.44897Z","caller":"traceutil/trace.go:171","msg":"trace[2011191290] linearizableReadLoop","detail":"{readStateIndex:25681; appliedIndex:25677; }","duration":"190.789644ms","start":"2026-06-04T12:46:56.258163Z","end":"2026-06-04T12:46:56.448953Z","steps":["trace[2011191290] 'read index received' (duration: 63.135274ms)","trace[2011191290] 'applied index is now lower than readState.Index' (duration: 127.65354ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:46:56.449191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.02037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-35bd433a-a160-4d9d-a1f2-b42e7fcb31f3\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-04T12:46:56.449266Z","caller":"traceutil/trace.go:171","msg":"trace[1239243258] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-35bd433a-a160-4d9d-a1f2-b42e7fcb31f3; range_end:; response_count:1; response_revision:24867; }","duration":"191.108201ms","start":"2026-06-04T12:46:56.258126Z","end":"2026-06-04T12:46:56.449234Z","steps":["trace[1239243258] 'agreement among raft nodes before linearized reading' (duration: 190.909517ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:56.449282Z","caller":"traceutil/trace.go:171","msg":"trace[151862218] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"216.858539ms","start":"2026-06-04T12:46:56.232371Z","end":"2026-06-04T12:46:56.449229Z","steps":["trace[151862218] 'process raft request' (duration: 109.797053ms)","trace[151862218] 'compare' (duration: 105.816207ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:46:56.449518Z","caller":"traceutil/trace.go:171","msg":"trace[1384179900] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"215.857575ms","start":"2026-06-04T12:46:56.233649Z","end":"2026-06-04T12:46:56.449507Z","steps":["trace[1384179900] 'process raft request' (duration: 215.171679ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:56.449646Z","caller":"traceutil/trace.go:171","msg":"trace[1807671631] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"213.470008ms","start":"2026-06-04T12:46:56.23616Z","end":"2026-06-04T12:46:56.44963Z","steps":["trace[1807671631] 'process raft request' (duration: 212.755211ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:57.124805Z","caller":"traceutil/trace.go:171","msg":"trace[382793383] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"111.369459ms","start":"2026-06-04T12:46:57.013398Z","end":"2026-06-04T12:46:57.124767Z","steps":["trace[382793383] 'process raft request' (duration: 111.037041ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:59.386419Z","caller":"traceutil/trace.go:171","msg":"trace[2138914832] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"184.714668ms","start":"2026-06-04T12:46:59.201676Z","end":"2026-06-04T12:46:59.38639Z","steps":["trace[2138914832] 'process raft request' (duration: 184.540654ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:59.540653Z","caller":"traceutil/trace.go:171","msg":"trace[1811463448] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"337.677376ms","start":"2026-06-04T12:46:59.202938Z","end":"2026-06-04T12:46:59.540616Z","steps":["trace[1811463448] 'process raft request' (duration: 337.178574ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:59.540767Z","caller":"traceutil/trace.go:171","msg":"trace[1041246475] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"336.789744ms","start":"2026-06-04T12:46:59.203954Z","end":"2026-06-04T12:46:59.540744Z","steps":["trace[1041246475] 'process raft request' (duration: 336.462936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:59.540814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:59.202901Z","time spent":"337.82308ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:59.540825Z","caller":"traceutil/trace.go:171","msg":"trace[1387845659] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"198.204852ms","start":"2026-06-04T12:46:59.342601Z","end":"2026-06-04T12:46:59.540805Z","steps":["trace[1387845659] 'process raft request' (duration: 198.026628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:59.540915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:59.203928Z","time spent":"336.911007ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:59.540953Z","caller":"traceutil/trace.go:171","msg":"trace[15395150] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"335.227578ms","start":"2026-06-04T12:46:59.205716Z","end":"2026-06-04T12:46:59.540944Z","steps":["trace[15395150] 'process raft request' (duration: 334.798278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:46:59.541028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:46:59.205692Z","time spent":"335.279458ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:46:59.541095Z","caller":"traceutil/trace.go:171","msg":"trace[1096584112] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"199.986844ms","start":"2026-06-04T12:46:59.341102Z","end":"2026-06-04T12:46:59.541089Z","steps":["trace[1096584112] 'process raft request' (duration: 199.457172ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:46:59.54115Z","caller":"traceutil/trace.go:171","msg":"trace[527542323] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"198.58008ms","start":"2026-06-04T12:46:59.342564Z","end":"2026-06-04T12:46:59.541144Z","steps":["trace[527542323] 'process raft request' (duration: 198.033218ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:02.629005Z","caller":"traceutil/trace.go:171","msg":"trace[854508595] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"107.326153ms","start":"2026-06-04T12:47:02.521655Z","end":"2026-06-04T12:47:02.628981Z","steps":["trace[854508595] 'process raft request' (duration: 107.17609ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:02.862939Z","caller":"traceutil/trace.go:171","msg":"trace[1849315549] linearizableReadLoop","detail":"{readStateIndex:25746; appliedIndex:25744; }","duration":"341.042307ms","start":"2026-06-04T12:47:02.521868Z","end":"2026-06-04T12:47:02.86291Z","steps":["trace[1849315549] 'read index received' (duration: 107.088738ms)","trace[1849315549] 'applied index is now lower than readState.Index' (duration: 233.952259ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:02.863046Z","caller":"traceutil/trace.go:171","msg":"trace[709084030] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"341.317354ms","start":"2026-06-04T12:47:02.521698Z","end":"2026-06-04T12:47:02.863016Z","steps":["trace[709084030] 'process raft request' (duration: 340.73063ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:02.863071Z","caller":"traceutil/trace.go:171","msg":"trace[1216962212] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"340.76058ms","start":"2026-06-04T12:47:02.522278Z","end":"2026-06-04T12:47:02.863038Z","steps":["trace[1216962212] 'process raft request' (duration: 340.561566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:02.863162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.809543ms","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:6"} {"level":"warn","ts":"2026-06-04T12:47:02.86318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:02.52166Z","time spent":"341.446137ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:47:02.863209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.319754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-06-04T12:47:02.863239Z","caller":"traceutil/trace.go:171","msg":"trace[1716355132] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24931; }","duration":"177.896565ms","start":"2026-06-04T12:47:02.685322Z","end":"2026-06-04T12:47:02.863219Z","steps":["trace[1716355132] 'agreement among raft nodes before linearized reading' (duration: 177.773463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:02.863248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:02.52226Z","time spent":"340.889013ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:02.863248Z","caller":"traceutil/trace.go:171","msg":"trace[55598352] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24931; }","duration":"341.379215ms","start":"2026-06-04T12:47:02.521858Z","end":"2026-06-04T12:47:02.863237Z","steps":["trace[55598352] 'agreement among raft nodes before linearized reading' (duration: 341.18755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:02.863439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:02.521836Z","time spent":"341.590059ms","remote":"127.0.0.1:58610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18594,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-06-04T12:47:14.8131Z","caller":"traceutil/trace.go:171","msg":"trace[169964719] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"126.35769ms","start":"2026-06-04T12:47:14.686719Z","end":"2026-06-04T12:47:14.813077Z","steps":["trace[169964719] 'process raft request' (duration: 126.144224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:14.990975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.039681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8f53d29f-6158-45d0-803b-d5b3805017b0\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-06-04T12:47:14.991086Z","caller":"traceutil/trace.go:171","msg":"trace[1956017785] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8f53d29f-6158-45d0-803b-d5b3805017b0; range_end:; response_count:1; response_revision:25030; }","duration":"171.171134ms","start":"2026-06-04T12:47:14.819889Z","end":"2026-06-04T12:47:14.99106Z","steps":["trace[1956017785] 'range keys from in-memory index tree' (duration: 170.874707ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:15.147195Z","caller":"traceutil/trace.go:171","msg":"trace[1319013874] linearizableReadLoop","detail":"{readStateIndex:25849; appliedIndex:25848; }","duration":"226.768747ms","start":"2026-06-04T12:47:14.920386Z","end":"2026-06-04T12:47:15.147155Z","steps":["trace[1319013874] 'read index received' (duration: 226.637034ms)","trace[1319013874] 'applied index is now lower than readState.Index' (duration: 130.763µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:15.14726Z","caller":"traceutil/trace.go:171","msg":"trace[7641954] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"249.706027ms","start":"2026-06-04T12:47:14.897524Z","end":"2026-06-04T12:47:15.14723Z","steps":["trace[7641954] 'process raft request' (duration: 249.494282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:15.147408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.007252ms","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-06-04T12:47:15.14745Z","caller":"traceutil/trace.go:171","msg":"trace[1077274067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25031; }","duration":"227.065363ms","start":"2026-06-04T12:47:14.920371Z","end":"2026-06-04T12:47:15.147437Z","steps":["trace[1077274067] 'agreement among raft nodes before linearized reading' (duration: 226.92602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:15.147589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.823894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-04T12:47:15.14764Z","caller":"traceutil/trace.go:171","msg":"trace[589272644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25031; }","duration":"215.949428ms","start":"2026-06-04T12:47:14.931677Z","end":"2026-06-04T12:47:15.147626Z","steps":["trace[589272644] 'agreement among raft nodes before linearized reading' (duration: 215.721662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:15.427186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.204936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:47:15.427825Z","caller":"traceutil/trace.go:171","msg":"trace[2089457386] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"275.373692ms","start":"2026-06-04T12:47:15.152419Z","end":"2026-06-04T12:47:15.427793Z","steps":["trace[2089457386] 'process raft request' (duration: 133.468869ms)","trace[2089457386] 'compare' (duration: 141.084654ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:15.427861Z","caller":"traceutil/trace.go:171","msg":"trace[1068794945] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"274.133133ms","start":"2026-06-04T12:47:15.153701Z","end":"2026-06-04T12:47:15.427834Z","steps":["trace[1068794945] 'process raft request' (duration: 273.644881ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:15.42789Z","caller":"traceutil/trace.go:171","msg":"trace[1925436047] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"270.808473ms","start":"2026-06-04T12:47:15.157062Z","end":"2026-06-04T12:47:15.427871Z","steps":["trace[1925436047] 'process raft request' (duration: 270.351942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:15.567605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.11904ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-04T12:47:15.567819Z","caller":"traceutil/trace.go:171","msg":"trace[1009986065] linearizableReadLoop","detail":"{readStateIndex:25853; appliedIndex:25852; }","duration":"117.102678ms","start":"2026-06-04T12:47:15.450698Z","end":"2026-06-04T12:47:15.567801Z","steps":["trace[1009986065] 'read index received' (duration: 40.241µs)","trace[1009986065] 'applied index is now lower than readState.Index' (duration: 117.061427ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:47:15.568786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.084411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:15.568826Z","caller":"traceutil/trace.go:171","msg":"trace[1879663328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25034; }","duration":"118.163683ms","start":"2026-06-04T12:47:15.45065Z","end":"2026-06-04T12:47:15.568813Z","steps":["trace[1879663328] 'agreement among raft nodes before linearized reading' (duration: 118.092451ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:15.569384Z","caller":"traceutil/trace.go:171","msg":"trace[1358294126] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"107.123618ms","start":"2026-06-04T12:47:15.462228Z","end":"2026-06-04T12:47:15.569352Z","steps":["trace[1358294126] 'process raft request' (duration: 105.48442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:15.729909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.296549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:47:15.729923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.555809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:15.729986Z","caller":"traceutil/trace.go:171","msg":"trace[1652015731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25035; }","duration":"159.409772ms","start":"2026-06-04T12:47:15.570559Z","end":"2026-06-04T12:47:15.729969Z","steps":["trace[1652015731] 'range keys from in-memory index tree' (duration: 159.232718ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:15.730001Z","caller":"traceutil/trace.go:171","msg":"trace[74505238] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25035; }","duration":"243.691073ms","start":"2026-06-04T12:47:15.486286Z","end":"2026-06-04T12:47:15.729977Z","steps":["trace[74505238] 'agreement among raft nodes before linearized reading' (duration: 83.031381ms)","trace[74505238] 'count revisions from in-memory index tree' (duration: 160.54233ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:47:15.730041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.811601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-04T12:47:15.73012Z","caller":"traceutil/trace.go:171","msg":"trace[1583581860] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25035; }","duration":"154.929754ms","start":"2026-06-04T12:47:15.575172Z","end":"2026-06-04T12:47:15.730101Z","steps":["trace[1583581860] 'range keys from in-memory index tree' (duration: 154.655878ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:15.929616Z","caller":"traceutil/trace.go:171","msg":"trace[1954196725] transaction","detail":"{read_only:false; response_revision:25036; number_of_response:1; }","duration":"339.176372ms","start":"2026-06-04T12:47:15.590419Z","end":"2026-06-04T12:47:15.929595Z","steps":["trace[1954196725] 'process raft request' (duration: 339.028218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:15.929734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.590393Z","time spent":"339.278904ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:16.209393Z","caller":"traceutil/trace.go:171","msg":"trace[420972073] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"615.399215ms","start":"2026-06-04T12:47:15.593944Z","end":"2026-06-04T12:47:16.209343Z","steps":["trace[420972073] 'process raft request' (duration: 614.317909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.209593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.593938Z","time spent":"615.545448ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:16.209595Z","caller":"traceutil/trace.go:171","msg":"trace[206189563] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"615.62255ms","start":"2026-06-04T12:47:15.593879Z","end":"2026-06-04T12:47:16.209501Z","steps":["trace[206189563] 'process raft request' (duration: 613.446407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.209767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.593861Z","time spent":"615.813335ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:16.210109Z","caller":"traceutil/trace.go:171","msg":"trace[1939571627] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"616.07116ms","start":"2026-06-04T12:47:15.593997Z","end":"2026-06-04T12:47:16.210068Z","steps":["trace[1939571627] 'process raft request' (duration: 615.19921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.210314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.593968Z","time spent":"616.244905ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:16.21124Z","caller":"traceutil/trace.go:171","msg":"trace[758237296] transaction","detail":"{read_only:false; response_revision:25040; number_of_response:1; }","duration":"608.553821ms","start":"2026-06-04T12:47:15.602658Z","end":"2026-06-04T12:47:16.211212Z","steps":["trace[758237296] 'process raft request' (duration: 607.205418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.211318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.60264Z","time spent":"608.637463ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:16.211497Z","caller":"traceutil/trace.go:171","msg":"trace[1728290359] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"607.344752ms","start":"2026-06-04T12:47:15.604105Z","end":"2026-06-04T12:47:16.21145Z","steps":["trace[1728290359] 'process raft request' (duration: 606.653285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.211851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.604097Z","time spent":"607.456394ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:16.212179Z","caller":"traceutil/trace.go:171","msg":"trace[714838082] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"478.36599ms","start":"2026-06-04T12:47:15.733795Z","end":"2026-06-04T12:47:16.212161Z","steps":["trace[714838082] 'process raft request' (duration: 477.549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.212251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.733777Z","time spent":"478.444811ms","remote":"127.0.0.1:58676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:47:16.212513Z","caller":"traceutil/trace.go:171","msg":"trace[1764767655] linearizableReadLoop","detail":"{readStateIndex:25860; appliedIndex:25854; }","duration":"505.003707ms","start":"2026-06-04T12:47:15.707477Z","end":"2026-06-04T12:47:16.212481Z","steps":["trace[1764767655] 'read index received' (duration: 222.031472ms)","trace[1764767655] 'applied index is now lower than readState.Index' (duration: 282.970965ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:16.212663Z","caller":"traceutil/trace.go:171","msg":"trace[938502990] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"294.220634ms","start":"2026-06-04T12:47:15.918349Z","end":"2026-06-04T12:47:16.212569Z","steps":["trace[938502990] 'process raft request' (duration: 293.741993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.212996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.411181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:47:16.213027Z","caller":"traceutil/trace.go:171","msg":"trace[86758558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25043; }","duration":"247.479053ms","start":"2026-06-04T12:47:15.96554Z","end":"2026-06-04T12:47:16.21302Z","steps":["trace[86758558] 'agreement among raft nodes before linearized reading' (duration: 247.057163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.213407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.290711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-04T12:47:16.213475Z","caller":"traceutil/trace.go:171","msg":"trace[875932968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25043; }","duration":"220.376693ms","start":"2026-06-04T12:47:15.993067Z","end":"2026-06-04T12:47:16.213444Z","steps":["trace[875932968] 'agreement among raft nodes before linearized reading' (duration: 219.948303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.214737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.270282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:269044"} {"level":"info","ts":"2026-06-04T12:47:16.214831Z","caller":"traceutil/trace.go:171","msg":"trace[73127401] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:25043; }","duration":"507.348714ms","start":"2026-06-04T12:47:15.707444Z","end":"2026-06-04T12:47:16.214793Z","steps":["trace[73127401] 'agreement among raft nodes before linearized reading' (duration: 505.145361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:16.214884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:47:15.707399Z","time spent":"507.470237ms","remote":"127.0.0.1:58828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":31,"response size":269067,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-06-04T12:47:16.362498Z","caller":"traceutil/trace.go:171","msg":"trace[747575770] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"145.037798ms","start":"2026-06-04T12:47:16.217408Z","end":"2026-06-04T12:47:16.362446Z","steps":["trace[747575770] 'process raft request' (duration: 53.972674ms)","trace[747575770] 'compare' (duration: 90.531221ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:16.362523Z","caller":"traceutil/trace.go:171","msg":"trace[359635206] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"141.328019ms","start":"2026-06-04T12:47:16.221162Z","end":"2026-06-04T12:47:16.36249Z","steps":["trace[359635206] 'process raft request' (duration: 141.017881ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:16.362508Z","caller":"traceutil/trace.go:171","msg":"trace[85613102] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"110.295835ms","start":"2026-06-04T12:47:16.252191Z","end":"2026-06-04T12:47:16.362487Z","steps":["trace[85613102] 'process raft request' (duration: 110.237294ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:16.720332Z","caller":"traceutil/trace.go:171","msg":"trace[819269102] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"275.161857ms","start":"2026-06-04T12:47:16.445141Z","end":"2026-06-04T12:47:16.720303Z","steps":["trace[819269102] 'process raft request' (duration: 274.914081ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:18.343057Z","caller":"traceutil/trace.go:171","msg":"trace[1773411451] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"128.46044ms","start":"2026-06-04T12:47:18.214574Z","end":"2026-06-04T12:47:18.343034Z","steps":["trace[1773411451] 'process raft request' (duration: 128.339157ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:18.376805Z","caller":"traceutil/trace.go:171","msg":"trace[1852988913] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"159.600396ms","start":"2026-06-04T12:47:18.217181Z","end":"2026-06-04T12:47:18.376781Z","steps":["trace[1852988913] 'process raft request' (duration: 159.35107ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:18.376834Z","caller":"traceutil/trace.go:171","msg":"trace[122902815] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"159.441123ms","start":"2026-06-04T12:47:18.217366Z","end":"2026-06-04T12:47:18.376808Z","steps":["trace[122902815] 'process raft request' (duration: 159.30155ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:18.376876Z","caller":"traceutil/trace.go:171","msg":"trace[1923281875] linearizableReadLoop","detail":"{readStateIndex:25876; appliedIndex:25871; }","duration":"124.087205ms","start":"2026-06-04T12:47:18.252779Z","end":"2026-06-04T12:47:18.376866Z","steps":["trace[1923281875] 'read index received' (duration: 90.203033ms)","trace[1923281875] 'applied index is now lower than readState.Index' (duration: 33.883032ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:18.376804Z","caller":"traceutil/trace.go:171","msg":"trace[1484664025] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"157.486956ms","start":"2026-06-04T12:47:18.219297Z","end":"2026-06-04T12:47:18.376784Z","steps":["trace[1484664025] 'process raft request' (duration: 157.413525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:18.376988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.191679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:18.376979Z","caller":"traceutil/trace.go:171","msg":"trace[2067263668] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"155.156361ms","start":"2026-06-04T12:47:18.22177Z","end":"2026-06-04T12:47:18.376926Z","steps":["trace[2067263668] 'process raft request' (duration: 154.978297ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:18.377028Z","caller":"traceutil/trace.go:171","msg":"trace[1148569391] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25057; }","duration":"124.28193ms","start":"2026-06-04T12:47:18.252737Z","end":"2026-06-04T12:47:18.377019Z","steps":["trace[1148569391] 'agreement among raft nodes before linearized reading' (duration: 124.198838ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:20.315999Z","caller":"traceutil/trace.go:171","msg":"trace[1840256234] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"118.257365ms","start":"2026-06-04T12:47:20.197706Z","end":"2026-06-04T12:47:20.315964Z","steps":["trace[1840256234] 'process raft request' (duration: 113.952501ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:28.480888Z","caller":"traceutil/trace.go:171","msg":"trace[1797223189] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"100.404647ms","start":"2026-06-04T12:47:28.380452Z","end":"2026-06-04T12:47:28.480857Z","steps":["trace[1797223189] 'process raft request' (duration: 100.209662ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:28.595279Z","caller":"traceutil/trace.go:171","msg":"trace[620764644] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"183.795075ms","start":"2026-06-04T12:47:28.411448Z","end":"2026-06-04T12:47:28.595243Z","steps":["trace[620764644] 'process raft request' (duration: 183.54475ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:28.595541Z","caller":"traceutil/trace.go:171","msg":"trace[602009734] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"182.847563ms","start":"2026-06-04T12:47:28.412676Z","end":"2026-06-04T12:47:28.595523Z","steps":["trace[602009734] 'process raft request' (duration: 182.503135ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:28.595597Z","caller":"traceutil/trace.go:171","msg":"trace[1524079954] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"182.68695ms","start":"2026-06-04T12:47:28.412872Z","end":"2026-06-04T12:47:28.595559Z","steps":["trace[1524079954] 'process raft request' (duration: 182.543037ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:28.595667Z","caller":"traceutil/trace.go:171","msg":"trace[6842499] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"181.48162ms","start":"2026-06-04T12:47:28.414171Z","end":"2026-06-04T12:47:28.595652Z","steps":["trace[6842499] 'process raft request' (duration: 181.342587ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:28.595651Z","caller":"traceutil/trace.go:171","msg":"trace[1818507515] linearizableReadLoop","detail":"{readStateIndex:25965; appliedIndex:25960; }","duration":"144.447033ms","start":"2026-06-04T12:47:28.451177Z","end":"2026-06-04T12:47:28.595624Z","steps":["trace[1818507515] 'read index received' (duration: 29.577749ms)","trace[1818507515] 'applied index is now lower than readState.Index' (duration: 114.867833ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:47:28.595829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.632027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:28.595868Z","caller":"traceutil/trace.go:171","msg":"trace[2121756858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25143; }","duration":"144.71834ms","start":"2026-06-04T12:47:28.451138Z","end":"2026-06-04T12:47:28.595856Z","steps":["trace[2121756858] 'agreement among raft nodes before linearized reading' (duration: 144.620277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:28.595915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.197033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-04T12:47:28.595959Z","caller":"traceutil/trace.go:171","msg":"trace[117664135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25143; }","duration":"140.276964ms","start":"2026-06-04T12:47:28.455666Z","end":"2026-06-04T12:47:28.595943Z","steps":["trace[117664135] 'agreement among raft nodes before linearized reading' (duration: 140.116969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:28.596167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.593469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:47:28.5962Z","caller":"traceutil/trace.go:171","msg":"trace[322227735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25143; }","duration":"137.657771ms","start":"2026-06-04T12:47:28.458531Z","end":"2026-06-04T12:47:28.596188Z","steps":["trace[322227735] 'agreement among raft nodes before linearized reading' (duration: 137.569359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:28.807946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.702994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:28.808029Z","caller":"traceutil/trace.go:171","msg":"trace[1274955456] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25145; }","duration":"100.823397ms","start":"2026-06-04T12:47:28.707187Z","end":"2026-06-04T12:47:28.80801Z","steps":["trace[1274955456] 'range keys from in-memory index tree' (duration: 100.634713ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:28.992613Z","caller":"traceutil/trace.go:171","msg":"trace[1156307366] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"100.606832ms","start":"2026-06-04T12:47:28.891973Z","end":"2026-06-04T12:47:28.992579Z","steps":["trace[1156307366] 'process raft request' (duration: 64.211159ms)","trace[1156307366] 'compare' (duration: 36.220039ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:30.905749Z","caller":"traceutil/trace.go:171","msg":"trace[716833164] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"101.672207ms","start":"2026-06-04T12:47:30.804045Z","end":"2026-06-04T12:47:30.905717Z","steps":["trace[716833164] 'process raft request' (duration: 27.580611ms)","trace[716833164] 'compare' (duration: 73.83922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:47:39.255115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.919986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:39.255206Z","caller":"traceutil/trace.go:171","msg":"trace[195281845] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25222; }","duration":"110.054369ms","start":"2026-06-04T12:47:39.14513Z","end":"2026-06-04T12:47:39.255184Z","steps":["trace[195281845] 'range keys from in-memory index tree' (duration: 109.842585ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:39.794565Z","caller":"traceutil/trace.go:171","msg":"trace[2029236317] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"145.762904ms","start":"2026-06-04T12:47:39.648777Z","end":"2026-06-04T12:47:39.79454Z","steps":["trace[2029236317] 'process raft request' (duration: 145.56393ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:39.970814Z","caller":"traceutil/trace.go:171","msg":"trace[1931232254] linearizableReadLoop","detail":"{readStateIndex:26048; appliedIndex:26047; }","duration":"176.276616ms","start":"2026-06-04T12:47:39.794517Z","end":"2026-06-04T12:47:39.970794Z","steps":["trace[1931232254] 'read index received' (duration: 134.057544ms)","trace[1931232254] 'applied index is now lower than readState.Index' (duration: 42.218222ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:39.970869Z","caller":"traceutil/trace.go:171","msg":"trace[1146987575] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"244.42407ms","start":"2026-06-04T12:47:39.726414Z","end":"2026-06-04T12:47:39.970838Z","steps":["trace[1146987575] 'process raft request' (duration: 202.148167ms)","trace[1146987575] 'compare' (duration: 42.13977ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:47:39.970998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.073242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:47:39.971065Z","caller":"traceutil/trace.go:171","msg":"trace[415035218] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25224; }","duration":"229.209605ms","start":"2026-06-04T12:47:39.74184Z","end":"2026-06-04T12:47:39.97105Z","steps":["trace[415035218] 'agreement among raft nodes before linearized reading' (duration: 229.039321ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:39.987331Z","caller":"traceutil/trace.go:171","msg":"trace[1145090519] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"187.032394ms","start":"2026-06-04T12:47:39.80028Z","end":"2026-06-04T12:47:39.987313Z","steps":["trace[1145090519] 'process raft request' (duration: 186.8622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:39.987418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.208275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-06-04T12:47:39.987511Z","caller":"traceutil/trace.go:171","msg":"trace[1183258602] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:25225; }","duration":"186.323537ms","start":"2026-06-04T12:47:39.801157Z","end":"2026-06-04T12:47:39.98748Z","steps":["trace[1183258602] 'agreement among raft nodes before linearized reading' (duration: 186.105092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:39.987652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.790007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:47:39.987681Z","caller":"traceutil/trace.go:171","msg":"trace[895827095] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25225; }","duration":"100.860208ms","start":"2026-06-04T12:47:39.886814Z","end":"2026-06-04T12:47:39.987674Z","steps":["trace[895827095] 'agreement among raft nodes before linearized reading' (duration: 100.756115ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:40.207794Z","caller":"traceutil/trace.go:171","msg":"trace[1676631487] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"128.577672ms","start":"2026-06-04T12:47:40.079166Z","end":"2026-06-04T12:47:40.207743Z","steps":["trace[1676631487] 'process raft request' (duration: 55.165832ms)","trace[1676631487] 'compare' (duration: 73.279417ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:40.431599Z","caller":"traceutil/trace.go:171","msg":"trace[2097604969] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"154.510595ms","start":"2026-06-04T12:47:40.277056Z","end":"2026-06-04T12:47:40.431567Z","steps":["trace[2097604969] 'process raft request' (duration: 65.677076ms)","trace[2097604969] 'compare' (duration: 88.694705ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:40.76363Z","caller":"traceutil/trace.go:171","msg":"trace[937099710] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"129.455384ms","start":"2026-06-04T12:47:40.634137Z","end":"2026-06-04T12:47:40.763592Z","steps":["trace[937099710] 'process raft request' (duration: 73.914762ms)","trace[937099710] 'compare' (duration: 55.353397ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:40.779244Z","caller":"traceutil/trace.go:171","msg":"trace[1178162921] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"143.556123ms","start":"2026-06-04T12:47:40.635662Z","end":"2026-06-04T12:47:40.779218Z","steps":["trace[1178162921] 'process raft request' (duration: 143.470631ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:40.779245Z","caller":"traceutil/trace.go:171","msg":"trace[19224190] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"144.552276ms","start":"2026-06-04T12:47:40.634665Z","end":"2026-06-04T12:47:40.779217Z","steps":["trace[19224190] 'process raft request' (duration: 144.294179ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:40.779394Z","caller":"traceutil/trace.go:171","msg":"trace[1664510276] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"142.903047ms","start":"2026-06-04T12:47:40.636478Z","end":"2026-06-04T12:47:40.779381Z","steps":["trace[1664510276] 'process raft request' (duration: 142.699232ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:40.944249Z","caller":"traceutil/trace.go:171","msg":"trace[1133529750] linearizableReadLoop","detail":"{readStateIndex:26059; appliedIndex:26057; }","duration":"148.263694ms","start":"2026-06-04T12:47:40.795964Z","end":"2026-06-04T12:47:40.944227Z","steps":["trace[1133529750] 'read index received' (duration: 11.164288ms)","trace[1133529750] 'applied index is now lower than readState.Index' (duration: 137.097886ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:47:40.944369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.409089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:40.944338Z","caller":"traceutil/trace.go:171","msg":"trace[1148733913] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"159.705749ms","start":"2026-06-04T12:47:40.784586Z","end":"2026-06-04T12:47:40.944292Z","steps":["trace[1148733913] 'process raft request' (duration: 90.877149ms)","trace[1148733913] 'compare' (duration: 68.659686ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:40.944405Z","caller":"traceutil/trace.go:171","msg":"trace[1489892591] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:25234; }","duration":"148.46731ms","start":"2026-06-04T12:47:40.795925Z","end":"2026-06-04T12:47:40.944392Z","steps":["trace[1489892591] 'agreement among raft nodes before linearized reading' (duration: 148.387928ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:41.156022Z","caller":"traceutil/trace.go:171","msg":"trace[2043767446] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"107.476487ms","start":"2026-06-04T12:47:41.048517Z","end":"2026-06-04T12:47:41.155993Z","steps":["trace[2043767446] 'process raft request' (duration: 100.044158ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:41.221574Z","caller":"traceutil/trace.go:171","msg":"trace[1438682328] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"153.668804ms","start":"2026-06-04T12:47:41.06788Z","end":"2026-06-04T12:47:41.221549Z","steps":["trace[1438682328] 'process raft request' (duration: 153.543581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:45.679462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.514475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:45.679569Z","caller":"traceutil/trace.go:171","msg":"trace[1404077924] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:25265; }","duration":"267.669328ms","start":"2026-06-04T12:47:45.411874Z","end":"2026-06-04T12:47:45.679544Z","steps":["trace[1404077924] 'count revisions from in-memory index tree' (duration: 267.454293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:45.68003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.562381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-06-04T12:47:45.680064Z","caller":"traceutil/trace.go:171","msg":"trace[413863448] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25265; }","duration":"236.617403ms","start":"2026-06-04T12:47:45.443434Z","end":"2026-06-04T12:47:45.680051Z","steps":["trace[413863448] 'range keys from in-memory index tree' (duration: 236.428608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:47:45.680093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.649947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:45.680189Z","caller":"traceutil/trace.go:171","msg":"trace[1461238001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25265; }","duration":"227.799532ms","start":"2026-06-04T12:47:45.452371Z","end":"2026-06-04T12:47:45.68017Z","steps":["trace[1461238001] 'range keys from in-memory index tree' (duration: 227.554875ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:46.070151Z","caller":"traceutil/trace.go:171","msg":"trace[1213864244] linearizableReadLoop","detail":"{readStateIndex:26094; appliedIndex:26093; }","duration":"104.570566ms","start":"2026-06-04T12:47:45.965554Z","end":"2026-06-04T12:47:46.070124Z","steps":["trace[1213864244] 'read index received' (duration: 36.166217ms)","trace[1213864244] 'applied index is now lower than readState.Index' (duration: 68.403359ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:47:46.070222Z","caller":"traceutil/trace.go:171","msg":"trace[1547803648] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"204.493962ms","start":"2026-06-04T12:47:45.8657Z","end":"2026-06-04T12:47:46.070194Z","steps":["trace[1547803648] 'process raft request' (duration: 136.008111ms)","trace[1547803648] 'compare' (duration: 68.272036ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:47:46.070348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.760481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:47:46.070423Z","caller":"traceutil/trace.go:171","msg":"trace[1332557305] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25268; }","duration":"104.891864ms","start":"2026-06-04T12:47:45.96551Z","end":"2026-06-04T12:47:46.070402Z","steps":["trace[1332557305] 'agreement among raft nodes before linearized reading' (duration: 104.74034ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:47:56.764642Z","caller":"traceutil/trace.go:171","msg":"trace[1681085559] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"133.224315ms","start":"2026-06-04T12:47:56.631397Z","end":"2026-06-04T12:47:56.764621Z","steps":["trace[1681085559] 'process raft request' (duration: 133.102372ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:02.35418Z","caller":"traceutil/trace.go:171","msg":"trace[480599573] linearizableReadLoop","detail":"{readStateIndex:26228; appliedIndex:26227; }","duration":"149.516005ms","start":"2026-06-04T12:48:02.204636Z","end":"2026-06-04T12:48:02.354152Z","steps":["trace[480599573] 'read index received' (duration: 74.357593ms)","trace[480599573] 'applied index is now lower than readState.Index' (duration: 75.157392ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:02.354401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.75186ms","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-06-04T12:48:02.354433Z","caller":"traceutil/trace.go:171","msg":"trace[1093286170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25399; }","duration":"149.828572ms","start":"2026-06-04T12:48:02.204593Z","end":"2026-06-04T12:48:02.354422Z","steps":["trace[1093286170] 'agreement among raft nodes before linearized reading' (duration: 149.672638ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:02.354418Z","caller":"traceutil/trace.go:171","msg":"trace[2134623175] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"158.954561ms","start":"2026-06-04T12:48:02.195315Z","end":"2026-06-04T12:48:02.354269Z","steps":["trace[2134623175] 'process raft request' (duration: 83.689987ms)","trace[2134623175] 'compare' (duration: 74.981898ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:02.395779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.032903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:269069"} {"level":"info","ts":"2026-06-04T12:48:02.395867Z","caller":"traceutil/trace.go:171","msg":"trace[806056439] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:25400; }","duration":"164.168177ms","start":"2026-06-04T12:48:02.23168Z","end":"2026-06-04T12:48:02.395848Z","steps":["trace[806056439] 'agreement among raft nodes before linearized reading' (duration: 163.624274ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:02.602845Z","caller":"traceutil/trace.go:171","msg":"trace[1476992606] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26229; }","duration":"151.566144ms","start":"2026-06-04T12:48:02.45125Z","end":"2026-06-04T12:48:02.602816Z","steps":["trace[1476992606] 'read index received' (duration: 53.701077ms)","trace[1476992606] 'applied index is now lower than readState.Index' (duration: 97.864377ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:02.602978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.710407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:02.603003Z","caller":"traceutil/trace.go:171","msg":"trace[2018284137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25401; }","duration":"151.778649ms","start":"2026-06-04T12:48:02.451216Z","end":"2026-06-04T12:48:02.602994Z","steps":["trace[2018284137] 'agreement among raft nodes before linearized reading' (duration: 151.694727ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:02.603031Z","caller":"traceutil/trace.go:171","msg":"trace[1046298897] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"244.82176ms","start":"2026-06-04T12:48:02.358165Z","end":"2026-06-04T12:48:02.602987Z","steps":["trace[1046298897] 'process raft request' (duration: 146.767729ms)","trace[1046298897] 'compare' (duration: 97.690542ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:02.865065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.639505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:02.865144Z","caller":"traceutil/trace.go:171","msg":"trace[1360652904] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25401; }","duration":"141.762699ms","start":"2026-06-04T12:48:02.723363Z","end":"2026-06-04T12:48:02.865126Z","steps":["trace[1360652904] 'count revisions from in-memory index tree' (duration: 141.564084ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:03.025441Z","caller":"traceutil/trace.go:171","msg":"trace[327793311] linearizableReadLoop","detail":"{readStateIndex:26235; appliedIndex:26233; }","duration":"104.248399ms","start":"2026-06-04T12:48:02.921177Z","end":"2026-06-04T12:48:03.025425Z","steps":["trace[327793311] 'read index received' (duration: 17.021648ms)","trace[327793311] 'applied index is now lower than readState.Index' (duration: 87.225971ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:03.025609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.426473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-04T12:48:03.025632Z","caller":"traceutil/trace.go:171","msg":"trace[27318997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25406; }","duration":"104.497165ms","start":"2026-06-04T12:48:02.921128Z","end":"2026-06-04T12:48:03.025625Z","steps":["trace[27318997] 'agreement among raft nodes before linearized reading' (duration: 104.377342ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:03.025837Z","caller":"traceutil/trace.go:171","msg":"trace[180163682] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"150.452778ms","start":"2026-06-04T12:48:02.875376Z","end":"2026-06-04T12:48:03.025829Z","steps":["trace[180163682] 'process raft request' (duration: 149.912445ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:23.896309Z","caller":"traceutil/trace.go:171","msg":"trace[1913559687] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"111.436322ms","start":"2026-06-04T12:48:23.784848Z","end":"2026-06-04T12:48:23.896284Z","steps":["trace[1913559687] 'process raft request' (duration: 111.214497ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:07.223434Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23203} {"level":"info","ts":"2026-06-04T12:49:07.352419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23203,"took":"125.679852ms","hash":4006364621,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":36028416,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-04T12:49:07.35251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4006364621,"revision":23203,"compact-revision":20079} {"level":"info","ts":"2026-06-04T12:49:32.964814Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a1c.wal"} {"level":"info","ts":"2026-06-04T12:49:39.291742Z","caller":"traceutil/trace.go:171","msg":"trace[1029295774] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"109.190867ms","start":"2026-06-04T12:49:39.182529Z","end":"2026-06-04T12:49:39.29172Z","steps":["trace[1029295774] 'process raft request' (duration: 109.063094ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:54:07.229218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26077} {"level":"info","ts":"2026-06-04T12:54:07.369391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26077,"took":"134.70868ms","hash":1287135550,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":34209792,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-04T12:54:07.369467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1287135550,"revision":26077,"compact-revision":23203} {"level":"info","ts":"2026-06-04T12:54:52.454411Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:54:52.459804Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-04T12:54:52.459914Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-04T12:59:07.237726Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28747} {"level":"info","ts":"2026-06-04T12:59:07.374076Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28747,"took":"131.556315ms","hash":1857091861,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-04T12:59:07.374132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1857091861,"revision":28747,"compact-revision":26077} {"level":"info","ts":"2026-06-04T13:04:07.243422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30964} {"level":"info","ts":"2026-06-04T13:04:07.354092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30964,"took":"106.28082ms","hash":1798851364,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":31444992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-04T13:04:07.354163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1798851364,"revision":30964,"compact-revision":28747} {"level":"info","ts":"2026-06-04T13:09:07.254893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33301} {"level":"info","ts":"2026-06-04T13:09:07.41777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33301,"took":"157.831881ms","hash":1232984957,"current-db-size-bytes":59174912,"current-db-size":"59 MB","current-db-size-in-use-bytes":32260096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T13:09:07.417845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1232984957,"revision":33301,"compact-revision":30964} {"level":"info","ts":"2026-06-04T13:14:07.261082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35628} {"level":"info","ts":"2026-06-04T13:14:07.408777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35628,"took":"143.420633ms","hash":505533413,"current-db-size-bytes":60911616,"current-db-size":"61 MB","current-db-size-in-use-bytes":31084544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-04T13:14:07.40885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":505533413,"revision":35628,"compact-revision":33301} {"level":"info","ts":"2026-06-04T13:16:05.060908Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T13:16:05.066557Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-04T13:16:05.066755Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-04T13:19:07.266566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37857} {"level":"info","ts":"2026-06-04T13:19:07.378806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37857,"took":"109.431967ms","hash":1537067756,"current-db-size-bytes":60911616,"current-db-size":"61 MB","current-db-size-in-use-bytes":31932416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T13:19:07.378867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1537067756,"revision":37857,"compact-revision":35628}