{"level":"warn","ts":"2026-09-30T02:29:33.160305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-30T02:29:33.160683Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.92: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://162.253.55.92:2380","--initial-cluster=instance=https://162.253.55.92:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.92:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.92: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-09-30T02:29:33.160898Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-30T02:29:33.160932Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-30T02:29:33.160973Z","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-09-30T02:29:33.162718Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"]} {"level":"info","ts":"2026-09-30T02:29:33.162871Z","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://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.92: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-09-30T02:29:33.168463Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.491438ms"} {"level":"info","ts":"2026-09-30T02:29:33.175946Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39a4f14f6507253d","cluster-id":"22d06cab78b8ace5"} {"level":"info","ts":"2026-09-30T02:29:33.176078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=()"} {"level":"info","ts":"2026-09-30T02:29:33.17612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 0"} {"level":"info","ts":"2026-09-30T02:29:33.176144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39a4f14f6507253d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-30T02:29:33.176158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became follower at term 1"} {"level":"info","ts":"2026-09-30T02:29:33.176217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"warn","ts":"2026-09-30T02:29:33.183219Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-30T02:29:33.18515Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-30T02:29:33.186897Z","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-09-30T02:29:33.19049Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39a4f14f6507253d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-30T02:29:33.19071Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39a4f14f6507253d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-30T02:29:33.190849Z","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-09-30T02:29:33.191009Z","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-09-30T02:29:33.191035Z","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-09-30T02:29:33.194111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d switched to configuration voters=(4153710079642117437)"} {"level":"info","ts":"2026-09-30T02:29:33.194292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","added-peer-id":"39a4f14f6507253d","added-peer-peer-urls":["https://162.253.55.92:2380"]} {"level":"info","ts":"2026-09-30T02:29:33.197409Z","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-09-30T02:29:33.197557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-30T02:29:33.197667Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.92:2380"} {"level":"info","ts":"2026-09-30T02:29:33.197831Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39a4f14f6507253d","initial-advertise-peer-urls":["https://162.253.55.92:2380"],"listen-peer-urls":["https://162.253.55.92:2380"],"advertise-client-urls":["https://162.253.55.92:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.92:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-30T02:29:33.197901Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-30T02:29:34.177241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d is starting a new election at term 1"} {"level":"info","ts":"2026-09-30T02:29:34.177318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-30T02:29:34.177345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgPreVoteResp from 39a4f14f6507253d at term 1"} {"level":"info","ts":"2026-09-30T02:29:34.177367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became candidate at term 2"} {"level":"info","ts":"2026-09-30T02:29:34.177378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d received MsgVoteResp from 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-30T02:29:34.177394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39a4f14f6507253d became leader at term 2"} {"level":"info","ts":"2026-09-30T02:29:34.177406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39a4f14f6507253d elected leader 39a4f14f6507253d at term 2"} {"level":"info","ts":"2026-09-30T02:29:34.1786Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:29:34.17973Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39a4f14f6507253d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.92:2379]}","request-path":"/0/members/39a4f14f6507253d/attributes","cluster-id":"22d06cab78b8ace5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-30T02:29:34.17975Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-30T02:29:34.179806Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-30T02:29:34.180062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-30T02:29:34.180094Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-30T02:29:34.180551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"22d06cab78b8ace5","local-member-id":"39a4f14f6507253d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:29:34.180734Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:29:34.180787Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-30T02:29:34.183915Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.92:2379"} {"level":"info","ts":"2026-09-30T02:29:34.183934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-30T02:30:22.046281Z","caller":"traceutil/trace.go:171","msg":"trace[1727841519] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"308.083814ms","start":"2026-09-30T02:30:21.738157Z","end":"2026-09-30T02:30:22.046241Z","steps":["trace[1727841519] 'process raft request' (duration: 307.459729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:30:22.046912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:30:21.738067Z","time spent":"308.32252ms","remote":"127.0.0.1:57514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:30:22.246173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.622572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:30:22.24633Z","caller":"traceutil/trace.go:171","msg":"trace[1953155860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"139.884187ms","start":"2026-09-30T02:30:22.106421Z","end":"2026-09-30T02:30:22.246305Z","steps":["trace[1953155860] 'range keys from in-memory index tree' (duration: 139.439727ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:30:22.246849Z","caller":"traceutil/trace.go:171","msg":"trace[634116685] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"123.516765ms","start":"2026-09-30T02:30:22.123286Z","end":"2026-09-30T02:30:22.246803Z","steps":["trace[634116685] 'process raft request' (duration: 117.47649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:30:22.745637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.686674ms","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-09-30T02:30:22.745754Z","caller":"traceutil/trace.go:171","msg":"trace[889214147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"232.861979ms","start":"2026-09-30T02:30:22.512856Z","end":"2026-09-30T02:30:22.745718Z","steps":["trace[889214147] 'range keys from in-memory index tree' (duration: 232.50227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:30:22.745745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.498643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-30T02:30:22.746042Z","caller":"traceutil/trace.go:171","msg":"trace[241347085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"245.82682ms","start":"2026-09-30T02:30:22.500138Z","end":"2026-09-30T02:30:22.745965Z","steps":["trace[241347085] 'range keys from in-memory index tree' (duration: 245.358439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:31:56.852028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.291837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-09-30T02:31:56.852133Z","caller":"traceutil/trace.go:171","msg":"trace[1360522153] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1831; }","duration":"119.499451ms","start":"2026-09-30T02:31:56.732611Z","end":"2026-09-30T02:31:56.85211Z","steps":["trace[1360522153] 'range keys from in-memory index tree' (duration: 118.997969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:31:56.852177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.301672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-09-30T02:31:56.852284Z","caller":"traceutil/trace.go:171","msg":"trace[1666619241] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1831; }","duration":"118.442886ms","start":"2026-09-30T02:31:56.733814Z","end":"2026-09-30T02:31:56.852257Z","steps":["trace[1666619241] 'range keys from in-memory index tree' (duration: 117.848272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:31:57.176682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.022417ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-30T02:31:57.177216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.921125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:31:57.177434Z","caller":"traceutil/trace.go:171","msg":"trace[1992841295] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1890; }","duration":"233.109221ms","start":"2026-09-30T02:31:56.944307Z","end":"2026-09-30T02:31:57.177417Z","steps":["trace[1992841295] 'read index received' (duration: 9.570951ms)","trace[1992841295] 'applied index is now lower than readState.Index' (duration: 223.53683ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:31:57.177539Z","caller":"traceutil/trace.go:171","msg":"trace[1986188506] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"312.183687ms","start":"2026-09-30T02:31:56.865346Z","end":"2026-09-30T02:31:57.177529Z","steps":["trace[1986188506] 'process raft request' (duration: 311.975282ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:31:57.176826Z","caller":"traceutil/trace.go:171","msg":"trace[1811361388] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1832; }","duration":"218.193552ms","start":"2026-09-30T02:31:56.958614Z","end":"2026-09-30T02:31:57.176807Z","steps":["trace[1811361388] 'range keys from in-memory index tree' (duration: 218.001547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:31:57.177616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:31:56.865304Z","time spent":"312.268919ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T02:31:57.177615Z","caller":"traceutil/trace.go:171","msg":"trace[2126048580] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"320.654081ms","start":"2026-09-30T02:31:56.856929Z","end":"2026-09-30T02:31:57.177583Z","steps":["trace[2126048580] 'process raft request' (duration: 96.976798ms)","trace[2126048580] 'compare' (duration: 222.635958ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:31:57.177795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:31:56.856907Z","time spent":"320.791575ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:31:57.17823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.867619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-30T02:31:57.178443Z","caller":"traceutil/trace.go:171","msg":"trace[452328075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1834; }","duration":"234.159716ms","start":"2026-09-30T02:31:56.944232Z","end":"2026-09-30T02:31:57.178392Z","steps":["trace[452328075] 'agreement among raft nodes before linearized reading' (duration: 233.567331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:31:57.178302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.447671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:31:57.178606Z","caller":"traceutil/trace.go:171","msg":"trace[879394806] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1834; }","duration":"197.769188ms","start":"2026-09-30T02:31:56.980816Z","end":"2026-09-30T02:31:57.178585Z","steps":["trace[879394806] 'agreement among raft nodes before linearized reading' (duration: 197.42779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:31:57.178868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.815592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c810a497-78b6-4c86-b86c-d072a9b36e01\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-30T02:31:57.179217Z","caller":"traceutil/trace.go:171","msg":"trace[235762982] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c810a497-78b6-4c86-b86c-d072a9b36e01; range_end:; response_count:1; response_revision:1834; }","duration":"201.024487ms","start":"2026-09-30T02:31:56.977958Z","end":"2026-09-30T02:31:57.178982Z","steps":["trace[235762982] 'agreement among raft nodes before linearized reading' (duration: 200.265979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:33:23.130512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.47021ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-09-30T02:33:56.962915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.007684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:33:56.963326Z","caller":"traceutil/trace.go:171","msg":"trace[1937322530] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"261.084765ms","start":"2026-09-30T02:33:56.702203Z","end":"2026-09-30T02:33:56.963288Z","steps":["trace[1937322530] 'process raft request' (duration: 113.57098ms)","trace[1937322530] 'compare' (duration: 146.864289ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:36:27.008092Z","caller":"traceutil/trace.go:171","msg":"trace[713319701] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"151.779038ms","start":"2026-09-30T02:36:26.856217Z","end":"2026-09-30T02:36:27.007996Z","steps":["trace[713319701] 'process raft request' (duration: 72.207931ms)","trace[713319701] 'compare' (duration: 79.366902ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:36:27.008208Z","caller":"traceutil/trace.go:171","msg":"trace[1247903344] transaction","detail":"{read_only:false; response_revision:4585; number_of_response:1; }","duration":"148.683133ms","start":"2026-09-30T02:36:26.8595Z","end":"2026-09-30T02:36:27.008183Z","steps":["trace[1247903344] 'process raft request' (duration: 148.602051ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:36:27.008353Z","caller":"traceutil/trace.go:171","msg":"trace[1091083696] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"148.912339ms","start":"2026-09-30T02:36:26.85937Z","end":"2026-09-30T02:36:27.008283Z","steps":["trace[1091083696] 'process raft request' (duration: 148.619052ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:36:27.00876Z","caller":"traceutil/trace.go:171","msg":"trace[1553197449] transaction","detail":"{read_only:false; response_revision:4586; number_of_response:1; }","duration":"149.050003ms","start":"2026-09-30T02:36:26.85969Z","end":"2026-09-30T02:36:27.00874Z","steps":["trace[1553197449] 'process raft request' (duration: 148.453298ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:36:27.008803Z","caller":"traceutil/trace.go:171","msg":"trace[1468176193] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"146.993443ms","start":"2026-09-30T02:36:26.86179Z","end":"2026-09-30T02:36:27.008784Z","steps":["trace[1468176193] 'process raft request' (duration: 146.837459ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:36:27.009664Z","caller":"traceutil/trace.go:171","msg":"trace[470550787] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"134.387971ms","start":"2026-09-30T02:36:26.875259Z","end":"2026-09-30T02:36:27.009647Z","steps":["trace[470550787] 'process raft request' (duration: 133.459109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:29.819703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.141759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:36:29.81985Z","caller":"traceutil/trace.go:171","msg":"trace[1640183118] linearizableReadLoop","detail":"{readStateIndex:4824; appliedIndex:4823; }","duration":"219.096732ms","start":"2026-09-30T02:36:29.600731Z","end":"2026-09-30T02:36:29.819828Z","steps":["trace[1640183118] 'read index received' (duration: 68.705427ms)","trace[1640183118] 'applied index is now lower than readState.Index' (duration: 150.389185ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:36:29.819974Z","caller":"traceutil/trace.go:171","msg":"trace[390836657] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"264.970781ms","start":"2026-09-30T02:36:29.554989Z","end":"2026-09-30T02:36:29.81996Z","steps":["trace[390836657] 'process raft request' (duration: 114.459713ms)","trace[390836657] 'compare' (duration: 149.78027ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:36:29.820408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.067192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd40e54f-8d55-40ab-b763-9887ed1474c5\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-30T02:36:29.820507Z","caller":"traceutil/trace.go:171","msg":"trace[9530415] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd40e54f-8d55-40ab-b763-9887ed1474c5; range_end:; response_count:1; response_revision:4626; }","duration":"121.212086ms","start":"2026-09-30T02:36:29.699275Z","end":"2026-09-30T02:36:29.820487Z","steps":["trace[9530415] 'agreement among raft nodes before linearized reading' (duration: 120.888318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:29.820489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.723116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cd40e54f-8d55-40ab-b763-9887ed1474c5\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-30T02:36:29.820629Z","caller":"traceutil/trace.go:171","msg":"trace[672928519] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cd40e54f-8d55-40ab-b763-9887ed1474c5; range_end:; response_count:1; response_revision:4626; }","duration":"219.89454ms","start":"2026-09-30T02:36:29.600712Z","end":"2026-09-30T02:36:29.820607Z","steps":["trace[672928519] 'agreement among raft nodes before linearized reading' (duration: 219.46558ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:36:31.90912Z","caller":"traceutil/trace.go:171","msg":"trace[1686588722] transaction","detail":"{read_only:false; response_revision:4664; number_of_response:1; }","duration":"152.29266ms","start":"2026-09-30T02:36:31.756784Z","end":"2026-09-30T02:36:31.909077Z","steps":["trace[1686588722] 'process raft request' (duration: 127.954907ms)","trace[1686588722] 'compare' (duration: 24.066937ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:36:48.286479Z","caller":"traceutil/trace.go:171","msg":"trace[956178864] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5198; }","duration":"259.022538ms","start":"2026-09-30T02:36:48.027429Z","end":"2026-09-30T02:36:48.286452Z","steps":["trace[956178864] 'read index received' (duration: 258.955926ms)","trace[956178864] 'applied index is now lower than readState.Index' (duration: 65.542µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:36:48.286556Z","caller":"traceutil/trace.go:171","msg":"trace[570725601] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"328.838321ms","start":"2026-09-30T02:36:47.957675Z","end":"2026-09-30T02:36:48.286514Z","steps":["trace[570725601] 'process raft request' (duration: 328.579015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:48.28675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.305575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"warn","ts":"2026-09-30T02:36:48.286751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:47.957653Z","time spent":"328.987315ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8035,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T02:36:48.286797Z","caller":"traceutil/trace.go:171","msg":"trace[843857272] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4988; }","duration":"259.426358ms","start":"2026-09-30T02:36:48.027357Z","end":"2026-09-30T02:36:48.286784Z","steps":["trace[843857272] 'agreement among raft nodes before linearized reading' (duration: 259.236213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:48.631997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.812535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-30T02:36:48.632259Z","caller":"traceutil/trace.go:171","msg":"trace[907784888] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"484.37958ms","start":"2026-09-30T02:36:48.147821Z","end":"2026-09-30T02:36:48.6322Z","steps":["trace[907784888] 'process raft request' (duration: 328.263958ms)","trace[907784888] 'compare' (duration: 154.849162ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:36:48.632316Z","caller":"traceutil/trace.go:171","msg":"trace[1779724889] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"339.414535ms","start":"2026-09-30T02:36:48.292876Z","end":"2026-09-30T02:36:48.632291Z","steps":["trace[1779724889] 'process raft request' (duration: 339.269362ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:36:48.632281Z","caller":"traceutil/trace.go:171","msg":"trace[1596709377] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5199; }","duration":"345.671545ms","start":"2026-09-30T02:36:48.28657Z","end":"2026-09-30T02:36:48.632241Z","steps":["trace[1596709377] 'read index received' (duration: 189.536423ms)","trace[1596709377] 'applied index is now lower than readState.Index' (duration: 156.131162ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:36:48.632396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:48.292846Z","time spent":"339.514048ms","remote":"127.0.0.1:57604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T02:36:48.632516Z","caller":"traceutil/trace.go:171","msg":"trace[2002970426] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"338.868532ms","start":"2026-09-30T02:36:48.293626Z","end":"2026-09-30T02:36:48.632495Z","steps":["trace[2002970426] 'process raft request' (duration: 338.603066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:48.632521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:48.147785Z","time spent":"484.574885ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:36:48.632606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:48.293603Z","time spent":"338.957765ms","remote":"127.0.0.1:57716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:36:48.63264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.67422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-09-30T02:36:48.632743Z","caller":"traceutil/trace.go:171","msg":"trace[1920047043] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4991; }","duration":"510.827613ms","start":"2026-09-30T02:36:48.12188Z","end":"2026-09-30T02:36:48.632707Z","steps":["trace[1920047043] 'agreement among raft nodes before linearized reading' (duration: 510.493015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:48.632787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:48.121857Z","time spent":"510.920566ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":546,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-09-30T02:36:48.632829Z","caller":"traceutil/trace.go:171","msg":"trace[2071307071] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"338.973544ms","start":"2026-09-30T02:36:48.293822Z","end":"2026-09-30T02:36:48.632796Z","steps":["trace[2071307071] 'process raft request' (duration: 338.609926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:48.633012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:48.293811Z","time spent":"339.098068ms","remote":"127.0.0.1:57716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T02:36:48.633179Z","caller":"traceutil/trace.go:171","msg":"trace[1325693620] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"338.912853ms","start":"2026-09-30T02:36:48.294247Z","end":"2026-09-30T02:36:48.63316Z","steps":["trace[1325693620] 'process raft request' (duration: 338.457482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:48.633259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:48.294234Z","time spent":"338.987596ms","remote":"127.0.0.1:57604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:36:48.633346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.742134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-09-30T02:36:48.633462Z","caller":"traceutil/trace.go:171","msg":"trace[1292550883] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4993; }","duration":"339.877017ms","start":"2026-09-30T02:36:48.293557Z","end":"2026-09-30T02:36:48.633434Z","steps":["trace[1292550883] 'agreement among raft nodes before linearized reading' (duration: 339.550679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:36:48.633384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.045702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qgq65\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-09-30T02:36:48.633516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:36:48.293481Z","time spent":"340.02529ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11518,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-30T02:36:48.633536Z","caller":"traceutil/trace.go:171","msg":"trace[1029878724] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qgq65; range_end:; response_count:1; response_revision:4993; }","duration":"295.221336ms","start":"2026-09-30T02:36:48.3383Z","end":"2026-09-30T02:36:48.633521Z","steps":["trace[1029878724] 'agreement among raft nodes before linearized reading' (duration: 294.880938ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:36:57.504656Z","caller":"traceutil/trace.go:171","msg":"trace[476869144] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"115.812995ms","start":"2026-09-30T02:36:57.388804Z","end":"2026-09-30T02:36:57.504617Z","steps":["trace[476869144] 'process raft request' (duration: 89.198757ms)","trace[476869144] 'compare' (duration: 26.227809ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:37:08.324584Z","caller":"traceutil/trace.go:171","msg":"trace[1204202467] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"158.99337ms","start":"2026-09-30T02:37:08.165551Z","end":"2026-09-30T02:37:08.324544Z","steps":["trace[1204202467] 'process raft request' (duration: 117.545497ms)","trace[1204202467] 'compare' (duration: 41.144966ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:37:08.324983Z","caller":"traceutil/trace.go:171","msg":"trace[2091937081] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"158.732144ms","start":"2026-09-30T02:37:08.166214Z","end":"2026-09-30T02:37:08.324946Z","steps":["trace[2091937081] 'process raft request' (duration: 158.55593ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:37:08.325005Z","caller":"traceutil/trace.go:171","msg":"trace[1024800704] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"159.146564ms","start":"2026-09-30T02:37:08.165812Z","end":"2026-09-30T02:37:08.324959Z","steps":["trace[1024800704] 'process raft request' (duration: 158.664043ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:39:34.217714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3144} {"level":"info","ts":"2026-09-30T02:39:34.317427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3144,"took":"97.670495ms","hash":2920773597,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":33767424,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-30T02:39:34.317501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2920773597,"revision":3144,"compact-revision":-1} {"level":"info","ts":"2026-09-30T02:42:55.610579Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002215.wal"} {"level":"info","ts":"2026-09-30T02:42:59.755583Z","caller":"traceutil/trace.go:171","msg":"trace[1004954807] linearizableReadLoop","detail":"{readStateIndex:8755; appliedIndex:8754; }","duration":"244.41987ms","start":"2026-09-30T02:42:59.511111Z","end":"2026-09-30T02:42:59.755531Z","steps":["trace[1004954807] 'read index received' (duration: 149.32518ms)","trace[1004954807] 'applied index is now lower than readState.Index' (duration: 95.09116ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:42:59.755654Z","caller":"traceutil/trace.go:171","msg":"trace[28644285] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"324.365387ms","start":"2026-09-30T02:42:59.431243Z","end":"2026-09-30T02:42:59.755609Z","steps":["trace[28644285] 'process raft request' (duration: 229.206196ms)","trace[28644285] 'compare' (duration: 94.7071ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:42:59.755886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:42:59.431227Z","time spent":"324.521621ms","remote":"127.0.0.1:57502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1116,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:42:59.756199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.060946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-30T02:42:59.756256Z","caller":"traceutil/trace.go:171","msg":"trace[1041541516] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8383; }","duration":"245.174698ms","start":"2026-09-30T02:42:59.511063Z","end":"2026-09-30T02:42:59.756237Z","steps":["trace[1041541516] 'agreement among raft nodes before linearized reading' (duration: 244.639055ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:12.002177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.525536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:43:12.002287Z","caller":"traceutil/trace.go:171","msg":"trace[91392547] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8462; }","duration":"328.721401ms","start":"2026-09-30T02:43:11.673541Z","end":"2026-09-30T02:43:12.002262Z","steps":["trace[91392547] 'count revisions from in-memory index tree' (duration: 328.408544ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:12.002329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:43:11.673513Z","time spent":"328.805553ms","remote":"127.0.0.1:33168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-09-30T02:43:12.476968Z","caller":"traceutil/trace.go:171","msg":"trace[1749647186] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8843; }","duration":"179.632817ms","start":"2026-09-30T02:43:12.297285Z","end":"2026-09-30T02:43:12.476918Z","steps":["trace[1749647186] 'read index received' (duration: 172.818614ms)","trace[1749647186] 'applied index is now lower than readState.Index' (duration: 6.812383ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:43:12.476997Z","caller":"traceutil/trace.go:171","msg":"trace[1177352245] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"202.031554ms","start":"2026-09-30T02:43:12.274914Z","end":"2026-09-30T02:43:12.476946Z","steps":["trace[1177352245] 'process raft request' (duration: 195.160709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:12.477218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.541402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:43:12.47727Z","caller":"traceutil/trace.go:171","msg":"trace[2089107911] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:8466; }","duration":"138.656294ms","start":"2026-09-30T02:43:12.338598Z","end":"2026-09-30T02:43:12.477255Z","steps":["trace[2089107911] 'agreement among raft nodes before linearized reading' (duration: 138.529861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:12.477403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.078558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-09-30T02:43:12.477524Z","caller":"traceutil/trace.go:171","msg":"trace[1973568254] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8466; }","duration":"180.266152ms","start":"2026-09-30T02:43:12.297224Z","end":"2026-09-30T02:43:12.47749Z","steps":["trace[1973568254] 'agreement among raft nodes before linearized reading' (duration: 179.887443ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:43:41.980711Z","caller":"traceutil/trace.go:171","msg":"trace[1600353505] transaction","detail":"{read_only:false; response_revision:8720; number_of_response:1; }","duration":"305.842453ms","start":"2026-09-30T02:43:41.674571Z","end":"2026-09-30T02:43:41.980413Z","steps":["trace[1600353505] 'process raft request' (duration: 305.625188ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:41.980941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:43:41.674539Z","time spent":"306.300145ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:43:56.433305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.761921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-30T02:43:56.433549Z","caller":"traceutil/trace.go:171","msg":"trace[1013927527] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:8853; }","duration":"104.051548ms","start":"2026-09-30T02:43:56.329465Z","end":"2026-09-30T02:43:56.433517Z","steps":["trace[1013927527] 'count revisions from in-memory index tree' (duration: 103.601127ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:43:57.345798Z","caller":"traceutil/trace.go:171","msg":"trace[1371079323] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"295.79952ms","start":"2026-09-30T02:43:57.049941Z","end":"2026-09-30T02:43:57.345741Z","steps":["trace[1371079323] 'process raft request' (duration: 295.565125ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:43:57.409439Z","caller":"traceutil/trace.go:171","msg":"trace[925103852] linearizableReadLoop","detail":"{readStateIndex:9259; appliedIndex:9257; }","duration":"221.859396ms","start":"2026-09-30T02:43:57.187547Z","end":"2026-09-30T02:43:57.409407Z","steps":["trace[925103852] 'read index received' (duration: 157.90832ms)","trace[925103852] 'applied index is now lower than readState.Index' (duration: 63.949886ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:43:57.409539Z","caller":"traceutil/trace.go:171","msg":"trace[1119591535] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"312.577754ms","start":"2026-09-30T02:43:57.09691Z","end":"2026-09-30T02:43:57.409488Z","steps":["trace[1119591535] 'process raft request' (duration: 305.050573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:57.40966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.082141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-30T02:43:57.409729Z","caller":"traceutil/trace.go:171","msg":"trace[1399237154] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8860; }","duration":"222.223935ms","start":"2026-09-30T02:43:57.187482Z","end":"2026-09-30T02:43:57.409706Z","steps":["trace[1399237154] 'agreement among raft nodes before linearized reading' (duration: 222.069891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:57.409799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T02:43:57.096873Z","time spent":"312.779359ms","remote":"127.0.0.1:57684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2999,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T02:43:57.430613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.073904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-30T02:43:57.430711Z","caller":"traceutil/trace.go:171","msg":"trace[327582951] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8860; }","duration":"123.205768ms","start":"2026-09-30T02:43:57.307483Z","end":"2026-09-30T02:43:57.430689Z","steps":["trace[327582951] 'agreement among raft nodes before linearized reading' (duration: 123.043724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:43:57.430975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.500989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-30T02:43:57.431016Z","caller":"traceutil/trace.go:171","msg":"trace[871846327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8860; }","duration":"159.588361ms","start":"2026-09-30T02:43:57.271418Z","end":"2026-09-30T02:43:57.431006Z","steps":["trace[871846327] 'agreement among raft nodes before linearized reading' (duration: 159.51374ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:43:57.838122Z","caller":"traceutil/trace.go:171","msg":"trace[2127522073] transaction","detail":"{read_only:false; response_revision:8870; number_of_response:1; }","duration":"126.423145ms","start":"2026-09-30T02:43:57.711657Z","end":"2026-09-30T02:43:57.83808Z","steps":["trace[2127522073] 'process raft request' (duration: 112.952092ms)","trace[2127522073] 'compare' (duration: 13.165876ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:44:34.226275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6490} {"level":"info","ts":"2026-09-30T02:44:34.343779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6490,"took":"114.827676ms","hash":659403196,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":25722880,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-30T02:44:34.343857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":659403196,"revision":6490,"compact-revision":3144} {"level":"info","ts":"2026-09-30T02:45:01.841717Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39a4f14f6507253d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-30T02:45:01.848439Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-30T02:45:01.848564Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-30T02:45:03.734907Z","caller":"traceutil/trace.go:171","msg":"trace[949127620] transaction","detail":"{read_only:false; response_revision:9578; number_of_response:1; }","duration":"103.703085ms","start":"2026-09-30T02:45:03.631169Z","end":"2026-09-30T02:45:03.734872Z","steps":["trace[949127620] 'process raft request' (duration: 60.785546ms)","trace[949127620] 'compare' (duration: 42.733254ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:45:15.12779Z","caller":"traceutil/trace.go:171","msg":"trace[580333574] transaction","detail":"{read_only:false; response_revision:9661; number_of_response:1; }","duration":"135.956027ms","start":"2026-09-30T02:45:14.991792Z","end":"2026-09-30T02:45:15.127748Z","steps":["trace[580333574] 'process raft request' (duration: 135.68525ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:49:34.233197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9257} {"level":"info","ts":"2026-09-30T02:49:34.323976Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9257,"took":"87.670316ms","hash":3461685131,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":27897856,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-30T02:49:34.324071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3461685131,"revision":9257,"compact-revision":6490} {"level":"info","ts":"2026-09-30T02:54:34.241788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12173} {"level":"info","ts":"2026-09-30T02:54:34.345968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12173,"took":"101.482031ms","hash":4249162666,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":25526272,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-30T02:54:34.346042Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4249162666,"revision":12173,"compact-revision":9257} {"level":"info","ts":"2026-09-30T02:55:17.564575Z","caller":"traceutil/trace.go:171","msg":"trace[1784342922] linearizableReadLoop","detail":"{readStateIndex:15623; appliedIndex:15621; }","duration":"106.933463ms","start":"2026-09-30T02:55:17.45759Z","end":"2026-09-30T02:55:17.564523Z","steps":["trace[1784342922] 'read index received' (duration: 26.838333ms)","trace[1784342922] 'applied index is now lower than readState.Index' (duration: 80.09401ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T02:55:17.564661Z","caller":"traceutil/trace.go:171","msg":"trace[1963120127] transaction","detail":"{read_only:false; response_revision:14924; number_of_response:1; }","duration":"111.591494ms","start":"2026-09-30T02:55:17.453021Z","end":"2026-09-30T02:55:17.564612Z","steps":["trace[1963120127] 'process raft request' (duration: 111.192334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:55:17.565139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.506486ms","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-09-30T02:55:17.565203Z","caller":"traceutil/trace.go:171","msg":"trace[1792057272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14924; }","duration":"107.64257ms","start":"2026-09-30T02:55:17.457548Z","end":"2026-09-30T02:55:17.56519Z","steps":["trace[1792057272] 'agreement among raft nodes before linearized reading' (duration: 107.210679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:55:17.565235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.386924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-30T02:55:17.565363Z","caller":"traceutil/trace.go:171","msg":"trace[1536254884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14924; }","duration":"102.551457ms","start":"2026-09-30T02:55:17.462783Z","end":"2026-09-30T02:55:17.565334Z","steps":["trace[1536254884] 'agreement among raft nodes before linearized reading' (duration: 102.199079ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:55:35.52484Z","caller":"traceutil/trace.go:171","msg":"trace[1780020788] transaction","detail":"{read_only:false; response_revision:15063; number_of_response:1; }","duration":"277.375039ms","start":"2026-09-30T02:55:35.247434Z","end":"2026-09-30T02:55:35.524809Z","steps":["trace[1780020788] 'process raft request' (duration: 277.190724ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:57:25.144677Z","caller":"traceutil/trace.go:171","msg":"trace[917154840] linearizableReadLoop","detail":"{readStateIndex:17100; appliedIndex:17099; }","duration":"151.568163ms","start":"2026-09-30T02:57:24.993051Z","end":"2026-09-30T02:57:25.14462Z","steps":["trace[917154840] 'read index received' (duration: 151.166013ms)","trace[917154840] 'applied index is now lower than readState.Index' (duration: 400.69µs)"],"step_count":2} {"level":"warn","ts":"2026-09-30T02:57:25.145057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.964882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-30T02:57:25.145247Z","caller":"traceutil/trace.go:171","msg":"trace[1481404859] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16334; }","duration":"152.177168ms","start":"2026-09-30T02:57:24.993011Z","end":"2026-09-30T02:57:25.145188Z","steps":["trace[1481404859] 'agreement among raft nodes before linearized reading' (duration: 151.794848ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:57:25.147299Z","caller":"traceutil/trace.go:171","msg":"trace[1445576415] transaction","detail":"{read_only:false; response_revision:16334; number_of_response:1; }","duration":"261.642561ms","start":"2026-09-30T02:57:24.88304Z","end":"2026-09-30T02:57:25.144682Z","steps":["trace[1445576415] 'process raft request' (duration: 261.200081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T02:57:25.158118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.151625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qgq65\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-30T02:57:25.158212Z","caller":"traceutil/trace.go:171","msg":"trace[1845689790] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qgq65; range_end:; response_count:1; response_revision:16335; }","duration":"129.32899ms","start":"2026-09-30T02:57:25.028865Z","end":"2026-09-30T02:57:25.158194Z","steps":["trace[1845689790] 'agreement among raft nodes before linearized reading' (duration: 129.061974ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T02:59:34.249193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14600} {"level":"info","ts":"2026-09-30T02:59:34.354287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14600,"took":"101.865701ms","hash":1963358503,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":29421568,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-30T02:59:34.354367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1963358503,"revision":14600,"compact-revision":12173} {"level":"info","ts":"2026-09-30T03:02:28.762401Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39a4f14f6507253d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-30T03:02:28.768383Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-30T03:02:28.768537Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-30T03:02:51.322624Z","caller":"traceutil/trace.go:171","msg":"trace[490373735] linearizableReadLoop","detail":"{readStateIndex:20199; appliedIndex:20198; }","duration":"252.419824ms","start":"2026-09-30T03:02:51.070169Z","end":"2026-09-30T03:02:51.322589Z","steps":["trace[490373735] 'read index received' (duration: 252.140787ms)","trace[490373735] 'applied index is now lower than readState.Index' (duration: 277.617µs)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:02:51.322991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.927285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-l7spt\" ","response":"range_response_count:1 size:20735"} {"level":"info","ts":"2026-09-30T03:02:51.323038Z","caller":"traceutil/trace.go:171","msg":"trace[1193698860] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-l7spt; range_end:; response_count:1; response_revision:19289; }","duration":"225.051809ms","start":"2026-09-30T03:02:51.097971Z","end":"2026-09-30T03:02:51.323023Z","steps":["trace[1193698860] 'agreement among raft nodes before linearized reading' (duration: 224.828044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:02:51.324126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.889315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-09-30T03:02:51.324242Z","caller":"traceutil/trace.go:171","msg":"trace[397532488] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19289; }","duration":"254.115705ms","start":"2026-09-30T03:02:51.070103Z","end":"2026-09-30T03:02:51.324218Z","steps":["trace[397532488] 'agreement among raft nodes before linearized reading' (duration: 252.66472ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:02:51.324689Z","caller":"traceutil/trace.go:171","msg":"trace[1056382683] transaction","detail":"{read_only:false; response_revision:19289; number_of_response:1; }","duration":"295.745851ms","start":"2026-09-30T03:02:51.026962Z","end":"2026-09-30T03:02:51.322708Z","steps":["trace[1056382683] 'process raft request' (duration: 295.361152ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:02:51.471275Z","caller":"traceutil/trace.go:171","msg":"trace[1510615768] linearizableReadLoop","detail":"{readStateIndex:20200; appliedIndex:20199; }","duration":"132.256887ms","start":"2026-09-30T03:02:51.338964Z","end":"2026-09-30T03:02:51.471221Z","steps":["trace[1510615768] 'read index received' (duration: 79.254328ms)","trace[1510615768] 'applied index is now lower than readState.Index' (duration: 53.000449ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:02:51.47147Z","caller":"traceutil/trace.go:171","msg":"trace[921515652] transaction","detail":"{read_only:false; response_revision:19290; number_of_response:1; }","duration":"141.555689ms","start":"2026-09-30T03:02:51.329893Z","end":"2026-09-30T03:02:51.471448Z","steps":["trace[921515652] 'process raft request' (duration: 88.377746ms)","trace[921515652] 'compare' (duration: 52.483696ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:02:51.472831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.837655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:933278"} {"level":"info","ts":"2026-09-30T03:02:51.472898Z","caller":"traceutil/trace.go:171","msg":"trace[1745388455] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19290; }","duration":"133.966198ms","start":"2026-09-30T03:02:51.338917Z","end":"2026-09-30T03:02:51.472884Z","steps":["trace[1745388455] 'agreement among raft nodes before linearized reading' (duration: 132.531814ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:02:51.489955Z","caller":"traceutil/trace.go:171","msg":"trace[911040803] transaction","detail":"{read_only:false; response_revision:19291; number_of_response:1; }","duration":"138.910886ms","start":"2026-09-30T03:02:51.35101Z","end":"2026-09-30T03:02:51.48992Z","steps":["trace[911040803] 'process raft request' (duration: 138.596789ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:04:34.256329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17470} {"level":"info","ts":"2026-09-30T03:04:34.363966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17470,"took":"103.734014ms","hash":1687260912,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":34705408,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-30T03:04:34.364054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1687260912,"revision":17470,"compact-revision":14600} {"level":"info","ts":"2026-09-30T03:09:34.266175Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20446} {"level":"info","ts":"2026-09-30T03:09:34.398669Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20446,"took":"127.922155ms","hash":1232655579,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":33382400,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-30T03:09:34.398758Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1232655579,"revision":20446,"compact-revision":17470} {"level":"info","ts":"2026-09-30T03:10:27.303641Z","caller":"traceutil/trace.go:171","msg":"trace[691972479] transaction","detail":"{read_only:false; response_revision:23750; number_of_response:1; }","duration":"110.431658ms","start":"2026-09-30T03:10:27.193152Z","end":"2026-09-30T03:10:27.303584Z","steps":["trace[691972479] 'process raft request' (duration: 107.659072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:10:32.926325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.38875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:10:32.926458Z","caller":"traceutil/trace.go:171","msg":"trace[1880592258] transaction","detail":"{read_only:false; response_revision:23783; number_of_response:1; }","duration":"269.757458ms","start":"2026-09-30T03:10:32.656679Z","end":"2026-09-30T03:10:32.926436Z","steps":["trace[1880592258] 'process raft request' (duration: 138.144313ms)","trace[1880592258] 'compare' (duration: 131.263587ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:10:49.298713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.982935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:10:49.298873Z","caller":"traceutil/trace.go:171","msg":"trace[2083125971] transaction","detail":"{read_only:false; response_revision:23887; number_of_response:1; }","duration":"131.727788ms","start":"2026-09-30T03:10:49.167121Z","end":"2026-09-30T03:10:49.298849Z","steps":["trace[2083125971] 'process raft request' (duration: 14.462686ms)","trace[2083125971] 'compare' (duration: 116.76241ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:10:49.34988Z","caller":"traceutil/trace.go:171","msg":"trace[868485638] linearizableReadLoop","detail":"{readStateIndex:25012; appliedIndex:25010; }","duration":"159.103565ms","start":"2026-09-30T03:10:49.190749Z","end":"2026-09-30T03:10:49.349853Z","steps":["trace[868485638] 'read index received' (duration: 158.662574ms)","trace[868485638] 'applied index is now lower than readState.Index' (duration: 439.701µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:10:49.350004Z","caller":"traceutil/trace.go:171","msg":"trace[252716428] transaction","detail":"{read_only:false; response_revision:23888; number_of_response:1; }","duration":"182.588967ms","start":"2026-09-30T03:10:49.167371Z","end":"2026-09-30T03:10:49.34996Z","steps":["trace[252716428] 'process raft request' (duration: 182.034294ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:10:49.350026Z","caller":"traceutil/trace.go:171","msg":"trace[1151238317] transaction","detail":"{read_only:false; response_revision:23889; number_of_response:1; }","duration":"179.085754ms","start":"2026-09-30T03:10:49.1709Z","end":"2026-09-30T03:10:49.349985Z","steps":["trace[1151238317] 'process raft request' (duration: 178.861499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:10:49.350387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.449374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-09-30T03:10:49.350466Z","caller":"traceutil/trace.go:171","msg":"trace[897641850] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23889; }","duration":"159.741ms","start":"2026-09-30T03:10:49.190704Z","end":"2026-09-30T03:10:49.350445Z","steps":["trace[897641850] 'agreement among raft nodes before linearized reading' (duration: 159.343361ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:10:49.465547Z","caller":"traceutil/trace.go:171","msg":"trace[356625423] transaction","detail":"{read_only:false; response_revision:23890; number_of_response:1; }","duration":"109.125546ms","start":"2026-09-30T03:10:49.356391Z","end":"2026-09-30T03:10:49.465516Z","steps":["trace[356625423] 'process raft request' (duration: 59.037145ms)","trace[356625423] 'compare' (duration: 49.956038ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:10:49.765921Z","caller":"traceutil/trace.go:171","msg":"trace[1266336313] linearizableReadLoop","detail":"{readStateIndex:25014; appliedIndex:25012; }","duration":"383.412293ms","start":"2026-09-30T03:10:49.382481Z","end":"2026-09-30T03:10:49.765894Z","steps":["trace[1266336313] 'read index received' (duration: 33.152385ms)","trace[1266336313] 'applied index is now lower than readState.Index' (duration: 350.258798ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:10:49.766024Z","caller":"traceutil/trace.go:171","msg":"trace[267859326] transaction","detail":"{read_only:false; response_revision:23892; number_of_response:1; }","duration":"360.849872ms","start":"2026-09-30T03:10:49.40516Z","end":"2026-09-30T03:10:49.76601Z","steps":["trace[267859326] 'process raft request' (duration: 360.603446ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:10:49.765922Z","caller":"traceutil/trace.go:171","msg":"trace[709032806] transaction","detail":"{read_only:false; response_revision:23891; number_of_response:1; }","duration":"405.116854ms","start":"2026-09-30T03:10:49.360754Z","end":"2026-09-30T03:10:49.765871Z","steps":["trace[709032806] 'process raft request' (duration: 311.95363ms)","trace[709032806] 'compare' (duration: 92.461877ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:10:49.766196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.68817ms","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-09-30T03:10:49.766243Z","caller":"traceutil/trace.go:171","msg":"trace[306714710] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23892; }","duration":"383.807622ms","start":"2026-09-30T03:10:49.382424Z","end":"2026-09-30T03:10:49.766231Z","steps":["trace[306714710] 'agreement among raft nodes before linearized reading' (duration: 383.614058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:10:49.766222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:10:49.405139Z","time spent":"360.927063ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:10:49.766283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:10:49.382399Z","time spent":"383.874764ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-30T03:10:49.766531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:10:49.360735Z","time spent":"405.630425ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:10:49.787877Z","caller":"traceutil/trace.go:171","msg":"trace[689355827] transaction","detail":"{read_only:false; response_revision:23893; number_of_response:1; }","duration":"155.688452ms","start":"2026-09-30T03:10:49.632152Z","end":"2026-09-30T03:10:49.78784Z","steps":["trace[689355827] 'process raft request' (duration: 155.426586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:10:49.788053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.33351ms","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-09-30T03:10:49.788181Z","caller":"traceutil/trace.go:171","msg":"trace[1414563651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23893; }","duration":"277.513594ms","start":"2026-09-30T03:10:49.510606Z","end":"2026-09-30T03:10:49.78812Z","steps":["trace[1414563651] 'agreement among raft nodes before linearized reading' (duration: 277.182836ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:10:50.136785Z","caller":"traceutil/trace.go:171","msg":"trace[333658730] linearizableReadLoop","detail":"{readStateIndex:25019; appliedIndex:25018; }","duration":"310.711089ms","start":"2026-09-30T03:10:49.826048Z","end":"2026-09-30T03:10:50.136759Z","steps":["trace[333658730] 'read index received' (duration: 310.418312ms)","trace[333658730] 'applied index is now lower than readState.Index' (duration: 291.817µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:10:50.136878Z","caller":"traceutil/trace.go:171","msg":"trace[1646006209] transaction","detail":"{read_only:false; response_revision:23895; number_of_response:1; }","duration":"344.000398ms","start":"2026-09-30T03:10:49.792833Z","end":"2026-09-30T03:10:50.136834Z","steps":["trace[1646006209] 'process raft request' (duration: 343.717611ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:10:50.137076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.336046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-09-30T03:10:50.137106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:10:49.792807Z","time spent":"344.177383ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:10:50.137139Z","caller":"traceutil/trace.go:171","msg":"trace[1968585868] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23895; }","duration":"178.419278ms","start":"2026-09-30T03:10:49.958705Z","end":"2026-09-30T03:10:50.137124Z","steps":["trace[1968585868] 'agreement among raft nodes before linearized reading' (duration: 178.234624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:10:50.137075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.007616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13486"} {"level":"info","ts":"2026-09-30T03:10:50.137266Z","caller":"traceutil/trace.go:171","msg":"trace[1432782976] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:23895; }","duration":"311.266793ms","start":"2026-09-30T03:10:49.825981Z","end":"2026-09-30T03:10:50.137248Z","steps":["trace[1432782976] 'agreement among raft nodes before linearized reading' (duration: 310.923895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:10:50.13732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:10:49.825958Z","time spent":"311.346664ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":13508,"request content":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" "} {"level":"info","ts":"2026-09-30T03:14:27.64428Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b8e.wal"} {"level":"info","ts":"2026-09-30T03:14:34.274422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23327} {"level":"info","ts":"2026-09-30T03:14:34.403238Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23327,"took":"123.806198ms","hash":1278347455,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":44281856,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-30T03:14:34.403332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1278347455,"revision":23327,"compact-revision":20446} {"level":"info","ts":"2026-09-30T03:14:36.628971Z","caller":"traceutil/trace.go:171","msg":"trace[1480336856] linearizableReadLoop","detail":"{readStateIndex:27804; appliedIndex:27803; }","duration":"113.262465ms","start":"2026-09-30T03:14:36.515684Z","end":"2026-09-30T03:14:36.628946Z","steps":["trace[1480336856] 'read index received' (duration: 112.936677ms)","trace[1480336856] 'applied index is now lower than readState.Index' (duration: 324.038µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:14:36.629042Z","caller":"traceutil/trace.go:171","msg":"trace[1632702637] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"150.768075ms","start":"2026-09-30T03:14:36.478213Z","end":"2026-09-30T03:14:36.628981Z","steps":["trace[1632702637] 'process raft request' (duration: 150.531449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:14:36.629306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.609354ms","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-09-30T03:14:36.629396Z","caller":"traceutil/trace.go:171","msg":"trace[406893456] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26565; }","duration":"113.759257ms","start":"2026-09-30T03:14:36.515616Z","end":"2026-09-30T03:14:36.629375Z","steps":["trace[406893456] 'agreement among raft nodes before linearized reading' (duration: 113.45721ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:31.662908Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39a4f14f6507253d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-30T03:17:31.670246Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-30T03:17:31.670371Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-30T03:17:41.518804Z","caller":"traceutil/trace.go:171","msg":"trace[1653290848] transaction","detail":"{read_only:false; response_revision:28806; number_of_response:1; }","duration":"108.273216ms","start":"2026-09-30T03:17:41.410504Z","end":"2026-09-30T03:17:41.518777Z","steps":["trace[1653290848] 'process raft request' (duration: 108.082921ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:41.715984Z","caller":"traceutil/trace.go:171","msg":"trace[569024652] transaction","detail":"{read_only:false; response_revision:28808; number_of_response:1; }","duration":"179.234436ms","start":"2026-09-30T03:17:41.536649Z","end":"2026-09-30T03:17:41.715884Z","steps":["trace[569024652] 'process raft request' (duration: 178.935409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:41.966754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.450688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:17:41.96701Z","caller":"traceutil/trace.go:171","msg":"trace[1835474857] linearizableReadLoop","detail":"{readStateIndex:30135; appliedIndex:30133; }","duration":"126.191735ms","start":"2026-09-30T03:17:41.840792Z","end":"2026-09-30T03:17:41.966984Z","steps":["trace[1835474857] 'read index received' (duration: 5.387639ms)","trace[1835474857] 'applied index is now lower than readState.Index' (duration: 120.802366ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:41.967174Z","caller":"traceutil/trace.go:171","msg":"trace[1825925242] transaction","detail":"{read_only:false; response_revision:28810; number_of_response:1; }","duration":"245.306961ms","start":"2026-09-30T03:17:41.721811Z","end":"2026-09-30T03:17:41.967118Z","steps":["trace[1825925242] 'process raft request' (duration: 245.086565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:41.967261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.465142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-30T03:17:41.967183Z","caller":"traceutil/trace.go:171","msg":"trace[1193203980] transaction","detail":"{read_only:false; response_revision:28809; number_of_response:1; }","duration":"246.057569ms","start":"2026-09-30T03:17:41.721097Z","end":"2026-09-30T03:17:41.967154Z","steps":["trace[1193203980] 'process raft request' (duration: 125.072809ms)","trace[1193203980] 'compare' (duration: 120.262843ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:41.9673Z","caller":"traceutil/trace.go:171","msg":"trace[1297618298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28810; }","duration":"126.538684ms","start":"2026-09-30T03:17:41.840749Z","end":"2026-09-30T03:17:41.967288Z","steps":["trace[1297618298] 'agreement among raft nodes before linearized reading' (duration: 126.308158ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:42.130026Z","caller":"traceutil/trace.go:171","msg":"trace[1543386182] linearizableReadLoop","detail":"{readStateIndex:30136; appliedIndex:30135; }","duration":"127.114007ms","start":"2026-09-30T03:17:42.002873Z","end":"2026-09-30T03:17:42.129987Z","steps":["trace[1543386182] 'read index received' (duration: 106.658367ms)","trace[1543386182] 'applied index is now lower than readState.Index' (duration: 20.4541ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:42.130145Z","caller":"traceutil/trace.go:171","msg":"trace[1022514950] transaction","detail":"{read_only:false; response_revision:28811; number_of_response:1; }","duration":"157.922616ms","start":"2026-09-30T03:17:41.972157Z","end":"2026-09-30T03:17:42.130079Z","steps":["trace[1022514950] 'process raft request' (duration: 137.397414ms)","trace[1022514950] 'compare' (duration: 20.106362ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:17:42.130398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.498697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-548cdb6577-wt85r\" ","response":"range_response_count:1 size:8648"} {"level":"info","ts":"2026-09-30T03:17:42.130516Z","caller":"traceutil/trace.go:171","msg":"trace[1519823679] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-548cdb6577-wt85r; range_end:; response_count:1; response_revision:28811; }","duration":"127.693331ms","start":"2026-09-30T03:17:42.002795Z","end":"2026-09-30T03:17:42.130488Z","steps":["trace[1519823679] 'agreement among raft nodes before linearized reading' (duration: 127.353353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:42.130916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.661435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-09-30T03:17:42.130974Z","caller":"traceutil/trace.go:171","msg":"trace[1892052214] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28811; }","duration":"103.788608ms","start":"2026-09-30T03:17:42.027168Z","end":"2026-09-30T03:17:42.130957Z","steps":["trace[1892052214] 'agreement among raft nodes before linearized reading' (duration: 103.260415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:47.062151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.825379ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:17:47.062334Z","caller":"traceutil/trace.go:171","msg":"trace[507220358] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28857; }","duration":"104.046504ms","start":"2026-09-30T03:17:46.958263Z","end":"2026-09-30T03:17:47.062309Z","steps":["trace[507220358] 'range keys from in-memory index tree' (duration: 103.798488ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:47.062907Z","caller":"traceutil/trace.go:171","msg":"trace[1028552816] transaction","detail":"{read_only:false; response_revision:28858; number_of_response:1; }","duration":"322.109162ms","start":"2026-09-30T03:17:46.740752Z","end":"2026-09-30T03:17:47.062861Z","steps":["trace[1028552816] 'process raft request' (duration: 293.712492ms)","trace[1028552816] 'compare' (duration: 28.045532ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:17:47.063289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:46.740728Z","time spent":"322.267626ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:17:47.082078Z","caller":"traceutil/trace.go:171","msg":"trace[468067838] transaction","detail":"{read_only:false; response_revision:28860; number_of_response:1; }","duration":"279.49701ms","start":"2026-09-30T03:17:46.802499Z","end":"2026-09-30T03:17:47.081996Z","steps":["trace[468067838] 'process raft request' (duration: 279.403248ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:47.082082Z","caller":"traceutil/trace.go:171","msg":"trace[271413340] transaction","detail":"{read_only:false; response_revision:28859; number_of_response:1; }","duration":"334.862118ms","start":"2026-09-30T03:17:46.747172Z","end":"2026-09-30T03:17:47.082034Z","steps":["trace[271413340] 'process raft request' (duration: 334.549081ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:47.082258Z","caller":"traceutil/trace.go:171","msg":"trace[1567556409] transaction","detail":"{read_only:false; response_revision:28861; number_of_response:1; }","duration":"241.418397ms","start":"2026-09-30T03:17:46.840792Z","end":"2026-09-30T03:17:47.08221Z","steps":["trace[1567556409] 'process raft request' (duration: 241.164521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:47.082318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:46.747147Z","time spent":"335.041722ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:17:47.356103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.829804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:17:47.356198Z","caller":"traceutil/trace.go:171","msg":"trace[654191243] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:28861; }","duration":"157.994147ms","start":"2026-09-30T03:17:47.198182Z","end":"2026-09-30T03:17:47.356176Z","steps":["trace[654191243] 'count revisions from in-memory index tree' (duration: 157.614488ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:47.965336Z","caller":"traceutil/trace.go:171","msg":"trace[234313302] linearizableReadLoop","detail":"{readStateIndex:30190; appliedIndex:30189; }","duration":"155.868396ms","start":"2026-09-30T03:17:47.809436Z","end":"2026-09-30T03:17:47.965304Z","steps":["trace[234313302] 'read index received' (duration: 155.636331ms)","trace[234313302] 'applied index is now lower than readState.Index' (duration: 230.485µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:47.965442Z","caller":"traceutil/trace.go:171","msg":"trace[942503460] transaction","detail":"{read_only:false; response_revision:28863; number_of_response:1; }","duration":"171.772048ms","start":"2026-09-30T03:17:47.793633Z","end":"2026-09-30T03:17:47.965405Z","steps":["trace[942503460] 'process raft request' (duration: 171.46728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:47.965647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.816622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"warn","ts":"2026-09-30T03:17:47.965647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.578625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-09-30T03:17:47.965703Z","caller":"traceutil/trace.go:171","msg":"trace[511930118] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:28863; }","duration":"111.692587ms","start":"2026-09-30T03:17:47.853987Z","end":"2026-09-30T03:17:47.96568Z","steps":["trace[511930118] 'agreement among raft nodes before linearized reading' (duration: 111.543344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:47.965652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.187004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.92\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-30T03:17:47.965773Z","caller":"traceutil/trace.go:171","msg":"trace[741129056] range","detail":"{range_begin:/registry/masterleases/162.253.55.92; range_end:; response_count:1; response_revision:28863; }","duration":"156.376009ms","start":"2026-09-30T03:17:47.809372Z","end":"2026-09-30T03:17:47.965748Z","steps":["trace[741129056] 'agreement among raft nodes before linearized reading' (duration: 156.055021ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:47.966836Z","caller":"traceutil/trace.go:171","msg":"trace[293354202] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:28863; }","duration":"139.944305ms","start":"2026-09-30T03:17:47.825734Z","end":"2026-09-30T03:17:47.965678Z","steps":["trace[293354202] 'agreement among raft nodes before linearized reading' (duration: 139.760841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:48.409386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.185272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:17:48.409503Z","caller":"traceutil/trace.go:171","msg":"trace[76251700] linearizableReadLoop","detail":"{readStateIndex:30196; appliedIndex:30195; }","duration":"206.235344ms","start":"2026-09-30T03:17:48.203248Z","end":"2026-09-30T03:17:48.409483Z","steps":["trace[76251700] 'read index received' (duration: 57.714103ms)","trace[76251700] 'applied index is now lower than readState.Index' (duration: 148.519131ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:48.409577Z","caller":"traceutil/trace.go:171","msg":"trace[91569541] transaction","detail":"{read_only:false; response_revision:28868; number_of_response:1; }","duration":"246.521571ms","start":"2026-09-30T03:17:48.163021Z","end":"2026-09-30T03:17:48.409542Z","steps":["trace[91569541] 'process raft request' (duration: 98.042891ms)","trace[91569541] 'compare' (duration: 147.976978ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:17:48.409886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.630713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-30T03:17:48.409938Z","caller":"traceutil/trace.go:171","msg":"trace[1466409956] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28868; }","duration":"206.705176ms","start":"2026-09-30T03:17:48.203219Z","end":"2026-09-30T03:17:48.409924Z","steps":["trace[1466409956] 'agreement among raft nodes before linearized reading' (duration: 206.332367ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:48.669829Z","caller":"traceutil/trace.go:171","msg":"trace[164979022] linearizableReadLoop","detail":"{readStateIndex:30197; appliedIndex:30196; }","duration":"260.26767ms","start":"2026-09-30T03:17:48.409529Z","end":"2026-09-30T03:17:48.669796Z","steps":["trace[164979022] 'read index received' (duration: 175.449016ms)","trace[164979022] 'applied index is now lower than readState.Index' (duration: 84.817064ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:48.669948Z","caller":"traceutil/trace.go:171","msg":"trace[1357116766] transaction","detail":"{read_only:false; response_revision:28869; number_of_response:1; }","duration":"356.745143ms","start":"2026-09-30T03:17:48.313187Z","end":"2026-09-30T03:17:48.669933Z","steps":["trace[1357116766] 'process raft request' (duration: 271.770106ms)","trace[1357116766] 'compare' (duration: 84.022664ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:17:48.670069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:48.313167Z","time spent":"356.813154ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:17:48.67005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.803403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:17:48.670143Z","caller":"traceutil/trace.go:171","msg":"trace[1889265887] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:28869; }","duration":"334.057888ms","start":"2026-09-30T03:17:48.336062Z","end":"2026-09-30T03:17:48.67012Z","steps":["trace[1889265887] 'agreement among raft nodes before linearized reading' (duration: 333.889184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:48.670194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:48.336027Z","time spent":"334.156301ms","remote":"127.0.0.1:57826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":28,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-30T03:17:48.670479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.399943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-09-30T03:17:48.670991Z","caller":"traceutil/trace.go:171","msg":"trace[1530441478] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28869; }","duration":"416.956866ms","start":"2026-09-30T03:17:48.253987Z","end":"2026-09-30T03:17:48.670943Z","steps":["trace[1530441478] 'agreement among raft nodes before linearized reading' (duration: 416.26533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:48.671058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:48.253958Z","time spent":"417.08496ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-30T03:17:48.670594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.960771ms","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-09-30T03:17:48.671271Z","caller":"traceutil/trace.go:171","msg":"trace[658875710] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28869; }","duration":"325.729698ms","start":"2026-09-30T03:17:48.345517Z","end":"2026-09-30T03:17:48.671247Z","steps":["trace[658875710] 'agreement among raft nodes before linearized reading' (duration: 324.602741ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:48.67134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:48.345505Z","time spent":"325.813001ms","remote":"127.0.0.1:38944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-09-30T03:17:48.675028Z","caller":"traceutil/trace.go:171","msg":"trace[1115389923] transaction","detail":"{read_only:false; response_revision:28870; number_of_response:1; }","duration":"259.291246ms","start":"2026-09-30T03:17:48.415725Z","end":"2026-09-30T03:17:48.675016Z","steps":["trace[1115389923] 'process raft request' (duration: 259.05969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:48.675202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.979837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:17:48.675245Z","caller":"traceutil/trace.go:171","msg":"trace[995308446] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:28870; }","duration":"178.044658ms","start":"2026-09-30T03:17:48.497189Z","end":"2026-09-30T03:17:48.675234Z","steps":["trace[995308446] 'agreement among raft nodes before linearized reading' (duration: 177.981176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:48.923478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.955659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:17:48.92395Z","caller":"traceutil/trace.go:171","msg":"trace[940680913] transaction","detail":"{read_only:false; response_revision:28872; number_of_response:1; }","duration":"182.120986ms","start":"2026-09-30T03:17:48.741793Z","end":"2026-09-30T03:17:48.923914Z","steps":["trace[940680913] 'process raft request' (duration: 181.795408ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:48.923971Z","caller":"traceutil/trace.go:171","msg":"trace[877736616] transaction","detail":"{read_only:false; response_revision:28871; number_of_response:1; }","duration":"244.471931ms","start":"2026-09-30T03:17:48.679469Z","end":"2026-09-30T03:17:48.923941Z","steps":["trace[877736616] 'process raft request' (duration: 140.944569ms)","trace[877736616] 'compare' (duration: 102.359445ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:49.279106Z","caller":"traceutil/trace.go:171","msg":"trace[1143965557] transaction","detail":"{read_only:false; response_revision:28874; number_of_response:1; }","duration":"346.273542ms","start":"2026-09-30T03:17:48.932808Z","end":"2026-09-30T03:17:49.279082Z","steps":["trace[1143965557] 'process raft request' (duration: 345.965464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.279266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:48.932787Z","time spent":"346.397525ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:17:49.279512Z","caller":"traceutil/trace.go:171","msg":"trace[459841661] linearizableReadLoop","detail":"{readStateIndex:30203; appliedIndex:30201; }","duration":"208.305674ms","start":"2026-09-30T03:17:49.071195Z","end":"2026-09-30T03:17:49.2795Z","steps":["trace[459841661] 'read index received' (duration: 207.676129ms)","trace[459841661] 'applied index is now lower than readState.Index' (duration: 628.575µs)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:17:49.279737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.55128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-30T03:17:49.279669Z","caller":"traceutil/trace.go:171","msg":"trace[2139420135] transaction","detail":"{read_only:false; response_revision:28875; number_of_response:1; }","duration":"292.358449ms","start":"2026-09-30T03:17:48.987264Z","end":"2026-09-30T03:17:49.279623Z","steps":["trace[2139420135] 'process raft request' (duration: 291.749894ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:49.279775Z","caller":"traceutil/trace.go:171","msg":"trace[1286677813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28875; }","duration":"208.648182ms","start":"2026-09-30T03:17:49.071118Z","end":"2026-09-30T03:17:49.279766Z","steps":["trace[1286677813] 'agreement among raft nodes before linearized reading' (duration: 208.507559ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.600402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.012956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:17:49.600717Z","caller":"traceutil/trace.go:171","msg":"trace[1671446279] linearizableReadLoop","detail":"{readStateIndex:30205; appliedIndex:30203; }","duration":"321.125298ms","start":"2026-09-30T03:17:49.279539Z","end":"2026-09-30T03:17:49.600665Z","steps":["trace[1671446279] 'read index received' (duration: 145.704113ms)","trace[1671446279] 'applied index is now lower than readState.Index' (duration: 175.419355ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:49.600668Z","caller":"traceutil/trace.go:171","msg":"trace[312124519] transaction","detail":"{read_only:false; response_revision:28876; number_of_response:1; }","duration":"512.192199ms","start":"2026-09-30T03:17:49.088433Z","end":"2026-09-30T03:17:49.600625Z","steps":["trace[312124519] 'process raft request' (duration: 336.793234ms)","trace[312124519] 'compare' (duration: 174.74305ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:49.600867Z","caller":"traceutil/trace.go:171","msg":"trace[1826926315] transaction","detail":"{read_only:false; response_revision:28877; number_of_response:1; }","duration":"511.219556ms","start":"2026-09-30T03:17:49.089605Z","end":"2026-09-30T03:17:49.600825Z","steps":["trace[1826926315] 'process raft request' (duration: 510.925309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.600919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:49.088414Z","time spent":"512.392955ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:17:49.601066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:49.089592Z","time spent":"511.360309ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:17:49.601227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.376128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:17:49.601331Z","caller":"traceutil/trace.go:171","msg":"trace[1075229334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28877; }","duration":"493.505792ms","start":"2026-09-30T03:17:49.107802Z","end":"2026-09-30T03:17:49.601308Z","steps":["trace[1075229334] 'agreement among raft nodes before linearized reading' (duration: 493.264086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.601383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:49.107795Z","time spent":"493.578853ms","remote":"127.0.0.1:57432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-30T03:17:49.601421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.616754ms","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-09-30T03:17:49.601471Z","caller":"traceutil/trace.go:171","msg":"trace[1720867605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28877; }","duration":"513.694185ms","start":"2026-09-30T03:17:49.087759Z","end":"2026-09-30T03:17:49.601453Z","steps":["trace[1720867605] 'agreement among raft nodes before linearized reading' (duration: 513.512301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.601509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:49.087738Z","time spent":"513.764647ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-30T03:17:49.601732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.209193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-30T03:17:49.601782Z","caller":"traceutil/trace.go:171","msg":"trace[1685243649] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:28877; }","duration":"372.263455ms","start":"2026-09-30T03:17:49.229507Z","end":"2026-09-30T03:17:49.60177Z","steps":["trace[1685243649] 'agreement among raft nodes before linearized reading' (duration: 371.281251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.601818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:49.229478Z","time spent":"372.330357ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":187,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-09-30T03:17:49.624084Z","caller":"traceutil/trace.go:171","msg":"trace[90867502] transaction","detail":"{read_only:false; response_revision:28878; number_of_response:1; }","duration":"339.318165ms","start":"2026-09-30T03:17:49.28474Z","end":"2026-09-30T03:17:49.624058Z","steps":["trace[90867502] 'process raft request' (duration: 339.093799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.624246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:49.284706Z","time spent":"339.449818ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:17:49.624275Z","caller":"traceutil/trace.go:171","msg":"trace[404854144] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28878; }","duration":"336.871586ms","start":"2026-09-30T03:17:49.28738Z","end":"2026-09-30T03:17:49.624252Z","steps":["trace[404854144] 'process raft request' (duration: 336.62234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.624346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.216265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-30T03:17:49.624383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:49.287361Z","time spent":"336.977078ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-30T03:17:49.624398Z","caller":"traceutil/trace.go:171","msg":"trace[809631970] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:28878; }","duration":"204.310908ms","start":"2026-09-30T03:17:49.420073Z","end":"2026-09-30T03:17:49.624384Z","steps":["trace[809631970] 'agreement among raft nodes before linearized reading' (duration: 204.187515ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:49.880041Z","caller":"traceutil/trace.go:171","msg":"trace[326247065] transaction","detail":"{read_only:false; response_revision:28880; number_of_response:1; }","duration":"230.874385ms","start":"2026-09-30T03:17:49.649096Z","end":"2026-09-30T03:17:49.879971Z","steps":["trace[326247065] 'process raft request' (duration: 132.64794ms)","trace[326247065] 'compare' (duration: 97.956368ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:49.893763Z","caller":"traceutil/trace.go:171","msg":"trace[1053581324] linearizableReadLoop","detail":"{readStateIndex:30210; appliedIndex:30208; }","duration":"162.551767ms","start":"2026-09-30T03:17:49.731187Z","end":"2026-09-30T03:17:49.893739Z","steps":["trace[1053581324] 'read index received' (duration: 50.533412ms)","trace[1053581324] 'applied index is now lower than readState.Index' (duration: 112.017215ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:49.893866Z","caller":"traceutil/trace.go:171","msg":"trace[1682911940] transaction","detail":"{read_only:false; response_revision:28881; number_of_response:1; }","duration":"241.188762ms","start":"2026-09-30T03:17:49.652621Z","end":"2026-09-30T03:17:49.893809Z","steps":["trace[1682911940] 'process raft request' (duration: 240.916395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:49.893961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.745161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:17:49.894257Z","caller":"traceutil/trace.go:171","msg":"trace[1013080155] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:28881; }","duration":"163.141121ms","start":"2026-09-30T03:17:49.731095Z","end":"2026-09-30T03:17:49.894236Z","steps":["trace[1013080155] 'agreement among raft nodes before linearized reading' (duration: 162.764772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:50.226818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.205135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-09-30T03:17:50.226921Z","caller":"traceutil/trace.go:171","msg":"trace[675377943] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28881; }","duration":"224.381289ms","start":"2026-09-30T03:17:50.002518Z","end":"2026-09-30T03:17:50.226899Z","steps":["trace[675377943] 'range keys from in-memory index tree' (duration: 223.914238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:50.227083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.714236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-30T03:17:50.22721Z","caller":"traceutil/trace.go:171","msg":"trace[2098907511] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:28881; }","duration":"181.927711ms","start":"2026-09-30T03:17:50.045256Z","end":"2026-09-30T03:17:50.227184Z","steps":["trace[2098907511] 'count revisions from in-memory index tree' (duration: 181.593994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:50.227489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.998526ms","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-09-30T03:17:50.22756Z","caller":"traceutil/trace.go:171","msg":"trace[882097750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28881; }","duration":"155.117569ms","start":"2026-09-30T03:17:50.072425Z","end":"2026-09-30T03:17:50.227542Z","steps":["trace[882097750] 'range keys from in-memory index tree' (duration: 154.856333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:50.227238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.904622ms","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-09-30T03:17:50.227784Z","caller":"traceutil/trace.go:171","msg":"trace[1311434524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28881; }","duration":"152.443115ms","start":"2026-09-30T03:17:50.075315Z","end":"2026-09-30T03:17:50.227759Z","steps":["trace[1311434524] 'range keys from in-memory index tree' (duration: 151.718907ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:50.440914Z","caller":"traceutil/trace.go:171","msg":"trace[387130100] transaction","detail":"{read_only:false; response_revision:28883; number_of_response:1; }","duration":"207.175807ms","start":"2026-09-30T03:17:50.233704Z","end":"2026-09-30T03:17:50.44088Z","steps":["trace[387130100] 'process raft request' (duration: 181.830239ms)","trace[387130100] 'compare' (duration: 25.044671ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:17:50.441459Z","caller":"traceutil/trace.go:171","msg":"trace[1137497101] transaction","detail":"{read_only:false; response_revision:28884; number_of_response:1; }","duration":"207.168167ms","start":"2026-09-30T03:17:50.234243Z","end":"2026-09-30T03:17:50.441411Z","steps":["trace[1137497101] 'process raft request' (duration: 206.578513ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:50.44165Z","caller":"traceutil/trace.go:171","msg":"trace[700947024] transaction","detail":"{read_only:false; response_revision:28885; number_of_response:1; }","duration":"204.79267ms","start":"2026-09-30T03:17:50.23682Z","end":"2026-09-30T03:17:50.441612Z","steps":["trace[700947024] 'process raft request' (duration: 204.503603ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:50.753413Z","caller":"traceutil/trace.go:171","msg":"trace[2076863797] transaction","detail":"{read_only:false; response_revision:28886; number_of_response:1; }","duration":"305.867383ms","start":"2026-09-30T03:17:50.447499Z","end":"2026-09-30T03:17:50.753366Z","steps":["trace[2076863797] 'process raft request' (duration: 305.636867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:50.75367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:50.447472Z","time spent":"306.053908ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:17:50.754516Z","caller":"traceutil/trace.go:171","msg":"trace[196327228] transaction","detail":"{read_only:false; response_revision:28887; number_of_response:1; }","duration":"302.82049ms","start":"2026-09-30T03:17:50.451658Z","end":"2026-09-30T03:17:50.754479Z","steps":["trace[196327228] 'process raft request' (duration: 302.545744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:50.754728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:50.451626Z","time spent":"302.979533ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:17:51.071735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.942221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:17:51.071962Z","caller":"traceutil/trace.go:171","msg":"trace[1796949110] transaction","detail":"{read_only:false; response_revision:28890; number_of_response:1; }","duration":"310.32093ms","start":"2026-09-30T03:17:50.761613Z","end":"2026-09-30T03:17:51.071934Z","steps":["trace[1796949110] 'process raft request' (duration: 156.043421ms)","trace[1796949110] 'compare' (duration: 153.666544ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:17:51.072305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:17:50.761578Z","time spent":"310.680309ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:17:51.07247Z","caller":"traceutil/trace.go:171","msg":"trace[849213488] transaction","detail":"{read_only:false; response_revision:28891; number_of_response:1; }","duration":"141.569254ms","start":"2026-09-30T03:17:50.93074Z","end":"2026-09-30T03:17:51.07231Z","steps":["trace[849213488] 'process raft request' (duration: 141.134664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:17:51.273494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.373081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:17:51.273616Z","caller":"traceutil/trace.go:171","msg":"trace[2131200307] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:28891; }","duration":"101.548095ms","start":"2026-09-30T03:17:51.172035Z","end":"2026-09-30T03:17:51.273583Z","steps":["trace[2131200307] 'count revisions from in-memory index tree' (duration: 101.276548ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:17:51.395305Z","caller":"traceutil/trace.go:171","msg":"trace[1579811902] transaction","detail":"{read_only:false; response_revision:28892; number_of_response:1; }","duration":"101.145174ms","start":"2026-09-30T03:17:51.294122Z","end":"2026-09-30T03:17:51.395267Z","steps":["trace[1579811902] 'process raft request' (duration: 100.879077ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:04.649243Z","caller":"traceutil/trace.go:171","msg":"trace[1533180831] transaction","detail":"{read_only:false; response_revision:29062; number_of_response:1; }","duration":"126.39207ms","start":"2026-09-30T03:18:04.522827Z","end":"2026-09-30T03:18:04.649219Z","steps":["trace[1533180831] 'process raft request' (duration: 126.222726ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:04.655034Z","caller":"traceutil/trace.go:171","msg":"trace[1623390801] transaction","detail":"{read_only:false; response_revision:29063; number_of_response:1; }","duration":"129.197917ms","start":"2026-09-30T03:18:04.525786Z","end":"2026-09-30T03:18:04.654983Z","steps":["trace[1623390801] 'process raft request' (duration: 128.925091ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:05.056794Z","caller":"traceutil/trace.go:171","msg":"trace[1665999923] transaction","detail":"{read_only:false; response_revision:29066; number_of_response:1; }","duration":"115.687203ms","start":"2026-09-30T03:18:04.94107Z","end":"2026-09-30T03:18:05.056758Z","steps":["trace[1665999923] 'process raft request' (duration: 113.409899ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:05.296723Z","caller":"traceutil/trace.go:171","msg":"trace[1781799741] linearizableReadLoop","detail":"{readStateIndex:30402; appliedIndex:30401; }","duration":"129.819582ms","start":"2026-09-30T03:18:05.166851Z","end":"2026-09-30T03:18:05.29667Z","steps":["trace[1781799741] 'read index received' (duration: 102.699742ms)","trace[1781799741] 'applied index is now lower than readState.Index' (duration: 27.11817ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:05.296925Z","caller":"traceutil/trace.go:171","msg":"trace[705233263] transaction","detail":"{read_only:false; response_revision:29068; number_of_response:1; }","duration":"159.597827ms","start":"2026-09-30T03:18:05.137271Z","end":"2026-09-30T03:18:05.296869Z","steps":["trace[705233263] 'process raft request' (duration: 132.367234ms)","trace[705233263] 'compare' (duration: 26.893925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:05.29719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.308203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-30T03:18:05.297259Z","caller":"traceutil/trace.go:171","msg":"trace[1877508499] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29068; }","duration":"130.417167ms","start":"2026-09-30T03:18:05.166821Z","end":"2026-09-30T03:18:05.297239Z","steps":["trace[1877508499] 'agreement among raft nodes before linearized reading' (duration: 130.029667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:05.648292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.509225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:05.648717Z","caller":"traceutil/trace.go:171","msg":"trace[861974284] transaction","detail":"{read_only:false; response_revision:29071; number_of_response:1; }","duration":"337.522882ms","start":"2026-09-30T03:18:05.311153Z","end":"2026-09-30T03:18:05.648675Z","steps":["trace[861974284] 'process raft request' (duration: 337.421509ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:05.648663Z","caller":"traceutil/trace.go:171","msg":"trace[541407627] transaction","detail":"{read_only:false; response_revision:29069; number_of_response:1; }","duration":"345.202786ms","start":"2026-09-30T03:18:05.303404Z","end":"2026-09-30T03:18:05.648607Z","steps":["trace[541407627] 'process raft request' (duration: 182.2573ms)","trace[541407627] 'compare' (duration: 160.388735ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:05.64881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.311085Z","time spent":"337.681485ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:05.648858Z","caller":"traceutil/trace.go:171","msg":"trace[66591631] linearizableReadLoop","detail":"{readStateIndex:30405; appliedIndex:30402; }","duration":"318.705491ms","start":"2026-09-30T03:18:05.330131Z","end":"2026-09-30T03:18:05.648837Z","steps":["trace[66591631] 'read index received' (duration: 155.616551ms)","trace[66591631] 'applied index is now lower than readState.Index' (duration: 163.087489ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:05.648901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.303384Z","time spent":"345.39759ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:05.64903Z","caller":"traceutil/trace.go:171","msg":"trace[529511046] transaction","detail":"{read_only:false; response_revision:29070; number_of_response:1; }","duration":"345.468182ms","start":"2026-09-30T03:18:05.303502Z","end":"2026-09-30T03:18:05.64897Z","steps":["trace[529511046] 'process raft request' (duration: 344.953119ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:05.649084Z","caller":"traceutil/trace.go:171","msg":"trace[2096590032] transaction","detail":"{read_only:false; response_revision:29072; number_of_response:1; }","duration":"215.401574ms","start":"2026-09-30T03:18:05.433621Z","end":"2026-09-30T03:18:05.649023Z","steps":["trace[2096590032] 'process raft request' (duration: 215.009485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:05.64919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.033899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-486edbd0-e4e7-4112-9d6e-c61941a98716\" ","response":"range_response_count:1 size:3760"} {"level":"warn","ts":"2026-09-30T03:18:05.649263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.303476Z","time spent":"345.667877ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:05.649309Z","caller":"traceutil/trace.go:171","msg":"trace[1032196737] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-486edbd0-e4e7-4112-9d6e-c61941a98716; range_end:; response_count:1; response_revision:29072; }","duration":"319.205633ms","start":"2026-09-30T03:18:05.330073Z","end":"2026-09-30T03:18:05.649278Z","steps":["trace[1032196737] 'agreement among raft nodes before linearized reading' (duration: 318.922706ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:05.649337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.871569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-30T03:18:05.649371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.330055Z","time spent":"319.305635ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3782,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-486edbd0-e4e7-4112-9d6e-c61941a98716\" "} {"level":"info","ts":"2026-09-30T03:18:05.649386Z","caller":"traceutil/trace.go:171","msg":"trace[114599719] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:29072; }","duration":"201.954521ms","start":"2026-09-30T03:18:05.447415Z","end":"2026-09-30T03:18:05.649369Z","steps":["trace[114599719] 'agreement among raft nodes before linearized reading' (duration: 201.871209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:05.649367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.565967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-09-30T03:18:05.649483Z","caller":"traceutil/trace.go:171","msg":"trace[459064341] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29072; }","duration":"245.70586ms","start":"2026-09-30T03:18:05.403748Z","end":"2026-09-30T03:18:05.649454Z","steps":["trace[459064341] 'agreement among raft nodes before linearized reading' (duration: 245.354102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:05.971527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.238095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:05.97171Z","caller":"traceutil/trace.go:171","msg":"trace[1394566633] transaction","detail":"{read_only:false; response_revision:29073; number_of_response:1; }","duration":"307.669215ms","start":"2026-09-30T03:18:05.663987Z","end":"2026-09-30T03:18:05.971656Z","steps":["trace[1394566633] 'process raft request' (duration: 126.151573ms)","trace[1394566633] 'compare' (duration: 181.080551ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:05.971815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.663967Z","time spent":"307.801947ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:06.010181Z","caller":"traceutil/trace.go:171","msg":"trace[853577847] transaction","detail":"{read_only:false; response_revision:29074; number_of_response:1; }","duration":"337.046529ms","start":"2026-09-30T03:18:05.673095Z","end":"2026-09-30T03:18:06.010142Z","steps":["trace[853577847] 'process raft request' (duration: 336.839334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.010417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.673067Z","time spent":"337.211483ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:06.010523Z","caller":"traceutil/trace.go:171","msg":"trace[883295] linearizableReadLoop","detail":"{readStateIndex:30409; appliedIndex:30406; }","duration":"301.028727ms","start":"2026-09-30T03:18:05.70946Z","end":"2026-09-30T03:18:06.010489Z","steps":["trace[883295] 'read index received' (duration: 80.778716ms)","trace[883295] 'applied index is now lower than readState.Index' (duration: 220.248741ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:06.010532Z","caller":"traceutil/trace.go:171","msg":"trace[923041529] transaction","detail":"{read_only:false; response_revision:29075; number_of_response:1; }","duration":"331.909766ms","start":"2026-09-30T03:18:05.678558Z","end":"2026-09-30T03:18:06.010468Z","steps":["trace[923041529] 'process raft request' (duration: 331.542037ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:06.01062Z","caller":"traceutil/trace.go:171","msg":"trace[501566793] transaction","detail":"{read_only:false; response_revision:29076; number_of_response:1; }","duration":"295.971336ms","start":"2026-09-30T03:18:05.714566Z","end":"2026-09-30T03:18:06.010537Z","steps":["trace[501566793] 'process raft request' (duration: 295.814202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.010782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.311183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-30T03:18:06.010845Z","caller":"traceutil/trace.go:171","msg":"trace[964103725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29076; }","duration":"301.422836ms","start":"2026-09-30T03:18:05.709403Z","end":"2026-09-30T03:18:06.010825Z","steps":["trace[964103725] 'agreement among raft nodes before linearized reading' (duration: 301.18376ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.010879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.678524Z","time spent":"332.189403ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:06.010905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:05.709379Z","time spent":"301.513458ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-30T03:18:06.029858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.479615ms","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-09-30T03:18:06.029953Z","caller":"traceutil/trace.go:171","msg":"trace[857426478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29077; }","duration":"293.64157ms","start":"2026-09-30T03:18:05.73629Z","end":"2026-09-30T03:18:06.029931Z","steps":["trace[857426478] 'agreement among raft nodes before linearized reading' (duration: 293.353083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.333849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.140763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:06.334166Z","caller":"traceutil/trace.go:171","msg":"trace[1626004216] transaction","detail":"{read_only:false; response_revision:29079; number_of_response:1; }","duration":"299.618553ms","start":"2026-09-30T03:18:06.034453Z","end":"2026-09-30T03:18:06.334071Z","steps":["trace[1626004216] 'process raft request' (duration: 181.127413ms)","trace[1626004216] 'compare' (duration: 117.912236ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:06.334312Z","caller":"traceutil/trace.go:171","msg":"trace[1458182548] linearizableReadLoop","detail":"{readStateIndex:30415; appliedIndex:30413; }","duration":"253.021616ms","start":"2026-09-30T03:18:06.081262Z","end":"2026-09-30T03:18:06.334284Z","steps":["trace[1458182548] 'read index received' (duration: 134.230318ms)","trace[1458182548] 'applied index is now lower than readState.Index' (duration: 118.789788ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:06.334369Z","caller":"traceutil/trace.go:171","msg":"trace[897270030] transaction","detail":"{read_only:false; response_revision:29080; number_of_response:1; }","duration":"299.597272ms","start":"2026-09-30T03:18:06.034728Z","end":"2026-09-30T03:18:06.334325Z","steps":["trace[897270030] 'process raft request' (duration: 299.271084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.334502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.085714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:06.334559Z","caller":"traceutil/trace.go:171","msg":"trace[1215728601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29080; }","duration":"227.191196ms","start":"2026-09-30T03:18:06.107349Z","end":"2026-09-30T03:18:06.33454Z","steps":["trace[1215728601] 'agreement among raft nodes before linearized reading' (duration: 227.096824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.334643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.345924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13486"} {"level":"warn","ts":"2026-09-30T03:18:06.334621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.41091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:06.334727Z","caller":"traceutil/trace.go:171","msg":"trace[745722878] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:29080; }","duration":"253.478406ms","start":"2026-09-30T03:18:06.081233Z","end":"2026-09-30T03:18:06.334711Z","steps":["trace[745722878] 'agreement among raft nodes before linearized reading' (duration: 253.173749ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:06.334753Z","caller":"traceutil/trace.go:171","msg":"trace[2069116650] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:29080; }","duration":"146.585974ms","start":"2026-09-30T03:18:06.188136Z","end":"2026-09-30T03:18:06.334722Z","steps":["trace[2069116650] 'agreement among raft nodes before linearized reading' (duration: 146.367198ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:06.53729Z","caller":"traceutil/trace.go:171","msg":"trace[2108438866] transaction","detail":"{read_only:false; response_revision:29081; number_of_response:1; }","duration":"188.621911ms","start":"2026-09-30T03:18:06.348636Z","end":"2026-09-30T03:18:06.537258Z","steps":["trace[2108438866] 'process raft request' (duration: 188.424457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.837019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.676889ms","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-09-30T03:18:06.837099Z","caller":"traceutil/trace.go:171","msg":"trace[1405626169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29081; }","duration":"182.807602ms","start":"2026-09-30T03:18:06.654273Z","end":"2026-09-30T03:18:06.83708Z","steps":["trace[1405626169] 'range keys from in-memory index tree' (duration: 182.495036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.837447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.399223ms","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-09-30T03:18:06.837483Z","caller":"traceutil/trace.go:171","msg":"trace[1186784035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29081; }","duration":"177.449845ms","start":"2026-09-30T03:18:06.660024Z","end":"2026-09-30T03:18:06.837474Z","steps":["trace[1186784035] 'range keys from in-memory index tree' (duration: 177.308671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:06.837713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.265402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-486edbd0-e4e7-4112-9d6e-c61941a98716\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-09-30T03:18:06.837742Z","caller":"traceutil/trace.go:171","msg":"trace[133938620] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-486edbd0-e4e7-4112-9d6e-c61941a98716; range_end:; response_count:1; response_revision:29081; }","duration":"175.293573ms","start":"2026-09-30T03:18:06.662438Z","end":"2026-09-30T03:18:06.837731Z","steps":["trace[133938620] 'range keys from in-memory index tree' (duration: 175.143828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:07.067745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.568407ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:07.067935Z","caller":"traceutil/trace.go:171","msg":"trace[2087035844] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:29082; }","duration":"109.801752ms","start":"2026-09-30T03:18:06.958104Z","end":"2026-09-30T03:18:07.067906Z","steps":["trace[2087035844] 'range keys from in-memory index tree' (duration: 109.541806ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:07.165529Z","caller":"traceutil/trace.go:171","msg":"trace[2121177535] linearizableReadLoop","detail":"{readStateIndex:30419; appliedIndex:30416; }","duration":"298.966518ms","start":"2026-09-30T03:18:06.866539Z","end":"2026-09-30T03:18:07.165505Z","steps":["trace[2121177535] 'read index received' (duration: 51.244449ms)","trace[2121177535] 'applied index is now lower than readState.Index' (duration: 247.721109ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:07.165581Z","caller":"traceutil/trace.go:171","msg":"trace[1918926669] transaction","detail":"{read_only:false; response_revision:29083; number_of_response:1; }","duration":"322.684446ms","start":"2026-09-30T03:18:06.842853Z","end":"2026-09-30T03:18:07.165537Z","steps":["trace[1918926669] 'process raft request' (duration: 322.264276ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:07.165648Z","caller":"traceutil/trace.go:171","msg":"trace[1414195628] transaction","detail":"{read_only:false; response_revision:29084; number_of_response:1; }","duration":"309.659313ms","start":"2026-09-30T03:18:06.855949Z","end":"2026-09-30T03:18:07.165609Z","steps":["trace[1414195628] 'process raft request' (duration: 309.51777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:07.165791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.245764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-30T03:18:07.165798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:06.842819Z","time spent":"322.85421ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:07.165823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:06.855925Z","time spent":"309.792687ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:07.165864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.801529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:07.165878Z","caller":"traceutil/trace.go:171","msg":"trace[2018773113] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:29084; }","duration":"161.86131ms","start":"2026-09-30T03:18:07.004013Z","end":"2026-09-30T03:18:07.165874Z","steps":["trace[2018773113] 'agreement among raft nodes before linearized reading' (duration: 161.83121ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:07.165818Z","caller":"traceutil/trace.go:171","msg":"trace[2088095186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29084; }","duration":"299.317126ms","start":"2026-09-30T03:18:06.866493Z","end":"2026-09-30T03:18:07.16581Z","steps":["trace[2088095186] 'agreement among raft nodes before linearized reading' (duration: 299.118641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:07.439093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.887177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-30T03:18:07.439213Z","caller":"traceutil/trace.go:171","msg":"trace[727614729] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29084; }","duration":"269.02293ms","start":"2026-09-30T03:18:07.170163Z","end":"2026-09-30T03:18:07.439186Z","steps":["trace[727614729] 'range keys from in-memory index tree' (duration: 268.673412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:07.439272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.30542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:07.439427Z","caller":"traceutil/trace.go:171","msg":"trace[1694553755] transaction","detail":"{read_only:false; response_revision:29085; number_of_response:1; }","duration":"267.286268ms","start":"2026-09-30T03:18:07.172097Z","end":"2026-09-30T03:18:07.439383Z","steps":["trace[1694553755] 'process raft request' (duration: 79.725651ms)","trace[1694553755] 'compare' (duration: 187.111346ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:07.439492Z","caller":"traceutil/trace.go:171","msg":"trace[484338767] transaction","detail":"{read_only:false; response_revision:29086; number_of_response:1; }","duration":"136.863551ms","start":"2026-09-30T03:18:07.302606Z","end":"2026-09-30T03:18:07.439469Z","steps":["trace[484338767] 'process raft request' (duration: 136.795599ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:07.439707Z","caller":"traceutil/trace.go:171","msg":"trace[866281861] linearizableReadLoop","detail":"{readStateIndex:30420; appliedIndex:30419; }","duration":"150.431217ms","start":"2026-09-30T03:18:07.289246Z","end":"2026-09-30T03:18:07.439677Z","steps":["trace[866281861] 'read index received' (duration: 54.622µs)","trace[866281861] 'applied index is now lower than readState.Index' (duration: 150.375495ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:07.44023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.98105ms","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-09-30T03:18:07.440265Z","caller":"traceutil/trace.go:171","msg":"trace[1235404897] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:29086; }","duration":"151.062102ms","start":"2026-09-30T03:18:07.28919Z","end":"2026-09-30T03:18:07.440252Z","steps":["trace[1235404897] 'agreement among raft nodes before linearized reading' (duration: 150.910838ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:07.745883Z","caller":"traceutil/trace.go:171","msg":"trace[1722214613] linearizableReadLoop","detail":"{readStateIndex:30423; appliedIndex:30422; }","duration":"193.178301ms","start":"2026-09-30T03:18:07.552669Z","end":"2026-09-30T03:18:07.745847Z","steps":["trace[1722214613] 'read index received' (duration: 152.082875ms)","trace[1722214613] 'applied index is now lower than readState.Index' (duration: 41.093976ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:07.745904Z","caller":"traceutil/trace.go:171","msg":"trace[1117103413] transaction","detail":"{read_only:false; response_revision:29088; number_of_response:1; }","duration":"300.652558ms","start":"2026-09-30T03:18:07.445207Z","end":"2026-09-30T03:18:07.745859Z","steps":["trace[1117103413] 'process raft request' (duration: 259.636075ms)","trace[1117103413] 'compare' (duration: 40.649774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:07.746001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.307384ms","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-09-30T03:18:07.746051Z","caller":"traceutil/trace.go:171","msg":"trace[1623168344] 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:29088; }","duration":"193.425057ms","start":"2026-09-30T03:18:07.55261Z","end":"2026-09-30T03:18:07.746035Z","steps":["trace[1623168344] 'agreement among raft nodes before linearized reading' (duration: 193.316434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:07.746161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:07.445183Z","time spent":"300.838622ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:08.021062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.725647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:08.021323Z","caller":"traceutil/trace.go:171","msg":"trace[716071140] transaction","detail":"{read_only:false; response_revision:29089; number_of_response:1; }","duration":"265.067285ms","start":"2026-09-30T03:18:07.756212Z","end":"2026-09-30T03:18:08.02128Z","steps":["trace[716071140] 'process raft request' (duration: 155.513868ms)","trace[716071140] 'compare' (duration: 108.063161ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:08.021381Z","caller":"traceutil/trace.go:171","msg":"trace[547247037] linearizableReadLoop","detail":"{readStateIndex:30424; appliedIndex:30423; }","duration":"262.952893ms","start":"2026-09-30T03:18:07.758391Z","end":"2026-09-30T03:18:08.021344Z","steps":["trace[547247037] 'read index received' (duration: 153.288324ms)","trace[547247037] 'applied index is now lower than readState.Index' (duration: 109.661019ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:08.021584Z","caller":"traceutil/trace.go:171","msg":"trace[995878576] transaction","detail":"{read_only:false; response_revision:29090; number_of_response:1; }","duration":"261.582421ms","start":"2026-09-30T03:18:07.759984Z","end":"2026-09-30T03:18:08.021566Z","steps":["trace[995878576] 'process raft request' (duration: 261.246183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.021614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.20076ms","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-09-30T03:18:08.021751Z","caller":"traceutil/trace.go:171","msg":"trace[852835024] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:29091; }","duration":"263.412574ms","start":"2026-09-30T03:18:07.758307Z","end":"2026-09-30T03:18:08.02172Z","steps":["trace[852835024] 'agreement among raft nodes before linearized reading' (duration: 263.127768ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.022063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.81384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.92\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-30T03:18:08.022164Z","caller":"traceutil/trace.go:171","msg":"trace[387192072] range","detail":"{range_begin:/registry/masterleases/162.253.55.92; range_end:; response_count:1; response_revision:29092; }","duration":"209.960673ms","start":"2026-09-30T03:18:07.81218Z","end":"2026-09-30T03:18:08.02214Z","steps":["trace[387192072] 'agreement among raft nodes before linearized reading' (duration: 209.639666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.022226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.394524ms","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-09-30T03:18:08.022281Z","caller":"traceutil/trace.go:171","msg":"trace[1396090576] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:29092; }","duration":"132.510027ms","start":"2026-09-30T03:18:07.889758Z","end":"2026-09-30T03:18:08.022268Z","steps":["trace[1396090576] 'agreement among raft nodes before linearized reading' (duration: 132.418004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.022367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.268818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-486edbd0-e4e7-4112-9d6e-c61941a98716\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-09-30T03:18:08.022426Z","caller":"traceutil/trace.go:171","msg":"trace[1442677170] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-486edbd0-e4e7-4112-9d6e-c61941a98716; range_end:; response_count:1; response_revision:29092; }","duration":"179.3792ms","start":"2026-09-30T03:18:07.843032Z","end":"2026-09-30T03:18:08.022411Z","steps":["trace[1442677170] 'agreement among raft nodes before linearized reading' (duration: 179.172535ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:08.391861Z","caller":"traceutil/trace.go:171","msg":"trace[1554156764] transaction","detail":"{read_only:false; response_revision:29093; number_of_response:1; }","duration":"356.404014ms","start":"2026-09-30T03:18:08.035415Z","end":"2026-09-30T03:18:08.391819Z","steps":["trace[1554156764] 'process raft request' (duration: 329.162021ms)","trace[1554156764] 'compare' (duration: 27.011787ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:08.392084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:08.035386Z","time spent":"356.576308ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:08.400114Z","caller":"traceutil/trace.go:171","msg":"trace[650024359] linearizableReadLoop","detail":"{readStateIndex:30430; appliedIndex:30428; }","duration":"350.486712ms","start":"2026-09-30T03:18:08.049594Z","end":"2026-09-30T03:18:08.400081Z","steps":["trace[650024359] 'read index received' (duration: 315.001212ms)","trace[650024359] 'applied index is now lower than readState.Index' (duration: 35.48402ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:08.400177Z","caller":"traceutil/trace.go:171","msg":"trace[319306550] transaction","detail":"{read_only:false; response_revision:29094; number_of_response:1; }","duration":"355.193346ms","start":"2026-09-30T03:18:08.044937Z","end":"2026-09-30T03:18:08.40013Z","steps":["trace[319306550] 'process raft request' (duration: 354.95539ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.400387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:08.044905Z","time spent":"355.3639ms","remote":"127.0.0.1:57456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:08.400521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.906723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3191"} {"level":"info","ts":"2026-09-30T03:18:08.40062Z","caller":"traceutil/trace.go:171","msg":"trace[385661264] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:29094; }","duration":"351.046676ms","start":"2026-09-30T03:18:08.049552Z","end":"2026-09-30T03:18:08.400599Z","steps":["trace[385661264] 'agreement among raft nodes before linearized reading' (duration: 350.678317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.400669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:08.049538Z","time spent":"351.121368ms","remote":"127.0.0.1:33134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3213,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-30T03:18:08.405873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.477518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:08.405935Z","caller":"traceutil/trace.go:171","msg":"trace[635560300] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:29095; }","duration":"160.58545ms","start":"2026-09-30T03:18:08.245336Z","end":"2026-09-30T03:18:08.405921Z","steps":["trace[635560300] 'agreement among raft nodes before linearized reading' (duration: 160.484868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.406212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.824928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-30T03:18:08.406238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.914631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-30T03:18:08.406281Z","caller":"traceutil/trace.go:171","msg":"trace[1090709443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29095; }","duration":"299.941672ms","start":"2026-09-30T03:18:08.106323Z","end":"2026-09-30T03:18:08.406264Z","steps":["trace[1090709443] 'agreement among raft nodes before linearized reading' (duration: 299.847999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.40633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:08.106304Z","time spent":"300.018823ms","remote":"127.0.0.1:57432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-30T03:18:08.406331Z","caller":"traceutil/trace.go:171","msg":"trace[2028917974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29095; }","duration":"323.040775ms","start":"2026-09-30T03:18:08.083271Z","end":"2026-09-30T03:18:08.406312Z","steps":["trace[2028917974] 'agreement among raft nodes before linearized reading' (duration: 322.820919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:08.406258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.219566ms","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-09-30T03:18:08.406373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:08.083256Z","time spent":"323.108096ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-30T03:18:08.406459Z","caller":"traceutil/trace.go:171","msg":"trace[204862198] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:29095; }","duration":"179.482103ms","start":"2026-09-30T03:18:08.226918Z","end":"2026-09-30T03:18:08.4064Z","steps":["trace[204862198] 'agreement among raft nodes before linearized reading' (duration: 179.235367ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:13.652807Z","caller":"traceutil/trace.go:171","msg":"trace[366872735] transaction","detail":"{read_only:false; response_revision:29158; number_of_response:1; }","duration":"136.979024ms","start":"2026-09-30T03:18:13.515797Z","end":"2026-09-30T03:18:13.652776Z","steps":["trace[366872735] 'process raft request' (duration: 136.046091ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:13.850674Z","caller":"traceutil/trace.go:171","msg":"trace[1664312032] transaction","detail":"{read_only:false; response_revision:29159; number_of_response:1; }","duration":"190.428445ms","start":"2026-09-30T03:18:13.660207Z","end":"2026-09-30T03:18:13.850636Z","steps":["trace[1664312032] 'process raft request' (duration: 130.063128ms)","trace[1664312032] 'compare' (duration: 59.886226ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:13.930495Z","caller":"traceutil/trace.go:171","msg":"trace[412429955] transaction","detail":"{read_only:false; response_revision:29160; number_of_response:1; }","duration":"267.676527ms","start":"2026-09-30T03:18:13.662783Z","end":"2026-09-30T03:18:13.93046Z","steps":["trace[412429955] 'process raft request' (duration: 267.39659ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:13.930929Z","caller":"traceutil/trace.go:171","msg":"trace[1075654700] transaction","detail":"{read_only:false; response_revision:29161; number_of_response:1; }","duration":"266.466288ms","start":"2026-09-30T03:18:13.664449Z","end":"2026-09-30T03:18:13.930916Z","steps":["trace[1075654700] 'process raft request' (duration: 265.909815ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:13.930937Z","caller":"traceutil/trace.go:171","msg":"trace[1253900683] transaction","detail":"{read_only:false; response_revision:29162; number_of_response:1; }","duration":"266.080629ms","start":"2026-09-30T03:18:13.664843Z","end":"2026-09-30T03:18:13.930924Z","steps":["trace[1253900683] 'process raft request' (duration: 265.811073ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:45.054222Z","caller":"traceutil/trace.go:171","msg":"trace[1924861288] transaction","detail":"{read_only:false; response_revision:29454; number_of_response:1; }","duration":"125.852256ms","start":"2026-09-30T03:18:44.928335Z","end":"2026-09-30T03:18:45.054187Z","steps":["trace[1924861288] 'process raft request' (duration: 125.567009ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:45.273195Z","caller":"traceutil/trace.go:171","msg":"trace[2108781997] transaction","detail":"{read_only:false; response_revision:29456; number_of_response:1; }","duration":"139.631597ms","start":"2026-09-30T03:18:45.133528Z","end":"2026-09-30T03:18:45.27316Z","steps":["trace[2108781997] 'process raft request' (duration: 137.66529ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:45.439454Z","caller":"traceutil/trace.go:171","msg":"trace[1849480837] transaction","detail":"{read_only:false; response_revision:29457; number_of_response:1; }","duration":"108.819429ms","start":"2026-09-30T03:18:45.330574Z","end":"2026-09-30T03:18:45.439393Z","steps":["trace[1849480837] 'process raft request' (duration: 106.825341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:45.74374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.939054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:45.74422Z","caller":"traceutil/trace.go:171","msg":"trace[856895329] linearizableReadLoop","detail":"{readStateIndex:30810; appliedIndex:30809; }","duration":"219.499832ms","start":"2026-09-30T03:18:45.52454Z","end":"2026-09-30T03:18:45.744039Z","steps":["trace[856895329] 'read index received' (duration: 49.019895ms)","trace[856895329] 'applied index is now lower than readState.Index' (duration: 170.477517ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:45.744161Z","caller":"traceutil/trace.go:171","msg":"trace[2001175414] transaction","detail":"{read_only:false; response_revision:29458; number_of_response:1; }","duration":"299.48575ms","start":"2026-09-30T03:18:45.444638Z","end":"2026-09-30T03:18:45.744123Z","steps":["trace[2001175414] 'process raft request' (duration: 128.956302ms)","trace[2001175414] 'compare' (duration: 169.748169ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:45.74448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.940513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-30T03:18:45.744548Z","caller":"traceutil/trace.go:171","msg":"trace[1275271289] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:29458; }","duration":"220.050056ms","start":"2026-09-30T03:18:45.524474Z","end":"2026-09-30T03:18:45.744525Z","steps":["trace[1275271289] 'agreement among raft nodes before linearized reading' (duration: 219.841101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:45.997709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.30143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:45.9979Z","caller":"traceutil/trace.go:171","msg":"trace[412938710] transaction","detail":"{read_only:false; response_revision:29459; number_of_response:1; }","duration":"309.09345ms","start":"2026-09-30T03:18:45.688762Z","end":"2026-09-30T03:18:45.997855Z","steps":["trace[412938710] 'process raft request' (duration: 194.507493ms)","trace[412938710] 'compare' (duration: 113.920261ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:45.99797Z","caller":"traceutil/trace.go:171","msg":"trace[1281073846] linearizableReadLoop","detail":"{readStateIndex:30811; appliedIndex:30810; }","duration":"253.63878ms","start":"2026-09-30T03:18:45.744275Z","end":"2026-09-30T03:18:45.997914Z","steps":["trace[1281073846] 'read index received' (duration: 138.984402ms)","trace[1281073846] 'applied index is now lower than readState.Index' (duration: 114.651058ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:45.998112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:45.688707Z","time spent":"309.283924ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:45.998155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.864893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-30T03:18:45.998201Z","caller":"traceutil/trace.go:171","msg":"trace[1340368624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29460; }","duration":"300.944195ms","start":"2026-09-30T03:18:45.697238Z","end":"2026-09-30T03:18:45.998182Z","steps":["trace[1340368624] 'agreement among raft nodes before linearized reading' (duration: 300.808522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:45.998225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.930257ms","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-09-30T03:18:45.998289Z","caller":"traceutil/trace.go:171","msg":"trace[980715060] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:29460; }","duration":"415.06574ms","start":"2026-09-30T03:18:45.583208Z","end":"2026-09-30T03:18:45.998274Z","steps":["trace[980715060] 'agreement among raft nodes before linearized reading' (duration: 414.904896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:45.998331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:45.583183Z","time spent":"415.136952ms","remote":"127.0.0.1:34452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-09-30T03:18:45.99825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:45.697212Z","time spent":"301.025206ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-30T03:18:45.998503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.699646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-039326ee-343e-4680-9544-c35ee53c4064\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-09-30T03:18:45.998633Z","caller":"traceutil/trace.go:171","msg":"trace[1711906521] range","detail":"{range_begin:/registry/persistentvolumes/pvc-039326ee-343e-4680-9544-c35ee53c4064; range_end:; response_count:1; response_revision:29460; }","duration":"249.84963ms","start":"2026-09-30T03:18:45.748753Z","end":"2026-09-30T03:18:45.998603Z","steps":["trace[1711906521] 'agreement among raft nodes before linearized reading' (duration: 249.409289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:46.372988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.287334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:46.373207Z","caller":"traceutil/trace.go:171","msg":"trace[1929484088] transaction","detail":"{read_only:false; response_revision:29462; number_of_response:1; }","duration":"189.654227ms","start":"2026-09-30T03:18:46.183532Z","end":"2026-09-30T03:18:46.373186Z","steps":["trace[1929484088] 'process raft request' (duration: 189.575275ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:46.373196Z","caller":"traceutil/trace.go:171","msg":"trace[468378995] transaction","detail":"{read_only:false; response_revision:29461; number_of_response:1; }","duration":"367.697646ms","start":"2026-09-30T03:18:46.005456Z","end":"2026-09-30T03:18:46.373153Z","steps":["trace[468378995] 'process raft request' (duration: 144.129196ms)","trace[468378995] 'compare' (duration: 223.007056ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:46.373227Z","caller":"traceutil/trace.go:171","msg":"trace[239727431] linearizableReadLoop","detail":"{readStateIndex:30813; appliedIndex:30812; }","duration":"266.986511ms","start":"2026-09-30T03:18:46.106187Z","end":"2026-09-30T03:18:46.373174Z","steps":["trace[239727431] 'read index received' (duration: 43.455002ms)","trace[239727431] 'applied index is now lower than readState.Index' (duration: 223.526319ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:46.373393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.188336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-30T03:18:46.373414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:46.005418Z","time spent":"367.87634ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:46.373496Z","caller":"traceutil/trace.go:171","msg":"trace[794865735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29462; }","duration":"267.35947ms","start":"2026-09-30T03:18:46.106112Z","end":"2026-09-30T03:18:46.373472Z","steps":["trace[794865735] 'agreement among raft nodes before linearized reading' (duration: 267.196016ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:46.663217Z","caller":"traceutil/trace.go:171","msg":"trace[1139674090] linearizableReadLoop","detail":"{readStateIndex:30815; appliedIndex:30814; }","duration":"289.839839ms","start":"2026-09-30T03:18:46.373348Z","end":"2026-09-30T03:18:46.663188Z","steps":["trace[1139674090] 'read index received' (duration: 258.241801ms)","trace[1139674090] 'applied index is now lower than readState.Index' (duration: 31.596148ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:46.663351Z","caller":"traceutil/trace.go:171","msg":"trace[1862965675] transaction","detail":"{read_only:false; response_revision:29463; number_of_response:1; }","duration":"475.94169ms","start":"2026-09-30T03:18:46.187346Z","end":"2026-09-30T03:18:46.663288Z","steps":["trace[1862965675] 'process raft request' (duration: 444.310672ms)","trace[1862965675] 'compare' (duration: 31.345501ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:46.663476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.759259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-30T03:18:46.663515Z","caller":"traceutil/trace.go:171","msg":"trace[1547654986] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29463; }","duration":"433.871531ms","start":"2026-09-30T03:18:46.229631Z","end":"2026-09-30T03:18:46.663502Z","steps":["trace[1547654986] 'agreement among raft nodes before linearized reading' (duration: 433.691927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:46.663556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:46.229603Z","time spent":"433.944213ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-09-30T03:18:46.663613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:46.187311Z","time spent":"476.152445ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:46.699575Z","caller":"traceutil/trace.go:171","msg":"trace[704997964] transaction","detail":"{read_only:false; response_revision:29465; number_of_response:1; }","duration":"156.906502ms","start":"2026-09-30T03:18:46.542634Z","end":"2026-09-30T03:18:46.69954Z","steps":["trace[704997964] 'process raft request' (duration: 156.82645ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:46.700287Z","caller":"traceutil/trace.go:171","msg":"trace[504826506] transaction","detail":"{read_only:false; response_revision:29464; number_of_response:1; }","duration":"181.567873ms","start":"2026-09-30T03:18:46.518634Z","end":"2026-09-30T03:18:46.700202Z","steps":["trace[504826506] 'process raft request' (duration: 180.60305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:46.701028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.099858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:46.701108Z","caller":"traceutil/trace.go:171","msg":"trace[956503668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29465; }","duration":"321.244231ms","start":"2026-09-30T03:18:46.379844Z","end":"2026-09-30T03:18:46.701088Z","steps":["trace[956503668] 'agreement among raft nodes before linearized reading' (duration: 321.094468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:46.701164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:46.379824Z","time spent":"321.328824ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-30T03:18:46.701227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.835811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-09-30T03:18:46.701341Z","caller":"traceutil/trace.go:171","msg":"trace[1592082843] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29465; }","duration":"189.991045ms","start":"2026-09-30T03:18:46.511322Z","end":"2026-09-30T03:18:46.701313Z","steps":["trace[1592082843] 'agreement among raft nodes before linearized reading' (duration: 189.765439ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:47.057932Z","caller":"traceutil/trace.go:171","msg":"trace[1588117456] transaction","detail":"{read_only:false; response_revision:29469; number_of_response:1; }","duration":"310.901844ms","start":"2026-09-30T03:18:46.746999Z","end":"2026-09-30T03:18:47.057901Z","steps":["trace[1588117456] 'process raft request' (duration: 310.711689ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:47.058145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:46.746983Z","time spent":"311.038437ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:47.245871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.650082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-09-30T03:18:47.245982Z","caller":"traceutil/trace.go:171","msg":"trace[2042873734] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29470; }","duration":"120.883978ms","start":"2026-09-30T03:18:47.12507Z","end":"2026-09-30T03:18:47.245954Z","steps":["trace[2042873734] 'range keys from in-memory index tree' (duration: 120.326894ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:47.397209Z","caller":"traceutil/trace.go:171","msg":"trace[844384683] linearizableReadLoop","detail":"{readStateIndex:30823; appliedIndex:30822; }","duration":"116.756609ms","start":"2026-09-30T03:18:47.28042Z","end":"2026-09-30T03:18:47.397176Z","steps":["trace[844384683] 'read index received' (duration: 116.470512ms)","trace[844384683] 'applied index is now lower than readState.Index' (duration: 285.077µs)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:47.397535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.076477ms","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-09-30T03:18:47.39758Z","caller":"traceutil/trace.go:171","msg":"trace[2059698819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29471; }","duration":"117.177779ms","start":"2026-09-30T03:18:47.280388Z","end":"2026-09-30T03:18:47.397566Z","steps":["trace[2059698819] 'agreement among raft nodes before linearized reading' (duration: 116.912753ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:47.398057Z","caller":"traceutil/trace.go:171","msg":"trace[2082473816] transaction","detail":"{read_only:false; response_revision:29471; number_of_response:1; }","duration":"127.434005ms","start":"2026-09-30T03:18:47.27061Z","end":"2026-09-30T03:18:47.398044Z","steps":["trace[2082473816] 'process raft request' (duration: 126.369029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:47.672861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.014313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:47.672998Z","caller":"traceutil/trace.go:171","msg":"trace[295647311] linearizableReadLoop","detail":"{readStateIndex:30824; appliedIndex:30823; }","duration":"268.671101ms","start":"2026-09-30T03:18:47.404303Z","end":"2026-09-30T03:18:47.672974Z","steps":["trace[295647311] 'read index received' (duration: 134.691509ms)","trace[295647311] 'applied index is now lower than readState.Index' (duration: 133.977942ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:47.673126Z","caller":"traceutil/trace.go:171","msg":"trace[947543347] transaction","detail":"{read_only:false; response_revision:29472; number_of_response:1; }","duration":"269.339928ms","start":"2026-09-30T03:18:47.403738Z","end":"2026-09-30T03:18:47.673078Z","steps":["trace[947543347] 'process raft request' (duration: 135.000497ms)","trace[947543347] 'compare' (duration: 133.756977ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:47.673209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.909157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-09-30T03:18:47.673243Z","caller":"traceutil/trace.go:171","msg":"trace[1255758381] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29472; }","duration":"268.967888ms","start":"2026-09-30T03:18:47.404264Z","end":"2026-09-30T03:18:47.673231Z","steps":["trace[1255758381] 'agreement among raft nodes before linearized reading' (duration: 268.805974ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:47.68766Z","caller":"traceutil/trace.go:171","msg":"trace[1853793007] transaction","detail":"{read_only:false; response_revision:29473; number_of_response:1; }","duration":"239.627784ms","start":"2026-09-30T03:18:47.447996Z","end":"2026-09-30T03:18:47.687623Z","steps":["trace[1853793007] 'process raft request' (duration: 239.428769ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:47.931681Z","caller":"traceutil/trace.go:171","msg":"trace[8616648] linearizableReadLoop","detail":"{readStateIndex:30827; appliedIndex:30826; }","duration":"121.935303ms","start":"2026-09-30T03:18:47.809714Z","end":"2026-09-30T03:18:47.93165Z","steps":["trace[8616648] 'read index received' (duration: 88.109972ms)","trace[8616648] 'applied index is now lower than readState.Index' (duration: 33.823751ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:47.932029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.316493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"warn","ts":"2026-09-30T03:18:47.932105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.321703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.92\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-30T03:18:47.932127Z","caller":"traceutil/trace.go:171","msg":"trace[718135618] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29475; }","duration":"122.489116ms","start":"2026-09-30T03:18:47.809618Z","end":"2026-09-30T03:18:47.932108Z","steps":["trace[718135618] 'agreement among raft nodes before linearized reading' (duration: 122.24025ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:47.932188Z","caller":"traceutil/trace.go:171","msg":"trace[1726917728] transaction","detail":"{read_only:false; response_revision:29475; number_of_response:1; }","duration":"180.160959ms","start":"2026-09-30T03:18:47.752017Z","end":"2026-09-30T03:18:47.932178Z","steps":["trace[1726917728] 'process raft request' (duration: 145.913128ms)","trace[1726917728] 'compare' (duration: 33.557275ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:47.932143Z","caller":"traceutil/trace.go:171","msg":"trace[336032924] range","detail":"{range_begin:/registry/masterleases/162.253.55.92; range_end:; response_count:1; response_revision:29475; }","duration":"117.426565ms","start":"2026-09-30T03:18:47.814705Z","end":"2026-09-30T03:18:47.932131Z","steps":["trace[336032924] 'agreement among raft nodes before linearized reading' (duration: 117.253741ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:48.05168Z","caller":"traceutil/trace.go:171","msg":"trace[1990433550] linearizableReadLoop","detail":"{readStateIndex:30828; appliedIndex:30827; }","duration":"114.358941ms","start":"2026-09-30T03:18:47.937303Z","end":"2026-09-30T03:18:48.051662Z","steps":["trace[1990433550] 'read index received' (duration: 113.746227ms)","trace[1990433550] 'applied index is now lower than readState.Index' (duration: 611.654µs)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:48.051858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.547516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-09-30T03:18:48.051893Z","caller":"traceutil/trace.go:171","msg":"trace[1152355339] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29476; }","duration":"114.628878ms","start":"2026-09-30T03:18:47.937254Z","end":"2026-09-30T03:18:48.051883Z","steps":["trace[1152355339] 'agreement among raft nodes before linearized reading' (duration: 114.495645ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:48.436212Z","caller":"traceutil/trace.go:171","msg":"trace[24620162] transaction","detail":"{read_only:false; response_revision:29478; number_of_response:1; }","duration":"275.396923ms","start":"2026-09-30T03:18:48.160784Z","end":"2026-09-30T03:18:48.436181Z","steps":["trace[24620162] 'process raft request' (duration: 275.080585ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:48.436399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.313973ms","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-09-30T03:18:48.436502Z","caller":"traceutil/trace.go:171","msg":"trace[2054314791] 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:29478; }","duration":"258.490117ms","start":"2026-09-30T03:18:48.177975Z","end":"2026-09-30T03:18:48.436465Z","steps":["trace[2054314791] 'agreement among raft nodes before linearized reading' (duration: 258.304112ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:48.436144Z","caller":"traceutil/trace.go:171","msg":"trace[1597141282] linearizableReadLoop","detail":"{readStateIndex:30831; appliedIndex:30830; }","duration":"258.071037ms","start":"2026-09-30T03:18:48.178038Z","end":"2026-09-30T03:18:48.436109Z","steps":["trace[1597141282] 'read index received' (duration: 257.78594ms)","trace[1597141282] 'applied index is now lower than readState.Index' (duration: 283.467µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:48.693621Z","caller":"traceutil/trace.go:171","msg":"trace[929785052] transaction","detail":"{read_only:false; number_of_response:0; response_revision:29479; }","duration":"236.731394ms","start":"2026-09-30T03:18:48.456864Z","end":"2026-09-30T03:18:48.693596Z","steps":["trace[929785052] 'process raft request' (duration: 236.625022ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:48.699035Z","caller":"traceutil/trace.go:171","msg":"trace[1524317962] transaction","detail":"{read_only:false; response_revision:29480; number_of_response:1; }","duration":"241.380606ms","start":"2026-09-30T03:18:48.457623Z","end":"2026-09-30T03:18:48.699003Z","steps":["trace[1524317962] 'process raft request' (duration: 241.113179ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:48.699298Z","caller":"traceutil/trace.go:171","msg":"trace[1059191839] linearizableReadLoop","detail":"{readStateIndex:30834; appliedIndex:30833; }","duration":"238.941337ms","start":"2026-09-30T03:18:48.460322Z","end":"2026-09-30T03:18:48.699264Z","steps":["trace[1059191839] 'read index received' (duration: 233.305782ms)","trace[1059191839] 'applied index is now lower than readState.Index' (duration: 5.634085ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:48.699544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.194293ms","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-09-30T03:18:48.699595Z","caller":"traceutil/trace.go:171","msg":"trace[194962980] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:29481; }","duration":"239.280086ms","start":"2026-09-30T03:18:48.460303Z","end":"2026-09-30T03:18:48.699583Z","steps":["trace[194962980] 'agreement among raft nodes before linearized reading' (duration: 239.175933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:49.823447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.130935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-30T03:18:49.823575Z","caller":"traceutil/trace.go:171","msg":"trace[766994602] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:29489; }","duration":"305.386951ms","start":"2026-09-30T03:18:49.518151Z","end":"2026-09-30T03:18:49.823538Z","steps":["trace[766994602] 'count revisions from in-memory index tree' (duration: 304.990792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:49.823712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:49.518122Z","time spent":"305.497874ms","remote":"127.0.0.1:57898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-09-30T03:18:49.823929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.732335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:49.824006Z","caller":"traceutil/trace.go:171","msg":"trace[1582324366] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:29489; }","duration":"505.884618ms","start":"2026-09-30T03:18:49.3181Z","end":"2026-09-30T03:18:49.823985Z","steps":["trace[1582324366] 'count revisions from in-memory index tree' (duration: 505.634242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:49.824049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:49.318072Z","time spent":"505.96748ms","remote":"127.0.0.1:49280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-09-30T03:18:49.824421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.570476ms","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:6"} {"level":"info","ts":"2026-09-30T03:18:49.824481Z","caller":"traceutil/trace.go:171","msg":"trace[1559236054] 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:29489; }","duration":"144.644217ms","start":"2026-09-30T03:18:49.679817Z","end":"2026-09-30T03:18:49.824462Z","steps":["trace[1559236054] 'count revisions from in-memory index tree' (duration: 144.537105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:49.824825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.297185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:49.824867Z","caller":"traceutil/trace.go:171","msg":"trace[1976407206] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:29489; }","duration":"261.385257ms","start":"2026-09-30T03:18:49.563472Z","end":"2026-09-30T03:18:49.824857Z","steps":["trace[1976407206] 'count revisions from in-memory index tree' (duration: 261.205643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:49.82489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.546684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:49.82493Z","caller":"traceutil/trace.go:171","msg":"trace[1334890878] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:29489; }","duration":"247.602576ms","start":"2026-09-30T03:18:49.577317Z","end":"2026-09-30T03:18:49.824919Z","steps":["trace[1334890878] 'count revisions from in-memory index tree' (duration: 247.505403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:49.825297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.345396ms","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-09-30T03:18:49.825324Z","caller":"traceutil/trace.go:171","msg":"trace[932989951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29489; }","duration":"148.373227ms","start":"2026-09-30T03:18:49.676944Z","end":"2026-09-30T03:18:49.825317Z","steps":["trace[932989951] 'range keys from in-memory index tree' (duration: 148.220624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:49.825295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.326348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-09-30T03:18:49.82613Z","caller":"traceutil/trace.go:171","msg":"trace[389933478] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29489; }","duration":"225.170949ms","start":"2026-09-30T03:18:49.60093Z","end":"2026-09-30T03:18:49.826101Z","steps":["trace[389933478] 'range keys from in-memory index tree' (duration: 224.177744ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:49.826563Z","caller":"traceutil/trace.go:171","msg":"trace[749467609] transaction","detail":"{read_only:false; response_revision:29490; number_of_response:1; }","duration":"132.024275ms","start":"2026-09-30T03:18:49.694522Z","end":"2026-09-30T03:18:49.826546Z","steps":["trace[749467609] 'process raft request' (duration: 131.8004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.333741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477906261589825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-30T03:18:50.390254Z","caller":"traceutil/trace.go:171","msg":"trace[1512134019] transaction","detail":"{read_only:false; response_revision:29491; number_of_response:1; }","duration":"557.042175ms","start":"2026-09-30T03:18:49.833182Z","end":"2026-09-30T03:18:50.390224Z","steps":["trace[1512134019] 'process raft request' (duration: 556.8478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.390421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:49.83316Z","time spent":"557.170767ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:50.434268Z","caller":"traceutil/trace.go:171","msg":"trace[2111298523] linearizableReadLoop","detail":"{readStateIndex:30846; appliedIndex:30844; }","duration":"600.863995ms","start":"2026-09-30T03:18:49.833375Z","end":"2026-09-30T03:18:50.434239Z","steps":["trace[2111298523] 'read index received' (duration: 556.711166ms)","trace[2111298523] 'applied index is now lower than readState.Index' (duration: 44.151349ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:50.434431Z","caller":"traceutil/trace.go:171","msg":"trace[105346955] transaction","detail":"{read_only:false; response_revision:29492; number_of_response:1; }","duration":"376.130317ms","start":"2026-09-30T03:18:50.058262Z","end":"2026-09-30T03:18:50.434392Z","steps":["trace[105346955] 'process raft request' (duration: 375.796709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.434504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.07376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-30T03:18:50.434547Z","caller":"traceutil/trace.go:171","msg":"trace[293773094] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:29492; }","duration":"601.180992ms","start":"2026-09-30T03:18:49.833355Z","end":"2026-09-30T03:18:50.434536Z","steps":["trace[293773094] 'agreement among raft nodes before linearized reading' (duration: 601.019118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.43459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:49.83334Z","time spent":"601.240864ms","remote":"127.0.0.1:57612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-09-30T03:18:50.434596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.058229Z","time spent":"376.267381ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:50.435153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.253616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-09-30T03:18:50.435199Z","caller":"traceutil/trace.go:171","msg":"trace[1702629911] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29492; }","duration":"599.336058ms","start":"2026-09-30T03:18:49.835847Z","end":"2026-09-30T03:18:50.435183Z","steps":["trace[1702629911] 'agreement among raft nodes before linearized reading' (duration: 599.160994ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.435189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.596972ms","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:6"} {"level":"warn","ts":"2026-09-30T03:18:50.435228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:49.835841Z","time spent":"599.381019ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":591,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-09-30T03:18:50.435257Z","caller":"traceutil/trace.go:171","msg":"trace[88586056] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:29492; }","duration":"256.708225ms","start":"2026-09-30T03:18:50.178532Z","end":"2026-09-30T03:18:50.43524Z","steps":["trace[88586056] 'agreement among raft nodes before linearized reading' (duration: 256.555691ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.435418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.116445ms","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":"info","ts":"2026-09-30T03:18:50.435458Z","caller":"traceutil/trace.go:171","msg":"trace[659969472] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:29492; }","duration":"373.181247ms","start":"2026-09-30T03:18:50.062264Z","end":"2026-09-30T03:18:50.435445Z","steps":["trace[659969472] 'agreement among raft nodes before linearized reading' (duration: 373.112925ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.435493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.062252Z","time spent":"373.231027ms","remote":"127.0.0.1:57580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "} {"level":"warn","ts":"2026-09-30T03:18:50.435746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.116076ms","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-09-30T03:18:50.435789Z","caller":"traceutil/trace.go:171","msg":"trace[132845595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29492; }","duration":"499.190638ms","start":"2026-09-30T03:18:49.936587Z","end":"2026-09-30T03:18:50.435778Z","steps":["trace[132845595] 'agreement among raft nodes before linearized reading' (duration: 499.057824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.435825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:49.936571Z","time spent":"499.246949ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-30T03:18:50.436303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.369443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c810a497-78b6-4c86-b86c-d072a9b36e01\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-09-30T03:18:50.436355Z","caller":"traceutil/trace.go:171","msg":"trace[1835933570] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c810a497-78b6-4c86-b86c-d072a9b36e01; range_end:; response_count:1; response_revision:29492; }","duration":"600.421864ms","start":"2026-09-30T03:18:49.835917Z","end":"2026-09-30T03:18:50.436339Z","steps":["trace[1835933570] 'agreement among raft nodes before linearized reading' (duration: 599.871191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:50.43639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:49.835876Z","time spent":"600.504676ms","remote":"127.0.0.1:57576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1326,"request content":"key:\"/registry/persistentvolumes/pvc-c810a497-78b6-4c86-b86c-d072a9b36e01\" "} {"level":"info","ts":"2026-09-30T03:18:50.612476Z","caller":"traceutil/trace.go:171","msg":"trace[1687499674] transaction","detail":"{read_only:false; response_revision:29495; number_of_response:1; }","duration":"152.900435ms","start":"2026-09-30T03:18:50.459557Z","end":"2026-09-30T03:18:50.612458Z","steps":["trace[1687499674] 'process raft request' (duration: 152.859254ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:50.612564Z","caller":"traceutil/trace.go:171","msg":"trace[119226544] transaction","detail":"{read_only:false; response_revision:29494; number_of_response:1; }","duration":"162.758142ms","start":"2026-09-30T03:18:50.449796Z","end":"2026-09-30T03:18:50.612554Z","steps":["trace[119226544] 'process raft request' (duration: 162.541716ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:50.612468Z","caller":"traceutil/trace.go:171","msg":"trace[1173558056] transaction","detail":"{read_only:false; response_revision:29493; number_of_response:1; }","duration":"169.617936ms","start":"2026-09-30T03:18:50.442818Z","end":"2026-09-30T03:18:50.612436Z","steps":["trace[1173558056] 'process raft request' (duration: 131.895181ms)","trace[1173558056] 'compare' (duration: 37.422077ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:51.182233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2683477906261589833,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-30T03:18:51.225982Z","caller":"traceutil/trace.go:171","msg":"trace[223335254] transaction","detail":"{read_only:false; response_revision:29496; number_of_response:1; }","duration":"600.903376ms","start":"2026-09-30T03:18:50.625038Z","end":"2026-09-30T03:18:51.225942Z","steps":["trace[223335254] 'process raft request' (duration: 600.472725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.226196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.625021Z","time spent":"601.063639ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:51.399454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.490842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:18:51.399666Z","caller":"traceutil/trace.go:171","msg":"trace[598775273] linearizableReadLoop","detail":"{readStateIndex:30852; appliedIndex:30849; }","duration":"717.775298ms","start":"2026-09-30T03:18:50.681869Z","end":"2026-09-30T03:18:51.399645Z","steps":["trace[598775273] 'read index received' (duration: 543.550661ms)","trace[598775273] 'applied index is now lower than readState.Index' (duration: 174.223347ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:51.399675Z","caller":"traceutil/trace.go:171","msg":"trace[1937153535] transaction","detail":"{read_only:false; response_revision:29497; number_of_response:1; }","duration":"693.562277ms","start":"2026-09-30T03:18:50.706079Z","end":"2026-09-30T03:18:51.399641Z","steps":["trace[1937153535] 'process raft request' (duration: 541.773458ms)","trace[1937153535] 'compare' (duration: 151.329668ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:51.399876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.706041Z","time spent":"693.728371ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:51.399965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.089186ms","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-09-30T03:18:51.399856Z","caller":"traceutil/trace.go:171","msg":"trace[1132549606] transaction","detail":"{read_only:false; response_revision:29498; number_of_response:1; }","duration":"683.720311ms","start":"2026-09-30T03:18:50.716097Z","end":"2026-09-30T03:18:51.399817Z","steps":["trace[1132549606] 'process raft request' (duration: 683.469945ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:51.40001Z","caller":"traceutil/trace.go:171","msg":"trace[428459151] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:29498; }","duration":"718.157167ms","start":"2026-09-30T03:18:50.681841Z","end":"2026-09-30T03:18:51.399998Z","steps":["trace[428459151] 'agreement among raft nodes before linearized reading' (duration: 717.945461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.400051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.716078Z","time spent":"683.941917ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:51.40006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.681829Z","time spent":"718.219569ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-30T03:18:51.4Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.10731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-30T03:18:51.400178Z","caller":"traceutil/trace.go:171","msg":"trace[1058472222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29498; }","duration":"694.313276ms","start":"2026-09-30T03:18:50.705853Z","end":"2026-09-30T03:18:51.400166Z","steps":["trace[1058472222] 'agreement among raft nodes before linearized reading' (duration: 693.982148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.400125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.553728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-09-30T03:18:51.400244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.705842Z","time spent":"694.363137ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-30T03:18:51.400266Z","caller":"traceutil/trace.go:171","msg":"trace[1036384016] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29498; }","duration":"283.724612ms","start":"2026-09-30T03:18:51.116525Z","end":"2026-09-30T03:18:51.400249Z","steps":["trace[1036384016] 'agreement among raft nodes before linearized reading' (duration: 283.508966ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.400321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.572883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-30T03:18:51.400179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.027567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:18:51.400365Z","caller":"traceutil/trace.go:171","msg":"trace[778653725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29498; }","duration":"629.627694ms","start":"2026-09-30T03:18:50.770724Z","end":"2026-09-30T03:18:51.400352Z","steps":["trace[778653725] 'agreement among raft nodes before linearized reading' (duration: 629.539322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.400402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.770716Z","time spent":"629.679105ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-30T03:18:51.40025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.46182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-09-30T03:18:51.400455Z","caller":"traceutil/trace.go:171","msg":"trace[1933353082] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29498; }","duration":"629.677015ms","start":"2026-09-30T03:18:50.770769Z","end":"2026-09-30T03:18:51.400446Z","steps":["trace[1933353082] 'agreement among raft nodes before linearized reading' (duration: 629.427149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.400474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.770763Z","time spent":"629.704466ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-09-30T03:18:51.400376Z","caller":"traceutil/trace.go:171","msg":"trace[1824354932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29498; }","duration":"291.264622ms","start":"2026-09-30T03:18:51.109103Z","end":"2026-09-30T03:18:51.400368Z","steps":["trace[1824354932] 'agreement among raft nodes before linearized reading' (duration: 291.047387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.400582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.27261ms","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-09-30T03:18:51.400643Z","caller":"traceutil/trace.go:171","msg":"trace[297010628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29498; }","duration":"630.382443ms","start":"2026-09-30T03:18:50.770247Z","end":"2026-09-30T03:18:51.400629Z","steps":["trace[297010628] 'agreement among raft nodes before linearized reading' (duration: 630.189528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.400675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:50.77022Z","time spent":"630.446344ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-30T03:18:51.711432Z","caller":"traceutil/trace.go:171","msg":"trace[132785618] transaction","detail":"{read_only:false; response_revision:29499; number_of_response:1; }","duration":"303.754492ms","start":"2026-09-30T03:18:51.407644Z","end":"2026-09-30T03:18:51.711398Z","steps":["trace[132785618] 'process raft request' (duration: 265.657549ms)","trace[132785618] 'compare' (duration: 37.715644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:18:51.711604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:51.407627Z","time spent":"303.881906ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:51.996948Z","caller":"traceutil/trace.go:171","msg":"trace[2103105958] transaction","detail":"{read_only:false; response_revision:29500; number_of_response:1; }","duration":"589.036782ms","start":"2026-09-30T03:18:51.407875Z","end":"2026-09-30T03:18:51.996912Z","steps":["trace[2103105958] 'process raft request' (duration: 489.6741ms)","trace[2103105958] 'compare' (duration: 98.787948ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:18:51.997101Z","caller":"traceutil/trace.go:171","msg":"trace[390295180] transaction","detail":"{read_only:false; response_revision:29505; number_of_response:1; }","duration":"158.800848ms","start":"2026-09-30T03:18:51.838263Z","end":"2026-09-30T03:18:51.997064Z","steps":["trace[390295180] 'process raft request' (duration: 158.747486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.997141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:51.407867Z","time spent":"589.162445ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:51.997421Z","caller":"traceutil/trace.go:171","msg":"trace[60199599] transaction","detail":"{read_only:false; response_revision:29502; number_of_response:1; }","duration":"589.470372ms","start":"2026-09-30T03:18:51.407932Z","end":"2026-09-30T03:18:51.997403Z","steps":["trace[60199599] 'process raft request' (duration: 588.913958ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:51.997313Z","caller":"traceutil/trace.go:171","msg":"trace[749242689] transaction","detail":"{read_only:false; response_revision:29503; number_of_response:1; }","duration":"588.52608ms","start":"2026-09-30T03:18:51.408742Z","end":"2026-09-30T03:18:51.997268Z","steps":["trace[749242689] 'process raft request' (duration: 588.151061ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:18:51.9974Z","caller":"traceutil/trace.go:171","msg":"trace[1651909169] transaction","detail":"{read_only:false; response_revision:29504; number_of_response:1; }","duration":"586.095511ms","start":"2026-09-30T03:18:51.411262Z","end":"2026-09-30T03:18:51.997358Z","steps":["trace[1651909169] 'process raft request' (duration: 585.672491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.997605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:51.408727Z","time spent":"588.753155ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:51.997612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:51.407923Z","time spent":"589.568514ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:18:51.997656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:51.411237Z","time spent":"586.275805ms","remote":"127.0.0.1:57518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:51.997341Z","caller":"traceutil/trace.go:171","msg":"trace[340385265] transaction","detail":"{read_only:false; response_revision:29501; number_of_response:1; }","duration":"589.41171ms","start":"2026-09-30T03:18:51.407884Z","end":"2026-09-30T03:18:51.997295Z","steps":["trace[340385265] 'process raft request' (duration: 588.850657ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:18:51.997872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:18:51.407866Z","time spent":"589.950144ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-30T03:18:54.660536Z","caller":"traceutil/trace.go:171","msg":"trace[243557358] transaction","detail":"{read_only:false; response_revision:29529; number_of_response:1; }","duration":"207.695569ms","start":"2026-09-30T03:18:54.452807Z","end":"2026-09-30T03:18:54.660502Z","steps":["trace[243557358] 'process raft request' (duration: 207.485144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:19:17.935652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.88025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.92\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-30T03:19:17.935793Z","caller":"traceutil/trace.go:171","msg":"trace[1511149205] range","detail":"{range_begin:/registry/masterleases/162.253.55.92; range_end:; response_count:1; response_revision:29735; }","duration":"119.131376ms","start":"2026-09-30T03:19:17.816637Z","end":"2026-09-30T03:19:17.935769Z","steps":["trace[1511149205] 'range keys from in-memory index tree' (duration: 118.608083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:19:17.935911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.834671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-09-30T03:19:17.935975Z","caller":"traceutil/trace.go:171","msg":"trace[1684009089] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29735; }","duration":"204.958234ms","start":"2026-09-30T03:19:17.731Z","end":"2026-09-30T03:19:17.935958Z","steps":["trace[1684009089] 'range keys from in-memory index tree' (duration: 204.595945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:19:17.935924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.036614ms","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-09-30T03:19:17.936058Z","caller":"traceutil/trace.go:171","msg":"trace[1788668350] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:29735; }","duration":"129.270689ms","start":"2026-09-30T03:19:17.806759Z","end":"2026-09-30T03:19:17.93603Z","steps":["trace[1788668350] 'count revisions from in-memory index tree' (duration: 128.9143ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.077081Z","caller":"traceutil/trace.go:171","msg":"trace[667744154] transaction","detail":"{read_only:false; response_revision:29736; number_of_response:1; }","duration":"135.609491ms","start":"2026-09-30T03:19:17.941439Z","end":"2026-09-30T03:19:18.077048Z","steps":["trace[667744154] 'process raft request' (duration: 130.657772ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.105597Z","caller":"traceutil/trace.go:171","msg":"trace[1253988385] linearizableReadLoop","detail":"{readStateIndex:31103; appliedIndex:31100; }","duration":"115.336545ms","start":"2026-09-30T03:19:17.990232Z","end":"2026-09-30T03:19:18.105568Z","steps":["trace[1253988385] 'read index received' (duration: 81.951735ms)","trace[1253988385] 'applied index is now lower than readState.Index' (duration: 33.38368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:19:18.105832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.564621ms","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:6"} {"level":"info","ts":"2026-09-30T03:19:18.10588Z","caller":"traceutil/trace.go:171","msg":"trace[871920502] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:29738; }","duration":"115.699824ms","start":"2026-09-30T03:19:17.990166Z","end":"2026-09-30T03:19:18.105866Z","steps":["trace[871920502] 'agreement among raft nodes before linearized reading' (duration: 115.593762ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.10589Z","caller":"traceutil/trace.go:171","msg":"trace[307549276] transaction","detail":"{read_only:false; response_revision:29737; number_of_response:1; }","duration":"157.855194ms","start":"2026-09-30T03:19:17.948016Z","end":"2026-09-30T03:19:18.105871Z","steps":["trace[307549276] 'process raft request' (duration: 157.302241ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.106051Z","caller":"traceutil/trace.go:171","msg":"trace[721957516] transaction","detail":"{read_only:false; response_revision:29738; number_of_response:1; }","duration":"153.743657ms","start":"2026-09-30T03:19:17.951977Z","end":"2026-09-30T03:19:18.105721Z","steps":["trace[721957516] 'process raft request' (duration: 153.518421ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.31225Z","caller":"traceutil/trace.go:171","msg":"trace[1817529876] transaction","detail":"{read_only:false; response_revision:29739; number_of_response:1; }","duration":"196.558292ms","start":"2026-09-30T03:19:18.115649Z","end":"2026-09-30T03:19:18.312208Z","steps":["trace[1817529876] 'process raft request' (duration: 132.294211ms)","trace[1817529876] 'compare' (duration: 63.953243ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:19:18.312391Z","caller":"traceutil/trace.go:171","msg":"trace[651007891] linearizableReadLoop","detail":"{readStateIndex:31105; appliedIndex:31103; }","duration":"144.533905ms","start":"2026-09-30T03:19:18.167815Z","end":"2026-09-30T03:19:18.312349Z","steps":["trace[651007891] 'read index received' (duration: 80.101221ms)","trace[651007891] 'applied index is now lower than readState.Index' (duration: 64.430354ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:19:18.312445Z","caller":"traceutil/trace.go:171","msg":"trace[1372576368] transaction","detail":"{read_only:false; response_revision:29740; number_of_response:1; }","duration":"182.078135ms","start":"2026-09-30T03:19:18.130326Z","end":"2026-09-30T03:19:18.312404Z","steps":["trace[1372576368] 'process raft request' (duration: 181.825259ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:19:18.312777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.931474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-30T03:19:18.312829Z","caller":"traceutil/trace.go:171","msg":"trace[907547092] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29740; }","duration":"145.048537ms","start":"2026-09-30T03:19:18.167769Z","end":"2026-09-30T03:19:18.312818Z","steps":["trace[907547092] 'agreement among raft nodes before linearized reading' (duration: 144.75769ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.542591Z","caller":"traceutil/trace.go:171","msg":"trace[2063969955] transaction","detail":"{read_only:false; response_revision:29742; number_of_response:1; }","duration":"224.016281ms","start":"2026-09-30T03:19:18.318544Z","end":"2026-09-30T03:19:18.54256Z","steps":["trace[2063969955] 'process raft request' (duration: 223.317984ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.542963Z","caller":"traceutil/trace.go:171","msg":"trace[1072471398] transaction","detail":"{read_only:false; response_revision:29743; number_of_response:1; }","duration":"223.491779ms","start":"2026-09-30T03:19:18.319433Z","end":"2026-09-30T03:19:18.542924Z","steps":["trace[1072471398] 'process raft request' (duration: 223.089259ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.836586Z","caller":"traceutil/trace.go:171","msg":"trace[424963137] transaction","detail":"{read_only:false; response_revision:29744; number_of_response:1; }","duration":"101.990084ms","start":"2026-09-30T03:19:18.734551Z","end":"2026-09-30T03:19:18.836541Z","steps":["trace[424963137] 'process raft request' (duration: 101.722308ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:18.847233Z","caller":"traceutil/trace.go:171","msg":"trace[32933944] transaction","detail":"{read_only:false; response_revision:29745; number_of_response:1; }","duration":"106.77734ms","start":"2026-09-30T03:19:18.740424Z","end":"2026-09-30T03:19:18.847201Z","steps":["trace[32933944] 'process raft request' (duration: 106.457682ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:19.132414Z","caller":"traceutil/trace.go:171","msg":"trace[1210705787] linearizableReadLoop","detail":"{readStateIndex:31113; appliedIndex:31112; }","duration":"270.685559ms","start":"2026-09-30T03:19:18.861689Z","end":"2026-09-30T03:19:19.132375Z","steps":["trace[1210705787] 'read index received' (duration: 270.354531ms)","trace[1210705787] 'applied index is now lower than readState.Index' (duration: 329.448µs)"],"step_count":2} {"level":"warn","ts":"2026-09-30T03:19:19.13297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.231782ms","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-09-30T03:19:19.13298Z","caller":"traceutil/trace.go:171","msg":"trace[291157399] transaction","detail":"{read_only:false; response_revision:29748; number_of_response:1; }","duration":"276.546379ms","start":"2026-09-30T03:19:18.856386Z","end":"2026-09-30T03:19:19.132933Z","steps":["trace[291157399] 'process raft request' (duration: 275.75378ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:19.133068Z","caller":"traceutil/trace.go:171","msg":"trace[1033445048] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:29748; }","duration":"271.429066ms","start":"2026-09-30T03:19:18.861617Z","end":"2026-09-30T03:19:19.133046Z","steps":["trace[1033445048] 'agreement among raft nodes before linearized reading' (duration: 271.213061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:19:19.133031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.945069ms","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-09-30T03:19:19.133156Z","caller":"traceutil/trace.go:171","msg":"trace[1904354828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29748; }","duration":"224.101052ms","start":"2026-09-30T03:19:18.909026Z","end":"2026-09-30T03:19:19.133127Z","steps":["trace[1904354828] 'agreement among raft nodes before linearized reading' (duration: 223.805135ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:19:34.281573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26513} {"level":"info","ts":"2026-09-30T03:19:34.428581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26513,"took":"140.801355ms","hash":139096459,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":47001600,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-09-30T03:19:34.428681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":139096459,"revision":26513,"compact-revision":23327} {"level":"warn","ts":"2026-09-30T03:22:58.291765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.50648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-30T03:22:58.29201Z","caller":"traceutil/trace.go:171","msg":"trace[885851811] transaction","detail":"{read_only:false; response_revision:32337; number_of_response:1; }","duration":"121.282939ms","start":"2026-09-30T03:22:58.170702Z","end":"2026-09-30T03:22:58.291985Z","steps":["trace[885851811] 'process raft request' (duration: 121.200647ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:22:58.2921Z","caller":"traceutil/trace.go:171","msg":"trace[756858033] transaction","detail":"{read_only:false; response_revision:32336; number_of_response:1; }","duration":"121.36907ms","start":"2026-09-30T03:22:58.170647Z","end":"2026-09-30T03:22:58.292016Z","steps":["trace[756858033] 'process raft request' (duration: 20.467391ms)","trace[756858033] 'compare' (duration: 100.377287ms)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:24:34.288994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30040} {"level":"info","ts":"2026-09-30T03:24:34.4575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30040,"took":"162.510647ms","hash":1527930597,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":37830656,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-09-30T03:24:34.457583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1527930597,"revision":30040,"compact-revision":26513} {"level":"info","ts":"2026-09-30T03:29:34.298964Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33185} {"level":"info","ts":"2026-09-30T03:29:34.459251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33185,"took":"154.101995ms","hash":2778448029,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":34902016,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-30T03:29:34.459349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2778448029,"revision":33185,"compact-revision":30040} {"level":"info","ts":"2026-09-30T03:33:59.945342Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39a4f14f6507253d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-30T03:33:59.952613Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-30T03:33:59.952892Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-30T03:34:34.307095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35856} {"level":"info","ts":"2026-09-30T03:34:34.444787Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35856,"took":"132.320021ms","hash":893716061,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":34656256,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-30T03:34:34.444864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":893716061,"revision":35856,"compact-revision":33185} {"level":"info","ts":"2026-09-30T03:39:34.315781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38562} {"level":"info","ts":"2026-09-30T03:39:34.483733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38562,"took":"160.359784ms","hash":589750653,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":34783232,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-30T03:39:34.483831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":589750653,"revision":38562,"compact-revision":35856} {"level":"info","ts":"2026-09-30T03:40:08.223672Z","caller":"traceutil/trace.go:171","msg":"trace[1522696231] linearizableReadLoop","detail":"{readStateIndex:43519; appliedIndex:43518; }","duration":"328.008853ms","start":"2026-09-30T03:40:07.895635Z","end":"2026-09-30T03:40:08.223644Z","steps":["trace[1522696231] 'read index received' (duration: 327.767707ms)","trace[1522696231] 'applied index is now lower than readState.Index' (duration: 240.106µs)"],"step_count":2} {"level":"info","ts":"2026-09-30T03:40:08.224057Z","caller":"traceutil/trace.go:171","msg":"trace[855147363] transaction","detail":"{read_only:false; response_revision:41631; number_of_response:1; }","duration":"370.616223ms","start":"2026-09-30T03:40:07.853424Z","end":"2026-09-30T03:40:08.224041Z","steps":["trace[855147363] 'process raft request' (duration: 370.047579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:40:08.224184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:40:07.853393Z","time spent":"370.701125ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-30T03:40:08.224462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.824062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.92\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-30T03:40:08.22451Z","caller":"traceutil/trace.go:171","msg":"trace[357730748] range","detail":"{range_begin:/registry/masterleases/162.253.55.92; range_end:; response_count:1; response_revision:41631; }","duration":"328.867703ms","start":"2026-09-30T03:40:07.895629Z","end":"2026-09-30T03:40:08.224496Z","steps":["trace[357730748] 'agreement among raft nodes before linearized reading' (duration: 328.73942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:40:08.224542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:40:07.895575Z","time spent":"328.960945ms","remote":"127.0.0.1:57456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.92\" "} {"level":"warn","ts":"2026-09-30T03:40:08.224758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.439553ms","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-09-30T03:40:08.224832Z","caller":"traceutil/trace.go:171","msg":"trace[124894264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:41631; }","duration":"328.568176ms","start":"2026-09-30T03:40:07.896253Z","end":"2026-09-30T03:40:08.224822Z","steps":["trace[124894264] 'agreement among raft nodes before linearized reading' (duration: 328.436643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:40:08.224869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-30T03:40:07.896232Z","time spent":"328.618377ms","remote":"127.0.0.1:57702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-30T03:40:08.225319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.076027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:40:08.225358Z","caller":"traceutil/trace.go:171","msg":"trace[1593580939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41631; }","duration":"118.138238ms","start":"2026-09-30T03:40:08.107211Z","end":"2026-09-30T03:40:08.22535Z","steps":["trace[1593580939] 'agreement among raft nodes before linearized reading' (duration: 118.084107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:40:08.225536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.85832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-09-30T03:40:08.225571Z","caller":"traceutil/trace.go:171","msg":"trace[1118816203] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:41631; }","duration":"175.905861ms","start":"2026-09-30T03:40:08.049656Z","end":"2026-09-30T03:40:08.225562Z","steps":["trace[1118816203] 'agreement among raft nodes before linearized reading' (duration: 175.817209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:40:08.225735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.887836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-30T03:40:08.225768Z","caller":"traceutil/trace.go:171","msg":"trace[1212861244] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:41631; }","duration":"184.942808ms","start":"2026-09-30T03:40:08.040817Z","end":"2026-09-30T03:40:08.22576Z","steps":["trace[1212861244] 'agreement among raft nodes before linearized reading' (duration: 184.865736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:40:08.226088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.237256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-30T03:40:08.226124Z","caller":"traceutil/trace.go:171","msg":"trace[650605319] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:41631; }","duration":"226.331868ms","start":"2026-09-30T03:40:07.999783Z","end":"2026-09-30T03:40:08.226115Z","steps":["trace[650605319] 'agreement among raft nodes before linearized reading' (duration: 226.268177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-30T03:40:08.22632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.004727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29845660-9x494\" ","response":"range_response_count:1 size:7176"} {"level":"info","ts":"2026-09-30T03:40:08.226374Z","caller":"traceutil/trace.go:171","msg":"trace[1535295701] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29845660-9x494; range_end:; response_count:1; response_revision:41631; }","duration":"291.058798ms","start":"2026-09-30T03:40:07.935286Z","end":"2026-09-30T03:40:08.226345Z","steps":["trace[1535295701] 'agreement among raft nodes before linearized reading' (duration: 290.962285ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:40:08.350197Z","caller":"traceutil/trace.go:171","msg":"trace[419191701] transaction","detail":"{read_only:false; response_revision:41637; number_of_response:1; }","duration":"106.52182ms","start":"2026-09-30T03:40:08.243648Z","end":"2026-09-30T03:40:08.35017Z","steps":["trace[419191701] 'process raft request' (duration: 106.310995ms)"],"step_count":1} {"level":"info","ts":"2026-09-30T03:44:34.324029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41243} {"level":"info","ts":"2026-09-30T03:44:34.492449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41243,"took":"162.343036ms","hash":2245438943,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":34336768,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-30T03:44:34.492544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2245438943,"revision":41243,"compact-revision":38562} {"level":"info","ts":"2026-09-30T03:49:34.339022Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43983} {"level":"info","ts":"2026-09-30T03:49:34.494081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43983,"took":"150.292098ms","hash":1540074699,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":33316864,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-30T03:49:34.494253Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1540074699,"revision":43983,"compact-revision":41243} {"level":"info","ts":"2026-09-30T03:51:33.634597Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"39a4f14f6507253d","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-30T03:51:33.644447Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-09-30T03:51:33.644614Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}