{"level":"warn","ts":"2026-10-09T02:20:51.919637Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T02:20:51.919772Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.218: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.218:2380","--initial-cluster=instance=https://162.253.55.218:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.218:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.218: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-10-09T02:20:51.919881Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T02:20:51.919901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-10-09T02:20:51.919925Z","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-10-09T02:20:51.920811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"]} {"level":"info","ts":"2026-10-09T02:20:51.920975Z","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.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.218: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-10-09T02:20:51.926088Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.739079ms"} {"level":"info","ts":"2026-10-09T02:20:51.933274Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bb7d59f4041c8abe","cluster-id":"5330ca29224af3c4"} {"level":"info","ts":"2026-10-09T02:20:51.933367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T02:20:51.933396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 0"} {"level":"info","ts":"2026-10-09T02:20:51.933412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bb7d59f4041c8abe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T02:20:51.933425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became follower at term 1"} {"level":"info","ts":"2026-10-09T02:20:51.9335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"warn","ts":"2026-10-09T02:20:51.939401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T02:20:51.941035Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T02:20:51.942629Z","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-10-09T02:20:51.945135Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bb7d59f4041c8abe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T02:20:51.945342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bb7d59f4041c8abe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T02:20:51.945544Z","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-10-09T02:20:51.946418Z","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-10-09T02:20:51.94646Z","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-10-09T02:20:51.946923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe switched to configuration voters=(13510053361757227710)"} {"level":"info","ts":"2026-10-09T02:20:51.94719Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","added-peer-id":"bb7d59f4041c8abe","added-peer-peer-urls":["https://162.253.55.218:2380"]} {"level":"info","ts":"2026-10-09T02:20:51.951915Z","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-10-09T02:20:51.952086Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-10-09T02:20:51.952192Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.218:2380"} {"level":"info","ts":"2026-10-09T02:20:51.952218Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bb7d59f4041c8abe","initial-advertise-peer-urls":["https://162.253.55.218:2380"],"listen-peer-urls":["https://162.253.55.218:2380"],"advertise-client-urls":["https://162.253.55.218:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.218:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T02:20:51.952281Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T02:20:52.434206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T02:20:52.434292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T02:20:52.434313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgPreVoteResp from bb7d59f4041c8abe at term 1"} {"level":"info","ts":"2026-10-09T02:20:52.43433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became candidate at term 2"} {"level":"info","ts":"2026-10-09T02:20:52.43434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe received MsgVoteResp from bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-10-09T02:20:52.434352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bb7d59f4041c8abe became leader at term 2"} {"level":"info","ts":"2026-10-09T02:20:52.434363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bb7d59f4041c8abe elected leader bb7d59f4041c8abe at term 2"} {"level":"info","ts":"2026-10-09T02:20:52.43542Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:20:52.436514Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:20:52.436539Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T02:20:52.436522Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bb7d59f4041c8abe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.218:2379]}","request-path":"/0/members/bb7d59f4041c8abe/attributes","cluster-id":"5330ca29224af3c4","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T02:20:52.436921Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T02:20:52.436949Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T02:20:52.437225Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5330ca29224af3c4","local-member-id":"bb7d59f4041c8abe","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:20:52.43737Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:20:52.437417Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T02:20:52.439045Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.218:2379"} {"level":"info","ts":"2026-10-09T02:20:52.440222Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T02:20:54.348091Z","caller":"traceutil/trace.go:171","msg":"trace[260430108] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"142.195139ms","start":"2026-10-09T02:20:54.205846Z","end":"2026-10-09T02:20:54.348041Z","steps":["trace[260430108] 'process raft request' (duration: 59.471173ms)","trace[260430108] 'compare' (duration: 82.49686ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:21:35.779302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.443681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:21:35.779758Z","caller":"traceutil/trace.go:171","msg":"trace[566659414] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"214.550454ms","start":"2026-10-09T02:21:35.565132Z","end":"2026-10-09T02:21:35.779682Z","steps":["trace[566659414] 'process raft request' (duration: 14.061004ms)","trace[566659414] 'compare' (duration: 199.209475ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:21:35.77978Z","caller":"traceutil/trace.go:171","msg":"trace[1801198172] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"214.40665ms","start":"2026-10-09T02:21:35.565281Z","end":"2026-10-09T02:21:35.779688Z","steps":["trace[1801198172] 'process raft request' (duration: 214.199034ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:21:43.703436Z","caller":"traceutil/trace.go:171","msg":"trace[922649044] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"272.768522ms","start":"2026-10-09T02:21:43.430642Z","end":"2026-10-09T02:21:43.70341Z","steps":["trace[922649044] 'process raft request' (duration: 272.284509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:21:44.376947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.649456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T02:21:44.377027Z","caller":"traceutil/trace.go:171","msg":"trace[1306543934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"246.770099ms","start":"2026-10-09T02:21:44.130241Z","end":"2026-10-09T02:21:44.377011Z","steps":["trace[1306543934] 'range keys from in-memory index tree' (duration: 246.45954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:21:44.376939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.580107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-svsh5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-09T02:21:44.378294Z","caller":"traceutil/trace.go:171","msg":"trace[1318033601] range","detail":"{range_begin:/registry/pods/kube-system/cilium-svsh5; range_end:; response_count:1; response_revision:650; }","duration":"304.968935ms","start":"2026-10-09T02:21:44.073287Z","end":"2026-10-09T02:21:44.378256Z","steps":["trace[1318033601] 'range keys from in-memory index tree' (duration: 303.427353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:21:44.378395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:21:44.073261Z","time spent":"305.085248ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-svsh5\" "} {"level":"warn","ts":"2026-10-09T02:22:00.167068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.555053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:22:00.167181Z","caller":"traceutil/trace.go:171","msg":"trace[1061373259] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:821; }","duration":"125.743219ms","start":"2026-10-09T02:22:00.041414Z","end":"2026-10-09T02:22:00.167157Z","steps":["trace[1061373259] 'count revisions from in-memory index tree' (duration: 125.39857ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:23:18.693303Z","caller":"traceutil/trace.go:171","msg":"trace[926122917] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"133.292301ms","start":"2026-10-09T02:23:18.55999Z","end":"2026-10-09T02:23:18.693282Z","steps":["trace[926122917] 'process raft request' (duration: 133.162168ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:03.196019Z","caller":"traceutil/trace.go:171","msg":"trace[1779160837] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"100.868194ms","start":"2026-10-09T02:24:03.095115Z","end":"2026-10-09T02:24:03.195983Z","steps":["trace[1779160837] 'process raft request' (duration: 100.639877ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:03.552194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.754523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-09T02:24:03.552252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.602066ms","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-10-09T02:24:03.552306Z","caller":"traceutil/trace.go:171","msg":"trace[1366268656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2192; }","duration":"141.884497ms","start":"2026-10-09T02:24:03.410396Z","end":"2026-10-09T02:24:03.55228Z","steps":["trace[1366268656] 'range keys from in-memory index tree' (duration: 141.557428ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:03.55241Z","caller":"traceutil/trace.go:171","msg":"trace[769475852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2192; }","duration":"104.799091ms","start":"2026-10-09T02:24:03.447586Z","end":"2026-10-09T02:24:03.552385Z","steps":["trace[769475852] 'range keys from in-memory index tree' (duration: 104.425981ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:24:03.552458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.260956ms","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":"warn","ts":"2026-10-09T02:24:03.552482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.862505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-09T02:24:03.552665Z","caller":"traceutil/trace.go:171","msg":"trace[1929698433] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2192; }","duration":"208.0691ms","start":"2026-10-09T02:24:03.344577Z","end":"2026-10-09T02:24:03.552646Z","steps":["trace[1929698433] 'range keys from in-memory index tree' (duration: 207.653899ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:03.552614Z","caller":"traceutil/trace.go:171","msg":"trace[165947801] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2192; }","duration":"219.466912ms","start":"2026-10-09T02:24:03.333125Z","end":"2026-10-09T02:24:03.552592Z","steps":["trace[165947801] 'range keys from in-memory index tree' (duration: 219.046561ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:24:59.030084Z","caller":"traceutil/trace.go:171","msg":"trace[2032229729] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"164.193692ms","start":"2026-10-09T02:24:58.865863Z","end":"2026-10-09T02:24:59.030057Z","steps":["trace[2032229729] 'process raft request' (duration: 164.032217ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:25:33.185536Z","caller":"traceutil/trace.go:171","msg":"trace[911806443] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"137.414996ms","start":"2026-10-09T02:25:33.048096Z","end":"2026-10-09T02:25:33.185511Z","steps":["trace[911806443] 'process raft request' (duration: 137.19361ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:10.928655Z","caller":"traceutil/trace.go:171","msg":"trace[1596152726] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"108.70174ms","start":"2026-10-09T02:30:10.819933Z","end":"2026-10-09T02:30:10.928635Z","steps":["trace[1596152726] 'process raft request' (duration: 60.285161ms)","trace[1596152726] 'compare' (duration: 47.856454ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:30:10.92866Z","caller":"traceutil/trace.go:171","msg":"trace[953487838] linearizableReadLoop","detail":"{readStateIndex:5315; appliedIndex:5314; }","duration":"105.448529ms","start":"2026-10-09T02:30:10.82315Z","end":"2026-10-09T02:30:10.928598Z","steps":["trace[953487838] 'read index received' (duration: 56.975048ms)","trace[953487838] 'applied index is now lower than readState.Index' (duration: 48.467421ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:30:10.928865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.688566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:30:10.928951Z","caller":"traceutil/trace.go:171","msg":"trace[1039457004] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:5120; }","duration":"105.82651ms","start":"2026-10-09T02:30:10.823102Z","end":"2026-10-09T02:30:10.928928Z","steps":["trace[1039457004] 'agreement among raft nodes before linearized reading' (duration: 105.642955ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.066971Z","caller":"traceutil/trace.go:171","msg":"trace[1692207757] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"133.11359ms","start":"2026-10-09T02:30:10.933831Z","end":"2026-10-09T02:30:11.066944Z","steps":["trace[1692207757] 'process raft request' (duration: 132.999517ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.067055Z","caller":"traceutil/trace.go:171","msg":"trace[1868859951] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"133.261874ms","start":"2026-10-09T02:30:10.93378Z","end":"2026-10-09T02:30:11.067042Z","steps":["trace[1868859951] 'process raft request' (duration: 133.012237ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.06705Z","caller":"traceutil/trace.go:171","msg":"trace[1323452062] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5316; }","duration":"132.931785ms","start":"2026-10-09T02:30:10.934096Z","end":"2026-10-09T02:30:11.067028Z","steps":["trace[1323452062] 'read index received' (duration: 37.916616ms)","trace[1323452062] 'applied index is now lower than readState.Index' (duration: 95.014339ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:30:11.067211Z","caller":"traceutil/trace.go:171","msg":"trace[997033442] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"133.101839ms","start":"2026-10-09T02:30:10.934093Z","end":"2026-10-09T02:30:11.067195Z","steps":["trace[997033442] 'process raft request' (duration: 132.798041ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.067272Z","caller":"traceutil/trace.go:171","msg":"trace[1630507173] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"133.287705ms","start":"2026-10-09T02:30:10.93396Z","end":"2026-10-09T02:30:11.067248Z","steps":["trace[1630507173] 'process raft request' (duration: 132.897564ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.066961Z","caller":"traceutil/trace.go:171","msg":"trace[320487512] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"133.246714ms","start":"2026-10-09T02:30:10.933666Z","end":"2026-10-09T02:30:11.066913Z","steps":["trace[320487512] 'process raft request' (duration: 122.842514ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.067314Z","caller":"traceutil/trace.go:171","msg":"trace[1545137782] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"130.847277ms","start":"2026-10-09T02:30:10.936398Z","end":"2026-10-09T02:30:11.067245Z","steps":["trace[1545137782] 'process raft request' (duration: 130.522678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:11.067593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.285315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-10-09T02:30:11.067478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.349427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-09T02:30:11.067674Z","caller":"traceutil/trace.go:171","msg":"trace[1929139564] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5128; }","duration":"133.402768ms","start":"2026-10-09T02:30:10.934253Z","end":"2026-10-09T02:30:11.067656Z","steps":["trace[1929139564] 'agreement among raft nodes before linearized reading' (duration: 133.11206ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.067696Z","caller":"traceutil/trace.go:171","msg":"trace[705301457] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5128; }","duration":"133.584344ms","start":"2026-10-09T02:30:10.934088Z","end":"2026-10-09T02:30:11.067672Z","steps":["trace[705301457] 'agreement among raft nodes before linearized reading' (duration: 133.221594ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:11.067499Z","caller":"traceutil/trace.go:171","msg":"trace[459458767] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"125.273602ms","start":"2026-10-09T02:30:10.94219Z","end":"2026-10-09T02:30:11.067464Z","steps":["trace[459458767] 'process raft request' (duration: 124.782348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:11.215141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.392196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-09T02:30:11.215247Z","caller":"traceutil/trace.go:171","msg":"trace[500906596] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5131; }","duration":"101.5268ms","start":"2026-10-09T02:30:11.113699Z","end":"2026-10-09T02:30:11.215226Z","steps":["trace[500906596] 'agreement among raft nodes before linearized reading' (duration: 101.247882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:11.215536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.436067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:529509"} {"level":"info","ts":"2026-10-09T02:30:11.215591Z","caller":"traceutil/trace.go:171","msg":"trace[1654383503] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5131; }","duration":"106.528489ms","start":"2026-10-09T02:30:11.109042Z","end":"2026-10-09T02:30:11.21557Z","steps":["trace[1654383503] 'agreement among raft nodes before linearized reading' (duration: 106.100657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:11.215641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.472996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-09T02:30:11.215686Z","caller":"traceutil/trace.go:171","msg":"trace[678199675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5131; }","duration":"126.584208ms","start":"2026-10-09T02:30:11.089089Z","end":"2026-10-09T02:30:11.215673Z","steps":["trace[678199675] 'agreement among raft nodes before linearized reading' (duration: 125.895109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:11.215847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.553006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-09T02:30:11.21592Z","caller":"traceutil/trace.go:171","msg":"trace[904220251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5131; }","duration":"117.6739ms","start":"2026-10-09T02:30:11.098227Z","end":"2026-10-09T02:30:11.215901Z","steps":["trace[904220251] 'agreement among raft nodes before linearized reading' (duration: 117.398852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:16.743957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.681572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:30:16.744106Z","caller":"traceutil/trace.go:171","msg":"trace[738319297] linearizableReadLoop","detail":"{readStateIndex:5499; appliedIndex:5498; }","duration":"224.153648ms","start":"2026-10-09T02:30:16.519923Z","end":"2026-10-09T02:30:16.744077Z","steps":["trace[738319297] 'read index received' (duration: 41.25878ms)","trace[738319297] 'applied index is now lower than readState.Index' (duration: 182.893118ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:30:16.744105Z","caller":"traceutil/trace.go:171","msg":"trace[1939096194] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"300.663271ms","start":"2026-10-09T02:30:16.443419Z","end":"2026-10-09T02:30:16.744083Z","steps":["trace[1939096194] 'process raft request' (duration: 117.759742ms)","trace[1939096194] 'compare' (duration: 182.215909ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:30:16.7442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:30:16.443402Z","time spent":"300.754904ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1811,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T02:30:16.744389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.459257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b54fa47e-265d-4a86-b734-3a12c827ae18\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-09T02:30:16.744447Z","caller":"traceutil/trace.go:171","msg":"trace[591065813] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b54fa47e-265d-4a86-b734-3a12c827ae18; range_end:; response_count:1; response_revision:5294; }","duration":"224.551969ms","start":"2026-10-09T02:30:16.519878Z","end":"2026-10-09T02:30:16.74443Z","steps":["trace[591065813] 'agreement among raft nodes before linearized reading' (duration: 224.332893ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:30:29.739976Z","caller":"traceutil/trace.go:171","msg":"trace[1945427012] transaction","detail":"{read_only:false; response_revision:5546; number_of_response:1; }","duration":"101.939182ms","start":"2026-10-09T02:30:29.637996Z","end":"2026-10-09T02:30:29.739935Z","steps":["trace[1945427012] 'process raft request' (duration: 36.152488ms)","trace[1945427012] 'compare' (duration: 65.502746ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:30:30.437083Z","caller":"traceutil/trace.go:171","msg":"trace[1716836527] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"197.458084ms","start":"2026-10-09T02:30:30.239598Z","end":"2026-10-09T02:30:30.437056Z","steps":["trace[1716836527] 'process raft request' (duration: 197.288159ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:38.266444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.101394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-2r86j\" ","response":"range_response_count:1 size:4040"} {"level":"info","ts":"2026-10-09T02:30:38.266563Z","caller":"traceutil/trace.go:171","msg":"trace[1845655953] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-2r86j; range_end:; response_count:1; response_revision:5741; }","duration":"211.279589ms","start":"2026-10-09T02:30:38.055264Z","end":"2026-10-09T02:30:38.266543Z","steps":["trace[1845655953] 'range keys from in-memory index tree' (duration: 210.911329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:30:38.266815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.476245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:30:38.266927Z","caller":"traceutil/trace.go:171","msg":"trace[1382495294] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"211.029422ms","start":"2026-10-09T02:30:38.055878Z","end":"2026-10-09T02:30:38.266908Z","steps":["trace[1382495294] 'process raft request' (duration: 32.227298ms)","trace[1382495294] 'compare' (duration: 178.150116ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:30:38.491879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.406883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:30:38.492106Z","caller":"traceutil/trace.go:171","msg":"trace[266974068] transaction","detail":"{read_only:false; response_revision:5743; number_of_response:1; }","duration":"213.840971ms","start":"2026-10-09T02:30:38.278236Z","end":"2026-10-09T02:30:38.492077Z","steps":["trace[266974068] 'process raft request' (duration: 96.125979ms)","trace[266974068] 'compare' (duration: 117.243328ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:30:52.467127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-10-09T02:30:52.539158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"70.548376ms","hash":2697689724,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T02:30:52.539228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2697689724,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-10-09T02:31:30.002104Z","caller":"traceutil/trace.go:171","msg":"trace[496720476] linearizableReadLoop","detail":"{readStateIndex:6444; appliedIndex:6443; }","duration":"111.291963ms","start":"2026-10-09T02:31:29.890771Z","end":"2026-10-09T02:31:30.002063Z","steps":["trace[496720476] 'read index received' (duration: 111.061556ms)","trace[496720476] 'applied index is now lower than readState.Index' (duration: 229.487µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:31:30.002133Z","caller":"traceutil/trace.go:171","msg":"trace[206702713] transaction","detail":"{read_only:false; response_revision:6214; number_of_response:1; }","duration":"116.158327ms","start":"2026-10-09T02:31:29.885951Z","end":"2026-10-09T02:31:30.002109Z","steps":["trace[206702713] 'process raft request' (duration: 115.92703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:31:30.003108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.317861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-09T02:31:30.003206Z","caller":"traceutil/trace.go:171","msg":"trace[239656554] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:6214; }","duration":"112.466055ms","start":"2026-10-09T02:31:29.890719Z","end":"2026-10-09T02:31:30.003185Z","steps":["trace[239656554] 'agreement among raft nodes before linearized reading' (duration: 111.489728ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:35:52.475236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5882} {"level":"info","ts":"2026-10-09T02:35:52.570703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5882,"took":"93.458996ms","hash":4064198326,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":26656768,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T02:35:52.570778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4064198326,"revision":5882,"compact-revision":2980} {"level":"info","ts":"2026-10-09T02:36:46.993432Z","caller":"traceutil/trace.go:171","msg":"trace[104390508] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"233.246425ms","start":"2026-10-09T02:36:46.760159Z","end":"2026-10-09T02:36:46.993406Z","steps":["trace[104390508] 'process raft request' (duration: 233.089161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:36:47.20099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.478915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:36:47.201088Z","caller":"traceutil/trace.go:171","msg":"trace[1703030772] linearizableReadLoop","detail":"{readStateIndex:8863; appliedIndex:8862; }","duration":"334.393673ms","start":"2026-10-09T02:36:46.866677Z","end":"2026-10-09T02:36:47.20107Z","steps":["trace[1703030772] 'read index received' (duration: 126.750988ms)","trace[1703030772] 'applied index is now lower than readState.Index' (duration: 207.641325ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:36:47.201171Z","caller":"traceutil/trace.go:171","msg":"trace[17081155] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"436.54115ms","start":"2026-10-09T02:36:46.76462Z","end":"2026-10-09T02:36:47.201161Z","steps":["trace[17081155] 'process raft request' (duration: 264.808898ms)","trace[17081155] 'compare' (duration: 171.361092ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:36:47.201233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:36:46.764595Z","time spent":"436.607872ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T02:36:47.20129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.592439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:36:47.201362Z","caller":"traceutil/trace.go:171","msg":"trace[1785174610] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:8556; }","duration":"334.711812ms","start":"2026-10-09T02:36:46.866635Z","end":"2026-10-09T02:36:47.201347Z","steps":["trace[1785174610] 'agreement among raft nodes before linearized reading' (duration: 334.587899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:36:47.201353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.894359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-09T02:36:47.2014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T02:36:46.866619Z","time spent":"334.771613ms","remote":"127.0.0.1:40968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-10-09T02:36:47.201432Z","caller":"traceutil/trace.go:171","msg":"trace[724526573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8556; }","duration":"248.020273ms","start":"2026-10-09T02:36:46.953394Z","end":"2026-10-09T02:36:47.201415Z","steps":["trace[724526573] 'agreement among raft nodes before linearized reading' (duration: 247.788276ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:36:58.664796Z","caller":"traceutil/trace.go:171","msg":"trace[1440018120] linearizableReadLoop","detail":"{readStateIndex:8929; appliedIndex:8928; }","duration":"239.174378ms","start":"2026-10-09T02:36:58.425599Z","end":"2026-10-09T02:36:58.664773Z","steps":["trace[1440018120] 'read index received' (duration: 238.957512ms)","trace[1440018120] 'applied index is now lower than readState.Index' (duration: 216.026µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:36:58.664836Z","caller":"traceutil/trace.go:171","msg":"trace[941250846] transaction","detail":"{read_only:false; response_revision:8619; number_of_response:1; }","duration":"276.846871ms","start":"2026-10-09T02:36:58.387953Z","end":"2026-10-09T02:36:58.6648Z","steps":["trace[941250846] 'process raft request' (duration: 276.675306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:36:58.664933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.310542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T02:36:58.66496Z","caller":"traceutil/trace.go:171","msg":"trace[1621707583] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:8619; }","duration":"239.390104ms","start":"2026-10-09T02:36:58.425562Z","end":"2026-10-09T02:36:58.664952Z","steps":["trace[1621707583] 'agreement among raft nodes before linearized reading' (duration: 239.309512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:36:59.488024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.151791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:36:59.488172Z","caller":"traceutil/trace.go:171","msg":"trace[393684619] linearizableReadLoop","detail":"{readStateIndex:8931; appliedIndex:8930; }","duration":"228.528983ms","start":"2026-10-09T02:36:59.259618Z","end":"2026-10-09T02:36:59.488147Z","steps":["trace[393684619] 'read index received' (duration: 38.096564ms)","trace[393684619] 'applied index is now lower than readState.Index' (duration: 190.430709ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:36:59.488233Z","caller":"traceutil/trace.go:171","msg":"trace[1731724016] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"236.158964ms","start":"2026-10-09T02:36:59.252025Z","end":"2026-10-09T02:36:59.488184Z","steps":["trace[1731724016] 'process raft request' (duration: 45.690944ms)","trace[1731724016] 'compare' (duration: 189.932045ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:36:59.488336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.694657ms","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-10-09T02:36:59.488403Z","caller":"traceutil/trace.go:171","msg":"trace[613760754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8621; }","duration":"228.816561ms","start":"2026-10-09T02:36:59.259573Z","end":"2026-10-09T02:36:59.488389Z","steps":["trace[613760754] 'agreement among raft nodes before linearized reading' (duration: 228.630495ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:36:59.659154Z","caller":"traceutil/trace.go:171","msg":"trace[1873616959] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"106.488276ms","start":"2026-10-09T02:36:59.55264Z","end":"2026-10-09T02:36:59.659128Z","steps":["trace[1873616959] 'process raft request' (duration: 106.355803ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:37:00.208255Z","caller":"traceutil/trace.go:171","msg":"trace[1155394240] linearizableReadLoop","detail":"{readStateIndex:8937; appliedIndex:8936; }","duration":"123.056615ms","start":"2026-10-09T02:37:00.085173Z","end":"2026-10-09T02:37:00.20823Z","steps":["trace[1155394240] 'read index received' (duration: 50.283921ms)","trace[1155394240] 'applied index is now lower than readState.Index' (duration: 72.771274ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:37:00.208667Z","caller":"traceutil/trace.go:171","msg":"trace[550926820] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"139.231063ms","start":"2026-10-09T02:37:00.069413Z","end":"2026-10-09T02:37:00.208644Z","steps":["trace[550926820] 'process raft request' (duration: 66.178021ms)","trace[550926820] 'compare' (duration: 72.518817ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T02:37:00.208806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.298392ms","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":"warn","ts":"2026-10-09T02:37:00.208837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.633791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-09T02:37:00.208902Z","caller":"traceutil/trace.go:171","msg":"trace[1196032801] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8627; }","duration":"123.453337ms","start":"2026-10-09T02:37:00.085422Z","end":"2026-10-09T02:37:00.208875Z","steps":["trace[1196032801] 'agreement among raft nodes before linearized reading' (duration: 123.168829ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:37:00.208951Z","caller":"traceutil/trace.go:171","msg":"trace[1951098015] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8627; }","duration":"123.835817ms","start":"2026-10-09T02:37:00.085086Z","end":"2026-10-09T02:37:00.208922Z","steps":["trace[1951098015] 'agreement among raft nodes before linearized reading' (duration: 123.431586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:37:29.237604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.559259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mqvjj\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-09T02:37:29.23768Z","caller":"traceutil/trace.go:171","msg":"trace[1893257220] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mqvjj; range_end:; response_count:1; response_revision:8834; }","duration":"295.696272ms","start":"2026-10-09T02:37:28.941966Z","end":"2026-10-09T02:37:29.237662Z","steps":["trace[1893257220] 'range keys from in-memory index tree' (duration: 295.427015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:37:29.238641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.455521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T02:37:29.238829Z","caller":"traceutil/trace.go:171","msg":"trace[2024957373] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"216.44504ms","start":"2026-10-09T02:37:29.022346Z","end":"2026-10-09T02:37:29.238791Z","steps":["trace[2024957373] 'process raft request' (duration: 12.538048ms)","trace[2024957373] 'compare' (duration: 203.129181ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:37:42.634079Z","caller":"traceutil/trace.go:171","msg":"trace[941516531] transaction","detail":"{read_only:false; response_revision:8920; number_of_response:1; }","duration":"202.686139ms","start":"2026-10-09T02:37:42.43137Z","end":"2026-10-09T02:37:42.634056Z","steps":["trace[941516531] 'process raft request' (duration: 202.528864ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:37:42.649412Z","caller":"traceutil/trace.go:171","msg":"trace[1819546254] linearizableReadLoop","detail":"{readStateIndex:9242; appliedIndex:9240; }","duration":"141.912957ms","start":"2026-10-09T02:37:42.507477Z","end":"2026-10-09T02:37:42.64939Z","steps":["trace[1819546254] 'read index received' (duration: 126.426928ms)","trace[1819546254] 'applied index is now lower than readState.Index' (duration: 15.484859ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:37:42.649521Z","caller":"traceutil/trace.go:171","msg":"trace[684024399] transaction","detail":"{read_only:false; response_revision:8921; number_of_response:1; }","duration":"212.772508ms","start":"2026-10-09T02:37:42.43674Z","end":"2026-10-09T02:37:42.649512Z","steps":["trace[684024399] 'process raft request' (duration: 212.331576ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:37:42.649733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.254946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-09T02:37:42.649763Z","caller":"traceutil/trace.go:171","msg":"trace[1894790421] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8921; }","duration":"142.346059ms","start":"2026-10-09T02:37:42.507406Z","end":"2026-10-09T02:37:42.649752Z","steps":["trace[1894790421] 'agreement among raft nodes before linearized reading' (duration: 142.231506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:37:42.699972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.891591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7f5mg\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-10-09T02:37:42.700079Z","caller":"traceutil/trace.go:171","msg":"trace[1797202139] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7f5mg; range_end:; response_count:1; response_revision:8922; }","duration":"131.050656ms","start":"2026-10-09T02:37:42.569004Z","end":"2026-10-09T02:37:42.700054Z","steps":["trace[1797202139] 'agreement among raft nodes before linearized reading' (duration: 130.685226ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:37:50.692231Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247a.wal"} {"level":"info","ts":"2026-10-09T02:38:52.948162Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T02:38:52.955859Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-09T02:38:52.955982Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-09T02:39:06.240152Z","caller":"traceutil/trace.go:171","msg":"trace[1277953678] transaction","detail":"{read_only:false; response_revision:9732; number_of_response:1; }","duration":"202.451407ms","start":"2026-10-09T02:39:06.037672Z","end":"2026-10-09T02:39:06.240124Z","steps":["trace[1277953678] 'process raft request' (duration: 202.149259ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:40:52.482588Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8056} {"level":"info","ts":"2026-10-09T02:40:52.550183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8056,"took":"65.512008ms","hash":4222781598,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":28835840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T02:40:52.550252Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4222781598,"revision":8056,"compact-revision":5882} {"level":"info","ts":"2026-10-09T02:42:51.987933Z","caller":"traceutil/trace.go:171","msg":"trace[908828210] transaction","detail":"{read_only:false; response_revision:11597; number_of_response:1; }","duration":"193.515139ms","start":"2026-10-09T02:42:51.794363Z","end":"2026-10-09T02:42:51.987878Z","steps":["trace[908828210] 'process raft request' (duration: 156.169058ms)","trace[908828210] 'compare' (duration: 37.055743ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:42:52.073259Z","caller":"traceutil/trace.go:171","msg":"trace[1982652985] transaction","detail":"{read_only:false; response_revision:11598; number_of_response:1; }","duration":"278.086081ms","start":"2026-10-09T02:42:51.795144Z","end":"2026-10-09T02:42:52.07323Z","steps":["trace[1982652985] 'process raft request' (duration: 277.881026ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:42:52.073573Z","caller":"traceutil/trace.go:171","msg":"trace[575793077] linearizableReadLoop","detail":"{readStateIndex:12001; appliedIndex:11998; }","duration":"156.709263ms","start":"2026-10-09T02:42:51.916835Z","end":"2026-10-09T02:42:52.073544Z","steps":["trace[575793077] 'read index received' (duration: 33.71764ms)","trace[575793077] 'applied index is now lower than readState.Index' (duration: 122.986783ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:42:52.073715Z","caller":"traceutil/trace.go:171","msg":"trace[545588743] transaction","detail":"{read_only:false; response_revision:11599; number_of_response:1; }","duration":"170.588596ms","start":"2026-10-09T02:42:51.903101Z","end":"2026-10-09T02:42:52.07369Z","steps":["trace[545588743] 'process raft request' (duration: 170.084532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:52.073773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.918138ms","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:7"} {"level":"info","ts":"2026-10-09T02:42:52.073818Z","caller":"traceutil/trace.go:171","msg":"trace[1584987284] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11599; }","duration":"157.014711ms","start":"2026-10-09T02:42:51.916792Z","end":"2026-10-09T02:42:52.073807Z","steps":["trace[1584987284] 'agreement among raft nodes before linearized reading' (duration: 156.898038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:52.073854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.136017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:42:52.073909Z","caller":"traceutil/trace.go:171","msg":"trace[1692411935] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11599; }","duration":"123.22995ms","start":"2026-10-09T02:42:51.950665Z","end":"2026-10-09T02:42:52.073895Z","steps":["trace[1692411935] 'agreement among raft nodes before linearized reading' (duration: 123.143927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:52.073922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.636778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-09T02:42:52.073961Z","caller":"traceutil/trace.go:171","msg":"trace[1899893157] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11599; }","duration":"120.70828ms","start":"2026-10-09T02:42:51.953242Z","end":"2026-10-09T02:42:52.07395Z","steps":["trace[1899893157] 'agreement among raft nodes before linearized reading' (duration: 120.603577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:52.073938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.854592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:42:52.074038Z","caller":"traceutil/trace.go:171","msg":"trace[1561099148] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11599; }","duration":"121.966325ms","start":"2026-10-09T02:42:51.952048Z","end":"2026-10-09T02:42:52.074015Z","steps":["trace[1561099148] 'agreement among raft nodes before linearized reading' (duration: 121.79078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:52.073937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.821581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:42:52.074095Z","caller":"traceutil/trace.go:171","msg":"trace[946128733] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11599; }","duration":"122.030247ms","start":"2026-10-09T02:42:51.952054Z","end":"2026-10-09T02:42:52.074085Z","steps":["trace[946128733] 'agreement among raft nodes before linearized reading' (duration: 121.78183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:42:52.074049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.135157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T02:42:52.0742Z","caller":"traceutil/trace.go:171","msg":"trace[1369069920] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11599; }","duration":"123.388233ms","start":"2026-10-09T02:42:51.950792Z","end":"2026-10-09T02:42:52.07418Z","steps":["trace[1369069920] 'agreement among raft nodes before linearized reading' (duration: 123.087005ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:45:52.491009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10500} {"level":"info","ts":"2026-10-09T02:45:52.581692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10500,"took":"88.083953ms","hash":1306936179,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":27742208,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T02:45:52.581763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1306936179,"revision":10500,"compact-revision":8056} {"level":"info","ts":"2026-10-09T02:49:10.088713Z","caller":"traceutil/trace.go:171","msg":"trace[442281583] transaction","detail":"{read_only:false; response_revision:14138; number_of_response:1; }","duration":"106.330971ms","start":"2026-10-09T02:49:09.982348Z","end":"2026-10-09T02:49:10.088679Z","steps":["trace[442281583] 'process raft request' (duration: 105.57085ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:49:41.47161Z","caller":"traceutil/trace.go:171","msg":"trace[441664332] linearizableReadLoop","detail":"{readStateIndex:15014; appliedIndex:15013; }","duration":"121.161132ms","start":"2026-10-09T02:49:41.350427Z","end":"2026-10-09T02:49:41.471588Z","steps":["trace[441664332] 'read index received' (duration: 120.967207ms)","trace[441664332] 'applied index is now lower than readState.Index' (duration: 192.935µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:49:41.471668Z","caller":"traceutil/trace.go:171","msg":"trace[192485053] transaction","detail":"{read_only:false; response_revision:14507; number_of_response:1; }","duration":"151.073239ms","start":"2026-10-09T02:49:41.320561Z","end":"2026-10-09T02:49:41.471634Z","steps":["trace[192485053] 'process raft request' (duration: 150.888044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:49:41.471773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.332906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mqvjj\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-09T02:49:41.471813Z","caller":"traceutil/trace.go:171","msg":"trace[1811449715] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mqvjj; range_end:; response_count:1; response_revision:14507; }","duration":"121.389888ms","start":"2026-10-09T02:49:41.350411Z","end":"2026-10-09T02:49:41.4718Z","steps":["trace[1811449715] 'agreement among raft nodes before linearized reading' (duration: 121.275815ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:49:41.720128Z","caller":"traceutil/trace.go:171","msg":"trace[323756422] transaction","detail":"{read_only:false; response_revision:14508; number_of_response:1; }","duration":"121.519801ms","start":"2026-10-09T02:49:41.59852Z","end":"2026-10-09T02:49:41.72004Z","steps":["trace[323756422] 'process raft request' (duration: 23.367517ms)","trace[323756422] 'compare' (duration: 97.848885ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:50:52.502165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12785} {"level":"info","ts":"2026-10-09T02:50:52.602415Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12785,"took":"97.414645ms","hash":410419513,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":30425088,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T02:50:52.602496Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":410419513,"revision":12785,"compact-revision":10500} {"level":"info","ts":"2026-10-09T02:55:52.509279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15250} {"level":"info","ts":"2026-10-09T02:55:52.607214Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15250,"took":"95.116077ms","hash":3479501132,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":32165888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T02:55:52.607267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3479501132,"revision":15250,"compact-revision":12785} {"level":"info","ts":"2026-10-09T02:56:51.924946Z","caller":"traceutil/trace.go:171","msg":"trace[1615470050] transaction","detail":"{read_only:false; response_revision:17895; number_of_response:1; }","duration":"261.196243ms","start":"2026-10-09T02:56:51.663726Z","end":"2026-10-09T02:56:51.924922Z","steps":["trace[1615470050] 'process raft request' (duration: 261.015308ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:57:46.268003Z","caller":"traceutil/trace.go:171","msg":"trace[681896391] linearizableReadLoop","detail":"{readStateIndex:19200; appliedIndex:19199; }","duration":"100.998849ms","start":"2026-10-09T02:57:46.166977Z","end":"2026-10-09T02:57:46.267976Z","steps":["trace[681896391] 'read index received' (duration: 100.921997ms)","trace[681896391] 'applied index is now lower than readState.Index' (duration: 75.612µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T02:57:46.268316Z","caller":"traceutil/trace.go:171","msg":"trace[1445247507] transaction","detail":"{read_only:false; response_revision:18567; number_of_response:1; }","duration":"105.887864ms","start":"2026-10-09T02:57:46.162414Z","end":"2026-10-09T02:57:46.268302Z","steps":["trace[1445247507] 'process raft request' (duration: 105.410961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T02:57:46.268766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.76025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5032"} {"level":"info","ts":"2026-10-09T02:57:46.26887Z","caller":"traceutil/trace.go:171","msg":"trace[1593270859] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18567; }","duration":"101.910384ms","start":"2026-10-09T02:57:46.16694Z","end":"2026-10-09T02:57:46.26885Z","steps":["trace[1593270859] 'agreement among raft nodes before linearized reading' (duration: 101.589605ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:57:46.27927Z","caller":"traceutil/trace.go:171","msg":"trace[1814650070] transaction","detail":"{read_only:false; response_revision:18568; number_of_response:1; }","duration":"110.82564ms","start":"2026-10-09T02:57:46.16842Z","end":"2026-10-09T02:57:46.279246Z","steps":["trace[1814650070] 'process raft request' (duration: 110.079669ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T02:59:36.159428Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T02:59:36.165428Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-09T02:59:36.165548Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-09T03:00:52.516064Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17477} {"level":"info","ts":"2026-10-09T03:00:52.615091Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17477,"took":"95.781465ms","hash":947729899,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":34910208,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-09T03:00:52.615153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":947729899,"revision":17477,"compact-revision":15250} {"level":"warn","ts":"2026-10-09T03:02:08.261925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.68633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:02:08.262319Z","caller":"traceutil/trace.go:171","msg":"trace[27051901] transaction","detail":"{read_only:false; response_revision:20486; number_of_response:1; }","duration":"284.120275ms","start":"2026-10-09T03:02:07.978162Z","end":"2026-10-09T03:02:08.262282Z","steps":["trace[27051901] 'process raft request' (duration: 136.964422ms)","trace[27051901] 'compare' (duration: 146.579767ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:02:23.963531Z","caller":"traceutil/trace.go:171","msg":"trace[602652440] linearizableReadLoop","detail":"{readStateIndex:21265; appliedIndex:21264; }","duration":"134.619977ms","start":"2026-10-09T03:02:23.828885Z","end":"2026-10-09T03:02:23.963505Z","steps":["trace[602652440] 'read index received' (duration: 134.427582ms)","trace[602652440] 'applied index is now lower than readState.Index' (duration: 191.145µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:02:23.963624Z","caller":"traceutil/trace.go:171","msg":"trace[324430931] transaction","detail":"{read_only:false; response_revision:20566; number_of_response:1; }","duration":"135.290505ms","start":"2026-10-09T03:02:23.828295Z","end":"2026-10-09T03:02:23.963585Z","steps":["trace[324430931] 'process raft request' (duration: 135.069789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:02:23.964015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.107521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T03:02:23.964091Z","caller":"traceutil/trace.go:171","msg":"trace[990359056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20566; }","duration":"135.198213ms","start":"2026-10-09T03:02:23.828882Z","end":"2026-10-09T03:02:23.96408Z","steps":["trace[990359056] 'agreement among raft nodes before linearized reading' (duration: 134.71918ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:02:24.178829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.118338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:02:24.178951Z","caller":"traceutil/trace.go:171","msg":"trace[334704483] linearizableReadLoop","detail":"{readStateIndex:21266; appliedIndex:21265; }","duration":"163.421171ms","start":"2026-10-09T03:02:24.015514Z","end":"2026-10-09T03:02:24.178935Z","steps":["trace[334704483] 'read index received' (duration: 23.055637ms)","trace[334704483] 'applied index is now lower than readState.Index' (duration: 140.364434ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:02:24.179037Z","caller":"traceutil/trace.go:171","msg":"trace[557472521] transaction","detail":"{read_only:false; response_revision:20567; number_of_response:1; }","duration":"207.167989ms","start":"2026-10-09T03:02:23.971858Z","end":"2026-10-09T03:02:24.179026Z","steps":["trace[557472521] 'process raft request' (duration: 66.766643ms)","trace[557472521] 'compare' (duration: 140.016295ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:02:24.179182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.660677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-09T03:02:24.179265Z","caller":"traceutil/trace.go:171","msg":"trace[1253407644] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:20567; }","duration":"163.783201ms","start":"2026-10-09T03:02:24.015464Z","end":"2026-10-09T03:02:24.179247Z","steps":["trace[1253407644] 'agreement among raft nodes before linearized reading' (duration: 163.590595ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:02:24.58482Z","caller":"traceutil/trace.go:171","msg":"trace[439904363] transaction","detail":"{read_only:false; response_revision:20570; number_of_response:1; }","duration":"147.126072ms","start":"2026-10-09T03:02:24.437671Z","end":"2026-10-09T03:02:24.584797Z","steps":["trace[439904363] 'process raft request' (duration: 142.841444ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:02:24.817258Z","caller":"traceutil/trace.go:171","msg":"trace[1214894611] transaction","detail":"{read_only:false; response_revision:20571; number_of_response:1; }","duration":"182.922531ms","start":"2026-10-09T03:02:24.634311Z","end":"2026-10-09T03:02:24.817233Z","steps":["trace[1214894611] 'process raft request' (duration: 182.769006ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:02:24.938793Z","caller":"traceutil/trace.go:171","msg":"trace[172646612] transaction","detail":"{read_only:false; response_revision:20572; number_of_response:1; }","duration":"110.936853ms","start":"2026-10-09T03:02:24.827818Z","end":"2026-10-09T03:02:24.938755Z","steps":["trace[172646612] 'process raft request' (duration: 73.729496ms)","trace[172646612] 'compare' (duration: 36.96997ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:02:25.223267Z","caller":"traceutil/trace.go:171","msg":"trace[217075965] transaction","detail":"{read_only:false; response_revision:20573; number_of_response:1; }","duration":"159.636317ms","start":"2026-10-09T03:02:25.0636Z","end":"2026-10-09T03:02:25.223237Z","steps":["trace[217075965] 'process raft request' (duration: 158.938828ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:05:52.52648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19975} {"level":"info","ts":"2026-10-09T03:05:52.656061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19975,"took":"125.363209ms","hash":651644665,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":31281152,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:05:52.656136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":651644665,"revision":19975,"compact-revision":17477} {"level":"warn","ts":"2026-10-09T03:09:53.545638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.762365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:09:53.545811Z","caller":"traceutil/trace.go:171","msg":"trace[1171671356] linearizableReadLoop","detail":"{readStateIndex:26013; appliedIndex:26011; }","duration":"334.826027ms","start":"2026-10-09T03:09:53.210973Z","end":"2026-10-09T03:09:53.545799Z","steps":["trace[1171671356] 'read index received' (duration: 90.847136ms)","trace[1171671356] 'applied index is now lower than readState.Index' (duration: 243.978171ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:53.545863Z","caller":"traceutil/trace.go:171","msg":"trace[410899532] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"415.022019ms","start":"2026-10-09T03:09:53.130832Z","end":"2026-10-09T03:09:53.545854Z","steps":["trace[410899532] 'process raft request' (duration: 170.971367ms)","trace[410899532] 'compare' (duration: 243.261701ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:53.545899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.130793Z","time spent":"415.085411ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:09:53.545984Z","caller":"traceutil/trace.go:171","msg":"trace[1361146063] transaction","detail":"{read_only:false; response_revision:25185; number_of_response:1; }","duration":"352.183086ms","start":"2026-10-09T03:09:53.193773Z","end":"2026-10-09T03:09:53.545956Z","steps":["trace[1361146063] 'process raft request' (duration: 351.95123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:53.546161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.193746Z","time spent":"352.312259ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:09:53.546164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.170547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T03:09:53.546243Z","caller":"traceutil/trace.go:171","msg":"trace[406722736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25185; }","duration":"335.287689ms","start":"2026-10-09T03:09:53.210932Z","end":"2026-10-09T03:09:53.546219Z","steps":["trace[406722736] 'agreement among raft nodes before linearized reading' (duration: 335.018112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:53.546286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.210916Z","time spent":"335.361952ms","remote":"127.0.0.1:40986","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-10-09T03:09:53.726115Z","caller":"traceutil/trace.go:171","msg":"trace[1656084552] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"321.86801ms","start":"2026-10-09T03:09:53.404232Z","end":"2026-10-09T03:09:53.7261Z","steps":["trace[1656084552] 'process raft request' (duration: 321.752627ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:53.726272Z","caller":"traceutil/trace.go:171","msg":"trace[787878424] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"300.069428ms","start":"2026-10-09T03:09:53.426192Z","end":"2026-10-09T03:09:53.726261Z","steps":["trace[787878424] 'process raft request' (duration: 299.886613ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:53.726256Z","caller":"traceutil/trace.go:171","msg":"trace[1666793858] linearizableReadLoop","detail":"{readStateIndex:26015; appliedIndex:26014; }","duration":"180.407457ms","start":"2026-10-09T03:09:53.545828Z","end":"2026-10-09T03:09:53.726236Z","steps":["trace[1666793858] 'read index received' (duration: 180.247092ms)","trace[1666793858] 'applied index is now lower than readState.Index' (duration: 159.654µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:53.726317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.426172Z","time spent":"300.114129ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:09:53.726379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.818114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:09:53.726403Z","caller":"traceutil/trace.go:171","msg":"trace[1429045519] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25187; }","duration":"211.902746ms","start":"2026-10-09T03:09:53.514493Z","end":"2026-10-09T03:09:53.726396Z","steps":["trace[1429045519] 'agreement among raft nodes before linearized reading' (duration: 211.826744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:53.726574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.591826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:09:53.726594Z","caller":"traceutil/trace.go:171","msg":"trace[490373468] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25187; }","duration":"216.611196ms","start":"2026-10-09T03:09:53.509975Z","end":"2026-10-09T03:09:53.726586Z","steps":["trace[490373468] 'agreement among raft nodes before linearized reading' (duration: 216.579205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:53.72661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.014734ms","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-10-09T03:09:53.726635Z","caller":"traceutil/trace.go:171","msg":"trace[161767168] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25187; }","duration":"351.066095ms","start":"2026-10-09T03:09:53.375561Z","end":"2026-10-09T03:09:53.726627Z","steps":["trace[161767168] 'agreement among raft nodes before linearized reading' (duration: 351.022484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:53.726655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.375544Z","time spent":"351.103496ms","remote":"127.0.0.1:40112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:09:53.726839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.404206Z","time spent":"321.957032ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:09:54.032725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.274705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:09:54.03282Z","caller":"traceutil/trace.go:171","msg":"trace[1360854710] linearizableReadLoop","detail":"{readStateIndex:26016; appliedIndex:26015; }","duration":"306.501636ms","start":"2026-10-09T03:09:53.7263Z","end":"2026-10-09T03:09:54.032802Z","steps":["trace[1360854710] 'read index received' (duration: 138.991655ms)","trace[1360854710] 'applied index is now lower than readState.Index' (duration: 167.508801ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:54.03291Z","caller":"traceutil/trace.go:171","msg":"trace[1327163111] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"482.09762ms","start":"2026-10-09T03:09:53.550802Z","end":"2026-10-09T03:09:54.0329Z","steps":["trace[1327163111] 'process raft request' (duration: 314.557328ms)","trace[1327163111] 'compare' (duration: 166.697689ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:54.032966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.550778Z","time spent":"482.159592ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:09:54.033173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.938369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:09:54.033208Z","caller":"traceutil/trace.go:171","msg":"trace[392192461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25188; }","duration":"323.004761ms","start":"2026-10-09T03:09:53.710193Z","end":"2026-10-09T03:09:54.033198Z","steps":["trace[392192461] 'agreement among raft nodes before linearized reading' (duration: 322.948069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:54.033233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:53.710174Z","time spent":"323.052682ms","remote":"127.0.0.1:40676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T03:09:54.033445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.899758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T03:09:54.033504Z","caller":"traceutil/trace.go:171","msg":"trace[301175268] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25188; }","duration":"173.99554ms","start":"2026-10-09T03:09:53.859499Z","end":"2026-10-09T03:09:54.033494Z","steps":["trace[301175268] 'agreement among raft nodes before linearized reading' (duration: 173.862586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:54.418218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.931046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:09:54.418398Z","caller":"traceutil/trace.go:171","msg":"trace[1356190998] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"319.854444ms","start":"2026-10-09T03:09:54.098527Z","end":"2026-10-09T03:09:54.418382Z","steps":["trace[1356190998] 'process raft request' (duration: 319.792852ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:54.418486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:54.098493Z","time spent":"319.943086ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:09:54.418528Z","caller":"traceutil/trace.go:171","msg":"trace[1336116327] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"377.213956ms","start":"2026-10-09T03:09:54.041288Z","end":"2026-10-09T03:09:54.418502Z","steps":["trace[1336116327] 'process raft request' (duration: 110.91797ms)","trace[1336116327] 'compare' (duration: 265.072352ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:54.418631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:54.041265Z","time spent":"377.304879ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:09:54.41863Z","caller":"traceutil/trace.go:171","msg":"trace[773113556] linearizableReadLoop","detail":"{readStateIndex:26017; appliedIndex:26016; }","duration":"348.432602ms","start":"2026-10-09T03:09:54.070108Z","end":"2026-10-09T03:09:54.418541Z","steps":["trace[773113556] 'read index received' (duration: 82.113355ms)","trace[773113556] 'applied index is now lower than readState.Index' (duration: 266.313247ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:54.418798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.633228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:09:54.418902Z","caller":"traceutil/trace.go:171","msg":"trace[859487446] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25190; }","duration":"348.768262ms","start":"2026-10-09T03:09:54.070104Z","end":"2026-10-09T03:09:54.418872Z","steps":["trace[859487446] 'agreement among raft nodes before linearized reading' (duration: 348.562906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:54.418954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:54.070066Z","time spent":"348.877754ms","remote":"127.0.0.1:49338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:09:54.428652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.985862ms","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-10-09T03:09:54.428687Z","caller":"traceutil/trace.go:171","msg":"trace[700664190] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"136.396793ms","start":"2026-10-09T03:09:54.292232Z","end":"2026-10-09T03:09:54.428629Z","steps":["trace[700664190] 'process raft request' (duration: 136.114865ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:54.428739Z","caller":"traceutil/trace.go:171","msg":"trace[1931372230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25191; }","duration":"148.134997ms","start":"2026-10-09T03:09:54.280581Z","end":"2026-10-09T03:09:54.428716Z","steps":["trace[1931372230] 'agreement among raft nodes before linearized reading' (duration: 147.916001ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:54.71975Z","caller":"traceutil/trace.go:171","msg":"trace[1933381174] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"174.047402ms","start":"2026-10-09T03:09:54.545658Z","end":"2026-10-09T03:09:54.719705Z","steps":["trace[1933381174] 'process raft request' (duration: 173.97054ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:54.720194Z","caller":"traceutil/trace.go:171","msg":"trace[835064003] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"286.481034ms","start":"2026-10-09T03:09:54.43369Z","end":"2026-10-09T03:09:54.720171Z","steps":["trace[835064003] 'process raft request' (duration: 197.264782ms)","trace[835064003] 'compare' (duration: 88.528333ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:54.720212Z","caller":"traceutil/trace.go:171","msg":"trace[1386308945] linearizableReadLoop","detail":"{readStateIndex:26020; appliedIndex:26019; }","duration":"267.015216ms","start":"2026-10-09T03:09:54.453168Z","end":"2026-10-09T03:09:54.720184Z","steps":["trace[1386308945] 'read index received' (duration: 177.799084ms)","trace[1386308945] 'applied index is now lower than readState.Index' (duration: 89.213532ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:54.720369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.187061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:09:54.720402Z","caller":"traceutil/trace.go:171","msg":"trace[717522393] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25193; }","duration":"267.282514ms","start":"2026-10-09T03:09:54.45311Z","end":"2026-10-09T03:09:54.720392Z","steps":["trace[717522393] 'agreement among raft nodes before linearized reading' (duration: 267.194911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:54.720526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.097056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:09:54.720607Z","caller":"traceutil/trace.go:171","msg":"trace[103869064] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25193; }","duration":"123.206599ms","start":"2026-10-09T03:09:54.59738Z","end":"2026-10-09T03:09:54.720586Z","steps":["trace[103869064] 'agreement among raft nodes before linearized reading' (duration: 123.021724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:54.720622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.767637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T03:09:54.72067Z","caller":"traceutil/trace.go:171","msg":"trace[1520282402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25193; }","duration":"105.874081ms","start":"2026-10-09T03:09:54.614784Z","end":"2026-10-09T03:09:54.720658Z","steps":["trace[1520282402] 'agreement among raft nodes before linearized reading' (duration: 105.700276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:55.020809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.217575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:09:55.021038Z","caller":"traceutil/trace.go:171","msg":"trace[1056827699] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"237.385529ms","start":"2026-10-09T03:09:54.783634Z","end":"2026-10-09T03:09:55.021019Z","steps":["trace[1056827699] 'process raft request' (duration: 237.279496ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:55.021017Z","caller":"traceutil/trace.go:171","msg":"trace[866097225] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"294.236877ms","start":"2026-10-09T03:09:54.726753Z","end":"2026-10-09T03:09:55.02099Z","steps":["trace[866097225] 'process raft request' (duration: 131.752785ms)","trace[866097225] 'compare' (duration: 161.730612ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:55.330979Z","caller":"traceutil/trace.go:171","msg":"trace[1132994033] linearizableReadLoop","detail":"{readStateIndex:26024; appliedIndex:26021; }","duration":"383.834208ms","start":"2026-10-09T03:09:54.947109Z","end":"2026-10-09T03:09:55.330943Z","steps":["trace[1132994033] 'read index received' (duration: 55.723697ms)","trace[1132994033] 'applied index is now lower than readState.Index' (duration: 328.108561ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:09:55.33114Z","caller":"traceutil/trace.go:171","msg":"trace[917680481] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"425.408685ms","start":"2026-10-09T03:09:54.905708Z","end":"2026-10-09T03:09:55.331116Z","steps":["trace[917680481] 'process raft request' (duration: 332.508732ms)","trace[917680481] 'compare' (duration: 92.477111ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:09:55.33123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.110065ms","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-10-09T03:09:55.331294Z","caller":"traceutil/trace.go:171","msg":"trace[1394660463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25196; }","duration":"384.207918ms","start":"2026-10-09T03:09:54.947069Z","end":"2026-10-09T03:09:55.331277Z","steps":["trace[1394660463] 'agreement among raft nodes before linearized reading' (duration: 384.036854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:55.331304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:54.905666Z","time spent":"425.509588ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:09:55.331326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:54.947051Z","time spent":"384.26799ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T03:09:55.332786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.646788ms","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-10-09T03:09:55.332776Z","caller":"traceutil/trace.go:171","msg":"trace[2029706720] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"168.592312ms","start":"2026-10-09T03:09:55.164142Z","end":"2026-10-09T03:09:55.332735Z","steps":["trace[2029706720] 'process raft request' (duration: 168.444917ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:09:55.33285Z","caller":"traceutil/trace.go:171","msg":"trace[433435995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25197; }","duration":"381.745001ms","start":"2026-10-09T03:09:54.951079Z","end":"2026-10-09T03:09:55.332824Z","steps":["trace[433435995] 'agreement among raft nodes before linearized reading' (duration: 381.608927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:55.332893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:54.951061Z","time spent":"381.823113ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T03:09:55.333063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.226241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:09:55.333139Z","caller":"traceutil/trace.go:171","msg":"trace[105154421] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25197; }","duration":"346.345924ms","start":"2026-10-09T03:09:54.986775Z","end":"2026-10-09T03:09:55.333121Z","steps":["trace[105154421] 'agreement among raft nodes before linearized reading' (duration: 346.17799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:09:55.333119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.677408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-10-09T03:09:55.333182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:09:54.986762Z","time spent":"346.408716ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-10-09T03:09:55.333217Z","caller":"traceutil/trace.go:171","msg":"trace[398647647] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25197; }","duration":"162.829182ms","start":"2026-10-09T03:09:55.170361Z","end":"2026-10-09T03:09:55.33319Z","steps":["trace[398647647] 'agreement among raft nodes before linearized reading' (duration: 162.502823ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:00.683222Z","caller":"traceutil/trace.go:171","msg":"trace[1695830960] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"242.171651ms","start":"2026-10-09T03:10:00.441021Z","end":"2026-10-09T03:10:00.683193Z","steps":["trace[1695830960] 'process raft request' (duration: 241.983216ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:00.690772Z","caller":"traceutil/trace.go:171","msg":"trace[1633018461] linearizableReadLoop","detail":"{readStateIndex:26082; appliedIndex:26081; }","duration":"241.245325ms","start":"2026-10-09T03:10:00.44951Z","end":"2026-10-09T03:10:00.690755Z","steps":["trace[1633018461] 'read index received' (duration: 233.80502ms)","trace[1633018461] 'applied index is now lower than readState.Index' (duration: 7.439335ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:00.690864Z","caller":"traceutil/trace.go:171","msg":"trace[522941106] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"241.4086ms","start":"2026-10-09T03:10:00.449444Z","end":"2026-10-09T03:10:00.690853Z","steps":["trace[522941106] 'process raft request' (duration: 241.223715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:00.691134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.551274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:00.691181Z","caller":"traceutil/trace.go:171","msg":"trace[894767308] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25253; }","duration":"100.661837ms","start":"2026-10-09T03:10:00.590505Z","end":"2026-10-09T03:10:00.691167Z","steps":["trace[894767308] 'agreement among raft nodes before linearized reading' (duration: 100.575114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:00.691499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.531283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T03:10:00.691651Z","caller":"traceutil/trace.go:171","msg":"trace[463135244] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25253; }","duration":"242.196242ms","start":"2026-10-09T03:10:00.44944Z","end":"2026-10-09T03:10:00.691636Z","steps":["trace[463135244] 'agreement among raft nodes before linearized reading' (duration: 241.503693ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:00.863399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.391285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:00.863554Z","caller":"traceutil/trace.go:171","msg":"trace[2070030719] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"120.030951ms","start":"2026-10-09T03:10:00.743508Z","end":"2026-10-09T03:10:00.863539Z","steps":["trace[2070030719] 'process raft request' (duration: 119.99009ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:00.86383Z","caller":"traceutil/trace.go:171","msg":"trace[1731786487] linearizableReadLoop","detail":"{readStateIndex:26083; appliedIndex:26082; }","duration":"154.970875ms","start":"2026-10-09T03:10:00.708852Z","end":"2026-10-09T03:10:00.863823Z","steps":["trace[1731786487] 'read index received' (duration: 52.095577ms)","trace[1731786487] 'applied index is now lower than readState.Index' (duration: 102.873698ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:00.863792Z","caller":"traceutil/trace.go:171","msg":"trace[1023709584] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"167.061989ms","start":"2026-10-09T03:10:00.696683Z","end":"2026-10-09T03:10:00.863745Z","steps":["trace[1023709584] 'process raft request' (duration: 64.247522ms)","trace[1023709584] 'compare' (duration: 102.274052ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:00.863967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.108738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:00.863991Z","caller":"traceutil/trace.go:171","msg":"trace[1336023222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25255; }","duration":"155.16637ms","start":"2026-10-09T03:10:00.708817Z","end":"2026-10-09T03:10:00.863984Z","steps":["trace[1336023222] 'agreement among raft nodes before linearized reading' (duration: 155.094908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:00.864148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.472682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-09T03:10:00.864203Z","caller":"traceutil/trace.go:171","msg":"trace[1121665264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25255; }","duration":"108.551135ms","start":"2026-10-09T03:10:00.755636Z","end":"2026-10-09T03:10:00.864187Z","steps":["trace[1121665264] 'agreement among raft nodes before linearized reading' (duration: 108.37999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:00.864221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.338372ms","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-10-09T03:10:00.864245Z","caller":"traceutil/trace.go:171","msg":"trace[235249584] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25255; }","duration":"107.373442ms","start":"2026-10-09T03:10:00.756862Z","end":"2026-10-09T03:10:00.864236Z","steps":["trace[235249584] 'agreement among raft nodes before linearized reading' (duration: 107.332391ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:01.032356Z","caller":"traceutil/trace.go:171","msg":"trace[482724886] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"162.326498ms","start":"2026-10-09T03:10:00.870001Z","end":"2026-10-09T03:10:01.032327Z","steps":["trace[482724886] 'process raft request' (duration: 108.37463ms)","trace[482724886] 'compare' (duration: 53.527057ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:01.302916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.404163ms","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-10-09T03:10:01.303023Z","caller":"traceutil/trace.go:171","msg":"trace[1507784030] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25256; }","duration":"144.531407ms","start":"2026-10-09T03:10:01.158478Z","end":"2026-10-09T03:10:01.303009Z","steps":["trace[1507784030] 'range keys from in-memory index tree' (duration: 144.389273ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:01.303327Z","caller":"traceutil/trace.go:171","msg":"trace[732437187] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"260.483376ms","start":"2026-10-09T03:10:01.04282Z","end":"2026-10-09T03:10:01.303303Z","steps":["trace[732437187] 'process raft request' (duration: 253.515914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.465139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.934587ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T03:10:01.46597Z","caller":"traceutil/trace.go:171","msg":"trace[395248282] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"345.118271ms","start":"2026-10-09T03:10:01.120825Z","end":"2026-10-09T03:10:01.465944Z","steps":["trace[395248282] 'process raft request' (duration: 344.395611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.466101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:01.120794Z","time spent":"345.243654ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2297,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T03:10:01.604096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.070459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:01.60425Z","caller":"traceutil/trace.go:171","msg":"trace[1647091347] linearizableReadLoop","detail":"{readStateIndex:26087; appliedIndex:26085; }","duration":"558.105507ms","start":"2026-10-09T03:10:01.046097Z","end":"2026-10-09T03:10:01.604202Z","steps":["trace[1647091347] 'read index received' (duration: 250.239544ms)","trace[1647091347] 'applied index is now lower than readState.Index' (duration: 307.864923ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:01.604402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.272211ms","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-10-09T03:10:01.604521Z","caller":"traceutil/trace.go:171","msg":"trace[1277503050] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"363.390895ms","start":"2026-10-09T03:10:01.241103Z","end":"2026-10-09T03:10:01.604494Z","steps":["trace[1277503050] 'process raft request' (duration: 224.827732ms)","trace[1277503050] 'compare' (duration: 137.923405ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:01.604533Z","caller":"traceutil/trace.go:171","msg":"trace[527899443] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25259; }","duration":"558.415186ms","start":"2026-10-09T03:10:01.04607Z","end":"2026-10-09T03:10:01.604485Z","steps":["trace[527899443] 'agreement among raft nodes before linearized reading' (duration: 558.20836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.604618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:01.046062Z","time spent":"558.518008ms","remote":"127.0.0.1:41002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-09T03:10:01.604644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:01.24108Z","time spent":"363.497828ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:10:01.605795Z","caller":"traceutil/trace.go:171","msg":"trace[2087104333] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"253.983106ms","start":"2026-10-09T03:10:01.351796Z","end":"2026-10-09T03:10:01.605779Z","steps":["trace[2087104333] 'process raft request' (duration: 253.807391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.616772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.234331ms","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-10-09T03:10:01.616844Z","caller":"traceutil/trace.go:171","msg":"trace[86374500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25262; }","duration":"570.331794ms","start":"2026-10-09T03:10:01.046496Z","end":"2026-10-09T03:10:01.616828Z","steps":["trace[86374500] 'agreement among raft nodes before linearized reading' (duration: 570.108378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.616881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:01.046491Z","time spent":"570.381875ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-09T03:10:01.617171Z","caller":"traceutil/trace.go:171","msg":"trace[1611833568] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"263.869409ms","start":"2026-10-09T03:10:01.35329Z","end":"2026-10-09T03:10:01.61716Z","steps":["trace[1611833568] 'process raft request' (duration: 263.081808ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.617634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.680729ms","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-10-09T03:10:01.617696Z","caller":"traceutil/trace.go:171","msg":"trace[1548247012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25262; }","duration":"253.773091ms","start":"2026-10-09T03:10:01.363905Z","end":"2026-10-09T03:10:01.617678Z","steps":["trace[1548247012] 'agreement among raft nodes before linearized reading' (duration: 253.38913ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:01.617764Z","caller":"traceutil/trace.go:171","msg":"trace[1380906966] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"142.892872ms","start":"2026-10-09T03:10:01.474859Z","end":"2026-10-09T03:10:01.617752Z","steps":["trace[1380906966] 'process raft request' (duration: 141.666738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.617907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.343834ms","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-10-09T03:10:01.617938Z","caller":"traceutil/trace.go:171","msg":"trace[696861487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25262; }","duration":"251.415766ms","start":"2026-10-09T03:10:01.366512Z","end":"2026-10-09T03:10:01.617928Z","steps":["trace[696861487] 'agreement among raft nodes before linearized reading' (duration: 251.330443ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:01.952786Z","caller":"traceutil/trace.go:171","msg":"trace[1572946791] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"329.702485ms","start":"2026-10-09T03:10:01.623045Z","end":"2026-10-09T03:10:01.952747Z","steps":["trace[1572946791] 'process raft request' (duration: 329.49604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.95299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:01.623025Z","time spent":"329.8523ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:10:01.972872Z","caller":"traceutil/trace.go:171","msg":"trace[502863311] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"349.332177ms","start":"2026-10-09T03:10:01.623513Z","end":"2026-10-09T03:10:01.972845Z","steps":["trace[502863311] 'process raft request' (duration: 349.125161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.973043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:01.623487Z","time spent":"349.464601ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:10:01.973392Z","caller":"traceutil/trace.go:171","msg":"trace[118975883] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"349.560943ms","start":"2026-10-09T03:10:01.623786Z","end":"2026-10-09T03:10:01.973347Z","steps":["trace[118975883] 'process raft request' (duration: 349.001138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.973525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:01.623777Z","time spent":"349.651116ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:10:01.973904Z","caller":"traceutil/trace.go:171","msg":"trace[1969237782] linearizableReadLoop","detail":"{readStateIndex:26095; appliedIndex:26092; }","duration":"264.129666ms","start":"2026-10-09T03:10:01.709762Z","end":"2026-10-09T03:10:01.973892Z","steps":["trace[1969237782] 'read index received' (duration: 242.841339ms)","trace[1969237782] 'applied index is now lower than readState.Index' (duration: 21.287347ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:01.974074Z","caller":"traceutil/trace.go:171","msg":"trace[822515598] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"209.580091ms","start":"2026-10-09T03:10:01.764483Z","end":"2026-10-09T03:10:01.974063Z","steps":["trace[822515598] 'process raft request' (duration: 208.81139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.975296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.064616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:10:01.975376Z","caller":"traceutil/trace.go:171","msg":"trace[1524583226] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25266; }","duration":"255.1967ms","start":"2026-10-09T03:10:01.72016Z","end":"2026-10-09T03:10:01.975357Z","steps":["trace[1524583226] 'agreement among raft nodes before linearized reading' (duration: 255.067176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.975968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.035571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-09T03:10:01.976012Z","caller":"traceutil/trace.go:171","msg":"trace[827130603] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25266; }","duration":"145.115524ms","start":"2026-10-09T03:10:01.830885Z","end":"2026-10-09T03:10:01.976Z","steps":["trace[827130603] 'agreement among raft nodes before linearized reading' (duration: 144.97886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:01.974286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.487546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:01.978186Z","caller":"traceutil/trace.go:171","msg":"trace[1229819931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25266; }","duration":"268.075865ms","start":"2026-10-09T03:10:01.709728Z","end":"2026-10-09T03:10:01.977804Z","steps":["trace[1229819931] 'agreement among raft nodes before linearized reading' (duration: 264.488736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:02.19384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.407411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:02.193927Z","caller":"traceutil/trace.go:171","msg":"trace[352201162] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25266; }","duration":"120.532025ms","start":"2026-10-09T03:10:02.073375Z","end":"2026-10-09T03:10:02.193907Z","steps":["trace[352201162] 'range keys from in-memory index tree' (duration: 120.34341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:02.193976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.784867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T03:10:02.194063Z","caller":"traceutil/trace.go:171","msg":"trace[934629419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25266; }","duration":"114.90442ms","start":"2026-10-09T03:10:02.079135Z","end":"2026-10-09T03:10:02.19404Z","steps":["trace[934629419] 'range keys from in-memory index tree' (duration: 114.635553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:02.194139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.853567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:02.194315Z","caller":"traceutil/trace.go:171","msg":"trace[28910316] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25266; }","duration":"124.077833ms","start":"2026-10-09T03:10:02.070221Z","end":"2026-10-09T03:10:02.194299Z","steps":["trace[28910316] 'count revisions from in-memory index tree' (duration: 123.770325ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:02.856208Z","caller":"traceutil/trace.go:171","msg":"trace[150626697] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"166.773271ms","start":"2026-10-09T03:10:02.689402Z","end":"2026-10-09T03:10:02.856176Z","steps":["trace[150626697] 'process raft request' (duration: 166.564096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:03.121728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.701053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:03.12191Z","caller":"traceutil/trace.go:171","msg":"trace[1704075768] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"253.178505ms","start":"2026-10-09T03:10:02.868714Z","end":"2026-10-09T03:10:03.121892Z","steps":["trace[1704075768] 'process raft request' (duration: 253.119713ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.122089Z","caller":"traceutil/trace.go:171","msg":"trace[1568100540] linearizableReadLoop","detail":"{readStateIndex:26099; appliedIndex:26097; }","duration":"359.261971ms","start":"2026-10-09T03:10:02.762793Z","end":"2026-10-09T03:10:03.122055Z","steps":["trace[1568100540] 'read index received' (duration: 93.212912ms)","trace[1568100540] 'applied index is now lower than readState.Index' (duration: 266.045559ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:03.122098Z","caller":"traceutil/trace.go:171","msg":"trace[1937226591] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"424.519302ms","start":"2026-10-09T03:10:02.697519Z","end":"2026-10-09T03:10:03.122039Z","steps":["trace[1937226591] 'process raft request' (duration: 267.419708ms)","trace[1937226591] 'compare' (duration: 156.317572ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:03.122203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.412685ms","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-10-09T03:10:03.122246Z","caller":"traceutil/trace.go:171","msg":"trace[642997275] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25270; }","duration":"359.505558ms","start":"2026-10-09T03:10:02.762727Z","end":"2026-10-09T03:10:03.122232Z","steps":["trace[642997275] 'agreement among raft nodes before linearized reading' (duration: 359.426366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:03.12227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:02.762703Z","time spent":"359.559579ms","remote":"127.0.0.1:40128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:10:03.122273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.560574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:03.122319Z","caller":"traceutil/trace.go:171","msg":"trace[1962561115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25270; }","duration":"179.653706ms","start":"2026-10-09T03:10:02.942651Z","end":"2026-10-09T03:10:03.122305Z","steps":["trace[1962561115] 'agreement among raft nodes before linearized reading' (duration: 179.578154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:03.122357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:02.697488Z","time spent":"424.729137ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:10:03.12238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.652127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T03:10:03.122435Z","caller":"traceutil/trace.go:171","msg":"trace[60278755] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25270; }","duration":"253.76139ms","start":"2026-10-09T03:10:02.868659Z","end":"2026-10-09T03:10:03.12242Z","steps":["trace[60278755] 'agreement among raft nodes before linearized reading' (duration: 253.561175ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.27005Z","caller":"traceutil/trace.go:171","msg":"trace[470086788] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"144.723832ms","start":"2026-10-09T03:10:03.125294Z","end":"2026-10-09T03:10:03.270018Z","steps":["trace[470086788] 'process raft request' (duration: 138.577302ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.349393Z","caller":"traceutil/trace.go:171","msg":"trace[1313958383] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"220.730769ms","start":"2026-10-09T03:10:03.128636Z","end":"2026-10-09T03:10:03.349367Z","steps":["trace[1313958383] 'process raft request' (duration: 220.677868ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.349829Z","caller":"traceutil/trace.go:171","msg":"trace[2134071905] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"221.530531ms","start":"2026-10-09T03:10:03.128266Z","end":"2026-10-09T03:10:03.349796Z","steps":["trace[2134071905] 'process raft request' (duration: 220.924304ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.405144Z","caller":"traceutil/trace.go:171","msg":"trace[762094355] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"127.416405ms","start":"2026-10-09T03:10:03.277695Z","end":"2026-10-09T03:10:03.405111Z","steps":["trace[762094355] 'process raft request' (duration: 94.110986ms)","trace[762094355] 'compare' (duration: 32.801935ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:03.697484Z","caller":"traceutil/trace.go:171","msg":"trace[1864853296] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"139.21347ms","start":"2026-10-09T03:10:03.558219Z","end":"2026-10-09T03:10:03.697433Z","steps":["trace[1864853296] 'process raft request' (duration: 134.704236ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.775238Z","caller":"traceutil/trace.go:171","msg":"trace[709872095] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"213.641244ms","start":"2026-10-09T03:10:03.561554Z","end":"2026-10-09T03:10:03.775196Z","steps":["trace[709872095] 'process raft request' (duration: 213.298044ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.775308Z","caller":"traceutil/trace.go:171","msg":"trace[604818616] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"154.609266ms","start":"2026-10-09T03:10:03.620658Z","end":"2026-10-09T03:10:03.775267Z","steps":["trace[604818616] 'process raft request' (duration: 154.351949ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:03.775399Z","caller":"traceutil/trace.go:171","msg":"trace[298003682] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"152.362794ms","start":"2026-10-09T03:10:03.623013Z","end":"2026-10-09T03:10:03.775375Z","steps":["trace[298003682] 'process raft request' (duration: 152.052835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:04.279128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.505935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:04.279227Z","caller":"traceutil/trace.go:171","msg":"trace[2109363969] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"290.69631ms","start":"2026-10-09T03:10:03.988512Z","end":"2026-10-09T03:10:04.279208Z","steps":["trace[2109363969] 'process raft request' (duration: 129.02781ms)","trace[2109363969] 'compare' (duration: 161.162946ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:04.559287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.158825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:04.559486Z","caller":"traceutil/trace.go:171","msg":"trace[2041514369] linearizableReadLoop","detail":"{readStateIndex:26113; appliedIndex:26111; }","duration":"339.609669ms","start":"2026-10-09T03:10:04.219859Z","end":"2026-10-09T03:10:04.559469Z","steps":["trace[2041514369] 'read index received' (duration: 161.105954ms)","trace[2041514369] 'applied index is now lower than readState.Index' (duration: 178.502265ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:04.55961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.745932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T03:10:04.559638Z","caller":"traceutil/trace.go:171","msg":"trace[750652447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25283; }","duration":"339.799584ms","start":"2026-10-09T03:10:04.219826Z","end":"2026-10-09T03:10:04.559626Z","steps":["trace[750652447] 'agreement among raft nodes before linearized reading' (duration: 339.687371ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:04.55963Z","caller":"traceutil/trace.go:171","msg":"trace[1915507850] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"566.248551ms","start":"2026-10-09T03:10:03.993351Z","end":"2026-10-09T03:10:04.559599Z","steps":["trace[1915507850] 'process raft request' (duration: 387.681395ms)","trace[1915507850] 'compare' (duration: 178.017531ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:04.559668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:04.21981Z","time spent":"339.850486ms","remote":"127.0.0.1:40986","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-10-09T03:10:04.559752Z","caller":"traceutil/trace.go:171","msg":"trace[549813918] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"566.333974ms","start":"2026-10-09T03:10:03.993355Z","end":"2026-10-09T03:10:04.559689Z","steps":["trace[549813918] 'process raft request' (duration: 566.026525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:04.559824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:03.99333Z","time spent":"566.368765ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:10:04.559871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:03.993336Z","time spent":"566.475957ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:10:05.065903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.173668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:1 size:11169"} {"level":"info","ts":"2026-10-09T03:10:05.065999Z","caller":"traceutil/trace.go:171","msg":"trace[462683511] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:1; response_revision:25285; }","duration":"210.328413ms","start":"2026-10-09T03:10:04.855646Z","end":"2026-10-09T03:10:05.065975Z","steps":["trace[462683511] 'range keys from in-memory index tree' (duration: 209.928212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:05.066151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.245412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21561"} {"level":"info","ts":"2026-10-09T03:10:05.06622Z","caller":"traceutil/trace.go:171","msg":"trace[1332863672] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:25285; }","duration":"209.354456ms","start":"2026-10-09T03:10:04.856849Z","end":"2026-10-09T03:10:05.066203Z","steps":["trace[1332863672] 'range keys from in-memory index tree' (duration: 209.049087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:05.066334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.441128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7564"} {"level":"info","ts":"2026-10-09T03:10:05.066382Z","caller":"traceutil/trace.go:171","msg":"trace[351490373] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:25285; }","duration":"209.52965ms","start":"2026-10-09T03:10:04.856838Z","end":"2026-10-09T03:10:05.066368Z","steps":["trace[351490373] 'range keys from in-memory index tree' (duration: 209.304224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:05.06648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.006363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1149"} {"level":"info","ts":"2026-10-09T03:10:05.066552Z","caller":"traceutil/trace.go:171","msg":"trace[173660508] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:25285; }","duration":"210.128467ms","start":"2026-10-09T03:10:04.856403Z","end":"2026-10-09T03:10:05.066531Z","steps":["trace[173660508] 'range keys from in-memory index tree' (duration: 209.859479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:05.066586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.371594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"warn","ts":"2026-10-09T03:10:05.066586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.682755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-10-09T03:10:05.066592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.678237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-10-09T03:10:05.066711Z","caller":"traceutil/trace.go:171","msg":"trace[1188929401] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"200.518461ms","start":"2026-10-09T03:10:04.866171Z","end":"2026-10-09T03:10:05.066689Z","steps":["trace[1188929401] 'process raft request' (duration: 69.642671ms)","trace[1188929401] 'compare' (duration: 129.803661ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:05.066756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.610124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:10:05.066742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.993583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-09T03:10:05.066798Z","caller":"traceutil/trace.go:171","msg":"trace[311556161] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25286; }","duration":"192.691156ms","start":"2026-10-09T03:10:04.874095Z","end":"2026-10-09T03:10:05.066786Z","steps":["trace[311556161] 'agreement among raft nodes before linearized reading' (duration: 192.621854ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:05.066818Z","caller":"traceutil/trace.go:171","msg":"trace[849431602] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:25285; }","duration":"210.103786ms","start":"2026-10-09T03:10:04.856696Z","end":"2026-10-09T03:10:05.0668Z","steps":["trace[849431602] 'range keys from in-memory index tree' (duration: 209.652684ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:05.066756Z","caller":"traceutil/trace.go:171","msg":"trace[1703682874] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:25285; }","duration":"208.878082ms","start":"2026-10-09T03:10:04.857847Z","end":"2026-10-09T03:10:05.066725Z","steps":["trace[1703682874] 'range keys from in-memory index tree' (duration: 208.473872ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:05.066694Z","caller":"traceutil/trace.go:171","msg":"trace[1933102940] linearizableReadLoop","detail":"{readStateIndex:26116; appliedIndex:26115; }","duration":"192.534432ms","start":"2026-10-09T03:10:04.874143Z","end":"2026-10-09T03:10:05.066678Z","steps":["trace[1933102940] 'read index received' (duration: 61.677582ms)","trace[1933102940] 'applied index is now lower than readState.Index' (duration: 130.85535ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:05.06667Z","caller":"traceutil/trace.go:171","msg":"trace[604494357] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:25285; }","duration":"210.501327ms","start":"2026-10-09T03:10:04.856147Z","end":"2026-10-09T03:10:05.066648Z","steps":["trace[604494357] 'range keys from in-memory index tree' (duration: 210.157858ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:05.322434Z","caller":"traceutil/trace.go:171","msg":"trace[1901484691] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"194.608969ms","start":"2026-10-09T03:10:05.127793Z","end":"2026-10-09T03:10:05.322402Z","steps":["trace[1901484691] 'process raft request' (duration: 194.510446ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:05.322516Z","caller":"traceutil/trace.go:171","msg":"trace[1963923855] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"194.885146ms","start":"2026-10-09T03:10:05.127598Z","end":"2026-10-09T03:10:05.322483Z","steps":["trace[1963923855] 'process raft request' (duration: 194.461134ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:05.479589Z","caller":"traceutil/trace.go:171","msg":"trace[768020235] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26119; }","duration":"120.71518ms","start":"2026-10-09T03:10:05.358833Z","end":"2026-10-09T03:10:05.479548Z","steps":["trace[768020235] 'read index received' (duration: 101.627893ms)","trace[768020235] 'applied index is now lower than readState.Index' (duration: 19.085417ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:05.47979Z","caller":"traceutil/trace.go:171","msg":"trace[938829746] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"335.810214ms","start":"2026-10-09T03:10:05.143964Z","end":"2026-10-09T03:10:05.479774Z","steps":["trace[938829746] 'process raft request' (duration: 316.473441ms)","trace[938829746] 'compare' (duration: 18.072078ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:05.479952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:05.143942Z","time spent":"335.887106ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7208,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:10:05.480027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.161532ms","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-10-09T03:10:05.48012Z","caller":"traceutil/trace.go:171","msg":"trace[2133984982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25290; }","duration":"121.314147ms","start":"2026-10-09T03:10:05.358786Z","end":"2026-10-09T03:10:05.4801Z","steps":["trace[2133984982] 'agreement among raft nodes before linearized reading' (duration: 121.06501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:05.486933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.989743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-09T03:10:05.487026Z","caller":"traceutil/trace.go:171","msg":"trace[1834042566] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25291; }","duration":"127.121556ms","start":"2026-10-09T03:10:05.359881Z","end":"2026-10-09T03:10:05.487002Z","steps":["trace[1834042566] 'agreement among raft nodes before linearized reading' (duration: 126.971902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:05.776903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.131504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:05.777291Z","caller":"traceutil/trace.go:171","msg":"trace[1488059722] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"292.190161ms","start":"2026-10-09T03:10:05.485061Z","end":"2026-10-09T03:10:05.777251Z","steps":["trace[1488059722] 'process raft request' (duration: 138.575793ms)","trace[1488059722] 'compare' (duration: 152.790145ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:05.777342Z","caller":"traceutil/trace.go:171","msg":"trace[145919762] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"285.473395ms","start":"2026-10-09T03:10:05.491842Z","end":"2026-10-09T03:10:05.777315Z","steps":["trace[145919762] 'process raft request' (duration: 285.26307ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:05.993813Z","caller":"traceutil/trace.go:171","msg":"trace[1378597120] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"212.577263ms","start":"2026-10-09T03:10:05.781204Z","end":"2026-10-09T03:10:05.993781Z","steps":["trace[1378597120] 'process raft request' (duration: 148.224498ms)","trace[1378597120] 'compare' (duration: 64.15435ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:06.029199Z","caller":"traceutil/trace.go:171","msg":"trace[759463382] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"246.332074ms","start":"2026-10-09T03:10:05.782833Z","end":"2026-10-09T03:10:06.029165Z","steps":["trace[759463382] 'process raft request' (duration: 246.144139ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:07.603002Z","caller":"traceutil/trace.go:171","msg":"trace[59609118] transaction","detail":"{read_only:false; response_revision:25305; number_of_response:1; }","duration":"108.963286ms","start":"2026-10-09T03:10:07.494019Z","end":"2026-10-09T03:10:07.602983Z","steps":["trace[59609118] 'process raft request' (duration: 106.137228ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:12.282055Z","caller":"traceutil/trace.go:171","msg":"trace[663152798] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"270.567684ms","start":"2026-10-09T03:10:12.011457Z","end":"2026-10-09T03:10:12.282025Z","steps":["trace[663152798] 'process raft request' (duration: 270.376369ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:12.400543Z","caller":"traceutil/trace.go:171","msg":"trace[1480839447] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"351.393044ms","start":"2026-10-09T03:10:12.049119Z","end":"2026-10-09T03:10:12.400512Z","steps":["trace[1480839447] 'process raft request' (duration: 348.70297ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:12.400568Z","caller":"traceutil/trace.go:171","msg":"trace[212249028] linearizableReadLoop","detail":"{readStateIndex:26178; appliedIndex:26176; }","duration":"342.2032ms","start":"2026-10-09T03:10:12.05834Z","end":"2026-10-09T03:10:12.400543Z","steps":["trace[212249028] 'read index received' (duration: 223.498325ms)","trace[212249028] 'applied index is now lower than readState.Index' (duration: 118.702605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:12.400701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:12.049087Z","time spent":"351.529268ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:10:12.400765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.361183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:10:12.400814Z","caller":"traceutil/trace.go:171","msg":"trace[1658743042] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25347; }","duration":"239.473456ms","start":"2026-10-09T03:10:12.161328Z","end":"2026-10-09T03:10:12.400801Z","steps":["trace[1658743042] 'agreement among raft nodes before linearized reading' (duration: 239.376184ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:12.400933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.586301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T03:10:12.401001Z","caller":"traceutil/trace.go:171","msg":"trace[913901934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25347; }","duration":"342.706694ms","start":"2026-10-09T03:10:12.058277Z","end":"2026-10-09T03:10:12.400984Z","steps":["trace[913901934] 'agreement among raft nodes before linearized reading' (duration: 342.54047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:12.400991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.756571ms","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":"warn","ts":"2026-10-09T03:10:12.40104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:12.058254Z","time spent":"342.775027ms","remote":"127.0.0.1:40986","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-10-09T03:10:12.40107Z","caller":"traceutil/trace.go:171","msg":"trace[2127420292] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25347; }","duration":"116.924536ms","start":"2026-10-09T03:10:12.284128Z","end":"2026-10-09T03:10:12.401053Z","steps":["trace[2127420292] 'agreement among raft nodes before linearized reading' (duration: 116.771002ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:12.752464Z","caller":"traceutil/trace.go:171","msg":"trace[2077234325] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"151.224962ms","start":"2026-10-09T03:10:12.601191Z","end":"2026-10-09T03:10:12.752416Z","steps":["trace[2077234325] 'process raft request' (duration: 151.055208ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:12.755816Z","caller":"traceutil/trace.go:171","msg":"trace[2106378334] linearizableReadLoop","detail":"{readStateIndex:26182; appliedIndex:26180; }","duration":"144.015532ms","start":"2026-10-09T03:10:12.611783Z","end":"2026-10-09T03:10:12.755799Z","steps":["trace[2106378334] 'read index received' (duration: 140.525606ms)","trace[2106378334] 'applied index is now lower than readState.Index' (duration: 3.488756ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:12.756004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.5586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:12.756056Z","caller":"traceutil/trace.go:171","msg":"trace[940161696] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25350; }","duration":"139.659012ms","start":"2026-10-09T03:10:12.616383Z","end":"2026-10-09T03:10:12.756042Z","steps":["trace[940161696] 'agreement among raft nodes before linearized reading' (duration: 139.552109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:12.756066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.976424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"warn","ts":"2026-10-09T03:10:12.75606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.26354ms","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-10-09T03:10:12.756102Z","caller":"traceutil/trace.go:171","msg":"trace[1488257884] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25350; }","duration":"143.016545ms","start":"2026-10-09T03:10:12.613073Z","end":"2026-10-09T03:10:12.756089Z","steps":["trace[1488257884] 'agreement among raft nodes before linearized reading' (duration: 142.8252ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:12.75614Z","caller":"traceutil/trace.go:171","msg":"trace[943412057] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25350; }","duration":"144.414504ms","start":"2026-10-09T03:10:12.611706Z","end":"2026-10-09T03:10:12.756121Z","steps":["trace[943412057] 'agreement among raft nodes before linearized reading' (duration: 144.180888ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:12.756482Z","caller":"traceutil/trace.go:171","msg":"trace[1695428897] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"154.276116ms","start":"2026-10-09T03:10:12.602186Z","end":"2026-10-09T03:10:12.756463Z","steps":["trace[1695428897] 'process raft request' (duration: 153.504804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:13.039888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.396959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:13.040018Z","caller":"traceutil/trace.go:171","msg":"trace[92430259] linearizableReadLoop","detail":"{readStateIndex:26183; appliedIndex:26182; }","duration":"133.970695ms","start":"2026-10-09T03:10:12.906021Z","end":"2026-10-09T03:10:13.039992Z","steps":["trace[92430259] 'read index received' (duration: 33.35855ms)","trace[92430259] 'applied index is now lower than readState.Index' (duration: 100.610105ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:13.040197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.15199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:13.040266Z","caller":"traceutil/trace.go:171","msg":"trace[1154133430] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25351; }","duration":"134.270543ms","start":"2026-10-09T03:10:12.905976Z","end":"2026-10-09T03:10:13.040247Z","steps":["trace[1154133430] 'agreement among raft nodes before linearized reading' (duration: 134.1488ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:13.040613Z","caller":"traceutil/trace.go:171","msg":"trace[52207447] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"279.334045ms","start":"2026-10-09T03:10:12.76126Z","end":"2026-10-09T03:10:13.040594Z","steps":["trace[52207447] 'process raft request' (duration: 178.124274ms)","trace[52207447] 'compare' (duration: 100.160012ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:13.625221Z","caller":"traceutil/trace.go:171","msg":"trace[2015152859] linearizableReadLoop","detail":"{readStateIndex:26186; appliedIndex:26185; }","duration":"161.055323ms","start":"2026-10-09T03:10:13.464144Z","end":"2026-10-09T03:10:13.625199Z","steps":["trace[2015152859] 'read index received' (duration: 160.535849ms)","trace[2015152859] 'applied index is now lower than readState.Index' (duration: 518.484µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:13.625321Z","caller":"traceutil/trace.go:171","msg":"trace[213642682] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"252.673581ms","start":"2026-10-09T03:10:13.372635Z","end":"2026-10-09T03:10:13.625308Z","steps":["trace[213642682] 'process raft request' (duration: 252.062064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:13.625561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.388412ms","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-10-09T03:10:13.625636Z","caller":"traceutil/trace.go:171","msg":"trace[1371382307] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25354; }","duration":"161.518786ms","start":"2026-10-09T03:10:13.4641Z","end":"2026-10-09T03:10:13.625619Z","steps":["trace[1371382307] 'agreement among raft nodes before linearized reading' (duration: 161.389212ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:14.954584Z","caller":"traceutil/trace.go:171","msg":"trace[888832189] linearizableReadLoop","detail":"{readStateIndex:26197; appliedIndex:26196; }","duration":"193.840867ms","start":"2026-10-09T03:10:14.760724Z","end":"2026-10-09T03:10:14.954565Z","steps":["trace[888832189] 'read index received' (duration: 193.613571ms)","trace[888832189] 'applied index is now lower than readState.Index' (duration: 226.736µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:14.954681Z","caller":"traceutil/trace.go:171","msg":"trace[1453886765] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"194.515987ms","start":"2026-10-09T03:10:14.760133Z","end":"2026-10-09T03:10:14.954649Z","steps":["trace[1453886765] 'process raft request' (duration: 194.25833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:14.954753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.014612ms","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-10-09T03:10:14.954786Z","caller":"traceutil/trace.go:171","msg":"trace[606640955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25365; }","duration":"194.090274ms","start":"2026-10-09T03:10:14.760684Z","end":"2026-10-09T03:10:14.954774Z","steps":["trace[606640955] 'agreement among raft nodes before linearized reading' (duration: 193.95325ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:15.126212Z","caller":"traceutil/trace.go:171","msg":"trace[1616133770] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"166.648177ms","start":"2026-10-09T03:10:14.959544Z","end":"2026-10-09T03:10:15.126192Z","steps":["trace[1616133770] 'process raft request' (duration: 96.326577ms)","trace[1616133770] 'compare' (duration: 70.000071ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:17.185177Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006698.wal"} {"level":"warn","ts":"2026-10-09T03:10:18.559464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.572692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:18.559678Z","caller":"traceutil/trace.go:171","msg":"trace[1178236031] transaction","detail":"{read_only:false; response_revision:25459; number_of_response:1; }","duration":"197.824467ms","start":"2026-10-09T03:10:18.361829Z","end":"2026-10-09T03:10:18.559654Z","steps":["trace[1178236031] 'process raft request' (duration: 70.917316ms)","trace[1178236031] 'compare' (duration: 126.412517ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:18.559739Z","caller":"traceutil/trace.go:171","msg":"trace[1635498323] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"137.171364ms","start":"2026-10-09T03:10:18.42252Z","end":"2026-10-09T03:10:18.559692Z","steps":["trace[1635498323] 'process raft request' (duration: 137.052301ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:18.812843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.409248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:18.81291Z","caller":"traceutil/trace.go:171","msg":"trace[1792853131] linearizableReadLoop","detail":"{readStateIndex:26295; appliedIndex:26292; }","duration":"362.372146ms","start":"2026-10-09T03:10:18.450528Z","end":"2026-10-09T03:10:18.8129Z","steps":["trace[1792853131] 'read index received' (duration: 103.257308ms)","trace[1792853131] 'applied index is now lower than readState.Index' (duration: 259.114188ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:18.813092Z","caller":"traceutil/trace.go:171","msg":"trace[321447492] transaction","detail":"{read_only:false; response_revision:25461; number_of_response:1; }","duration":"380.20781ms","start":"2026-10-09T03:10:18.432877Z","end":"2026-10-09T03:10:18.813085Z","steps":["trace[321447492] 'process raft request' (duration: 270.497693ms)","trace[321447492] 'compare' (duration: 109.253004ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:18.813292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.757828ms","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-10-09T03:10:18.813345Z","caller":"traceutil/trace.go:171","msg":"trace[2092762858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25461; }","duration":"362.85143ms","start":"2026-10-09T03:10:18.45048Z","end":"2026-10-09T03:10:18.813332Z","steps":["trace[2092762858] 'agreement among raft nodes before linearized reading' (duration: 362.662305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:18.813373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:18.450464Z","time spent":"362.903391ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T03:10:18.813606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:18.432856Z","time spent":"380.25608ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:10:18.814419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.235071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29a8b366-2f7e-4b04-9c65-7a5ebad0e66d\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-09T03:10:18.814518Z","caller":"traceutil/trace.go:171","msg":"trace[1170370400] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-29a8b366-2f7e-4b04-9c65-7a5ebad0e66d; range_end:; response_count:1; response_revision:25462; }","duration":"363.372514ms","start":"2026-10-09T03:10:18.451127Z","end":"2026-10-09T03:10:18.814499Z","steps":["trace[1170370400] 'agreement among raft nodes before linearized reading' (duration: 363.161499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:18.814561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:18.45111Z","time spent":"363.442006ms","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29a8b366-2f7e-4b04-9c65-7a5ebad0e66d\" "} {"level":"warn","ts":"2026-10-09T03:10:18.814876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.65155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:18.814909Z","caller":"traceutil/trace.go:171","msg":"trace[174250937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25462; }","duration":"107.713221ms","start":"2026-10-09T03:10:18.707186Z","end":"2026-10-09T03:10:18.814899Z","steps":["trace[174250937] 'agreement among raft nodes before linearized reading' (duration: 107.67199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:20.864671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.381032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:20.864738Z","caller":"traceutil/trace.go:171","msg":"trace[1487527859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25477; }","duration":"157.491355ms","start":"2026-10-09T03:10:20.707231Z","end":"2026-10-09T03:10:20.864722Z","steps":["trace[1487527859] 'range keys from in-memory index tree' (duration: 157.31483ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:24.45006Z","caller":"traceutil/trace.go:171","msg":"trace[429571289] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"105.187832ms","start":"2026-10-09T03:10:24.34485Z","end":"2026-10-09T03:10:24.450038Z","steps":["trace[429571289] 'process raft request' (duration: 105.041678ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:24.706796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.179801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-10-09T03:10:24.706866Z","caller":"traceutil/trace.go:171","msg":"trace[75316899] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25522; }","duration":"151.278383ms","start":"2026-10-09T03:10:24.555562Z","end":"2026-10-09T03:10:24.70684Z","steps":["trace[75316899] 'range keys from in-memory index tree' (duration: 150.958555ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:24.707019Z","caller":"traceutil/trace.go:171","msg":"trace[1411838094] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"110.096627ms","start":"2026-10-09T03:10:24.596887Z","end":"2026-10-09T03:10:24.706984Z","steps":["trace[1411838094] 'process raft request' (duration: 71.42354ms)","trace[1411838094] 'compare' (duration: 38.176473ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:24.898207Z","caller":"traceutil/trace.go:171","msg":"trace[56788735] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"180.90817ms","start":"2026-10-09T03:10:24.717278Z","end":"2026-10-09T03:10:24.898186Z","steps":["trace[56788735] 'process raft request' (duration: 180.770077ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:35.899418Z","caller":"traceutil/trace.go:171","msg":"trace[30274359] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"189.451236ms","start":"2026-10-09T03:10:35.709941Z","end":"2026-10-09T03:10:35.899392Z","steps":["trace[30274359] 'process raft request' (duration: 189.300372ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:35.982478Z","caller":"traceutil/trace.go:171","msg":"trace[661425696] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"266.355338ms","start":"2026-10-09T03:10:35.716086Z","end":"2026-10-09T03:10:35.982442Z","steps":["trace[661425696] 'process raft request' (duration: 264.377494ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:36.297688Z","caller":"traceutil/trace.go:171","msg":"trace[2013258259] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"309.59604ms","start":"2026-10-09T03:10:35.988068Z","end":"2026-10-09T03:10:36.297664Z","steps":["trace[2013258259] 'process raft request' (duration: 283.436199ms)","trace[2013258259] 'compare' (duration: 26.051729ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:36.297827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:35.988046Z","time spent":"309.708944ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:10:36.300778Z","caller":"traceutil/trace.go:171","msg":"trace[1841300147] linearizableReadLoop","detail":"{readStateIndex:26459; appliedIndex:26457; }","duration":"261.886425ms","start":"2026-10-09T03:10:36.038868Z","end":"2026-10-09T03:10:36.300754Z","steps":["trace[1841300147] 'read index received' (duration: 232.652538ms)","trace[1841300147] 'applied index is now lower than readState.Index' (duration: 29.232857ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:36.300856Z","caller":"traceutil/trace.go:171","msg":"trace[1149017440] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"312.679436ms","start":"2026-10-09T03:10:35.988147Z","end":"2026-10-09T03:10:36.300827Z","steps":["trace[1149017440] 'process raft request' (duration: 312.47599ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:36.301002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:10:35.988118Z","time spent":"312.799479ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T03:10:36.301014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.139251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.218\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-09T03:10:36.301052Z","caller":"traceutil/trace.go:171","msg":"trace[936988313] range","detail":"{range_begin:/registry/masterleases/162.253.55.218; range_end:; response_count:1; response_revision:25621; }","duration":"262.221624ms","start":"2026-10-09T03:10:36.038817Z","end":"2026-10-09T03:10:36.301039Z","steps":["trace[936988313] 'agreement among raft nodes before linearized reading' (duration: 262.08145ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:36.471934Z","caller":"traceutil/trace.go:171","msg":"trace[502623762] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"166.386231ms","start":"2026-10-09T03:10:36.305518Z","end":"2026-10-09T03:10:36.471904Z","steps":["trace[502623762] 'process raft request' (duration: 146.764129ms)","trace[502623762] 'compare' (duration: 19.474007ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:36.773996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.666398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:36.774257Z","caller":"traceutil/trace.go:171","msg":"trace[387138518] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"268.96337ms","start":"2026-10-09T03:10:36.505212Z","end":"2026-10-09T03:10:36.774175Z","steps":["trace[387138518] 'process raft request' (duration: 122.976563ms)","trace[387138518] 'compare' (duration: 145.197136ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:37.097123Z","caller":"traceutil/trace.go:171","msg":"trace[523486552] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"135.800026ms","start":"2026-10-09T03:10:36.96129Z","end":"2026-10-09T03:10:37.09709Z","steps":["trace[523486552] 'process raft request' (duration: 135.58217ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:37.379939Z","caller":"traceutil/trace.go:171","msg":"trace[2090720606] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"278.108543ms","start":"2026-10-09T03:10:37.1018Z","end":"2026-10-09T03:10:37.379909Z","steps":["trace[2090720606] 'process raft request' (duration: 277.858716ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:37.395831Z","caller":"traceutil/trace.go:171","msg":"trace[684931266] linearizableReadLoop","detail":"{readStateIndex:26467; appliedIndex:26464; }","duration":"170.889204ms","start":"2026-10-09T03:10:37.224912Z","end":"2026-10-09T03:10:37.395801Z","steps":["trace[684931266] 'read index received' (duration: 154.746739ms)","trace[684931266] 'applied index is now lower than readState.Index' (duration: 16.140525ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:37.395941Z","caller":"traceutil/trace.go:171","msg":"trace[1445165099] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"190.866566ms","start":"2026-10-09T03:10:37.205055Z","end":"2026-10-09T03:10:37.395921Z","steps":["trace[1445165099] 'process raft request' (duration: 190.625149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:37.396467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.944956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T03:10:37.395904Z","caller":"traceutil/trace.go:171","msg":"trace[73895000] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"293.864647ms","start":"2026-10-09T03:10:37.102012Z","end":"2026-10-09T03:10:37.395877Z","steps":["trace[73895000] 'process raft request' (duration: 293.496467ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:37.39655Z","caller":"traceutil/trace.go:171","msg":"trace[309265983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25628; }","duration":"171.021198ms","start":"2026-10-09T03:10:37.225506Z","end":"2026-10-09T03:10:37.396527Z","steps":["trace[309265983] 'agreement among raft nodes before linearized reading' (duration: 170.852714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:37.396165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.229563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T03:10:37.39673Z","caller":"traceutil/trace.go:171","msg":"trace[873427776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25628; }","duration":"171.858361ms","start":"2026-10-09T03:10:37.22486Z","end":"2026-10-09T03:10:37.396718Z","steps":["trace[873427776] 'agreement among raft nodes before linearized reading' (duration: 171.107061ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:44.438286Z","caller":"traceutil/trace.go:171","msg":"trace[913890730] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"102.616511ms","start":"2026-10-09T03:10:44.33565Z","end":"2026-10-09T03:10:44.438267Z","steps":["trace[913890730] 'process raft request' (duration: 102.379174ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:44.757351Z","caller":"traceutil/trace.go:171","msg":"trace[82663313] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"173.931078ms","start":"2026-10-09T03:10:44.583391Z","end":"2026-10-09T03:10:44.757322Z","steps":["trace[82663313] 'process raft request' (duration: 97.410297ms)","trace[82663313] 'compare' (duration: 76.363347ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:44.977294Z","caller":"traceutil/trace.go:171","msg":"trace[1601023623] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26532; }","duration":"189.785545ms","start":"2026-10-09T03:10:44.787485Z","end":"2026-10-09T03:10:44.977271Z","steps":["trace[1601023623] 'read index received' (duration: 104.432591ms)","trace[1601023623] 'applied index is now lower than readState.Index' (duration: 85.352054ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:44.977445Z","caller":"traceutil/trace.go:171","msg":"trace[228928504] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"192.220643ms","start":"2026-10-09T03:10:44.785184Z","end":"2026-10-09T03:10:44.977405Z","steps":["trace[228928504] 'process raft request' (duration: 106.672003ms)","trace[228928504] 'compare' (duration: 85.156369ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:44.977501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.988201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-10-09T03:10:44.977541Z","caller":"traceutil/trace.go:171","msg":"trace[497312177] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25693; }","duration":"190.067153ms","start":"2026-10-09T03:10:44.787461Z","end":"2026-10-09T03:10:44.977528Z","steps":["trace[497312177] 'agreement among raft nodes before linearized reading' (duration: 189.893369ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:45.023549Z","caller":"traceutil/trace.go:171","msg":"trace[958591585] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"222.614481ms","start":"2026-10-09T03:10:44.800915Z","end":"2026-10-09T03:10:45.02353Z","steps":["trace[958591585] 'process raft request' (duration: 222.464337ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:45.023888Z","caller":"traceutil/trace.go:171","msg":"trace[394727543] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"221.933873ms","start":"2026-10-09T03:10:44.801928Z","end":"2026-10-09T03:10:45.023862Z","steps":["trace[394727543] 'process raft request' (duration: 221.572283ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:45.219487Z","caller":"traceutil/trace.go:171","msg":"trace[817846627] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"142.420058ms","start":"2026-10-09T03:10:45.077031Z","end":"2026-10-09T03:10:45.219451Z","steps":["trace[817846627] 'process raft request' (duration: 60.216462ms)","trace[817846627] 'compare' (duration: 81.97333ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:45.219502Z","caller":"traceutil/trace.go:171","msg":"trace[469486748] linearizableReadLoop","detail":"{readStateIndex:26537; appliedIndex:26536; }","duration":"141.100622ms","start":"2026-10-09T03:10:45.078356Z","end":"2026-10-09T03:10:45.219457Z","steps":["trace[469486748] 'read index received' (duration: 58.905425ms)","trace[469486748] 'applied index is now lower than readState.Index' (duration: 82.191097ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:45.220018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.643766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-10-09T03:10:45.220253Z","caller":"traceutil/trace.go:171","msg":"trace[1380620331] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25698; }","duration":"141.911404ms","start":"2026-10-09T03:10:45.078325Z","end":"2026-10-09T03:10:45.220236Z","steps":["trace[1380620331] 'agreement among raft nodes before linearized reading' (duration: 141.320598ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:45.590546Z","caller":"traceutil/trace.go:171","msg":"trace[1230167167] linearizableReadLoop","detail":"{readStateIndex:26540; appliedIndex:26539; }","duration":"163.179302ms","start":"2026-10-09T03:10:45.427343Z","end":"2026-10-09T03:10:45.590522Z","steps":["trace[1230167167] 'read index received' (duration: 30.262734ms)","trace[1230167167] 'applied index is now lower than readState.Index' (duration: 132.915528ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:45.590621Z","caller":"traceutil/trace.go:171","msg":"trace[933011518] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"169.28638ms","start":"2026-10-09T03:10:45.421303Z","end":"2026-10-09T03:10:45.590589Z","steps":["trace[933011518] 'process raft request' (duration: 168.999323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:45.590759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.399548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T03:10:45.590815Z","caller":"traceutil/trace.go:171","msg":"trace[1829284635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25700; }","duration":"163.513251ms","start":"2026-10-09T03:10:45.427289Z","end":"2026-10-09T03:10:45.590802Z","steps":["trace[1829284635] 'agreement among raft nodes before linearized reading' (duration: 163.324436ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:45.648158Z","caller":"traceutil/trace.go:171","msg":"trace[546920591] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"105.089749ms","start":"2026-10-09T03:10:45.54301Z","end":"2026-10-09T03:10:45.648099Z","steps":["trace[546920591] 'process raft request' (duration: 104.845902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:45.648191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.955931ms","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-10-09T03:10:45.648258Z","caller":"traceutil/trace.go:171","msg":"trace[1699505080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25701; }","duration":"186.072644ms","start":"2026-10-09T03:10:45.462169Z","end":"2026-10-09T03:10:45.648241Z","steps":["trace[1699505080] 'agreement among raft nodes before linearized reading' (duration: 185.869608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:45.64826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.613105ms","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-10-09T03:10:45.648317Z","caller":"traceutil/trace.go:171","msg":"trace[1442523635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25701; }","duration":"167.729838ms","start":"2026-10-09T03:10:45.480573Z","end":"2026-10-09T03:10:45.648303Z","steps":["trace[1442523635] 'agreement among raft nodes before linearized reading' (duration: 167.537542ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:45.811262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.530167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:45.811525Z","caller":"traceutil/trace.go:171","msg":"trace[1047921276] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"158.3928ms","start":"2026-10-09T03:10:45.653115Z","end":"2026-10-09T03:10:45.811508Z","steps":["trace[1047921276] 'process raft request' (duration: 158.254576ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:45.811534Z","caller":"traceutil/trace.go:171","msg":"trace[625897343] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"216.399339ms","start":"2026-10-09T03:10:45.595095Z","end":"2026-10-09T03:10:45.811494Z","steps":["trace[625897343] 'process raft request' (duration: 96.529963ms)","trace[625897343] 'compare' (duration: 119.28659ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:45.811613Z","caller":"traceutil/trace.go:171","msg":"trace[722654118] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"157.573297ms","start":"2026-10-09T03:10:45.654021Z","end":"2026-10-09T03:10:45.811594Z","steps":["trace[722654118] 'process raft request' (duration: 157.450804ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:47.971812Z","caller":"traceutil/trace.go:171","msg":"trace[872903908] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"151.418538ms","start":"2026-10-09T03:10:47.82035Z","end":"2026-10-09T03:10:47.971768Z","steps":["trace[872903908] 'process raft request' (duration: 65.167168ms)","trace[872903908] 'compare' (duration: 86.114146ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:48.064297Z","caller":"traceutil/trace.go:171","msg":"trace[824927177] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"114.390235ms","start":"2026-10-09T03:10:47.949876Z","end":"2026-10-09T03:10:48.064266Z","steps":["trace[824927177] 'process raft request' (duration: 114.169769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:48.064449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.519238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:48.064545Z","caller":"traceutil/trace.go:171","msg":"trace[171725662] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25720; }","duration":"106.737395ms","start":"2026-10-09T03:10:47.957789Z","end":"2026-10-09T03:10:48.064526Z","steps":["trace[171725662] 'agreement among raft nodes before linearized reading' (duration: 106.437406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:51.272829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.263652ms","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-10-09T03:10:51.272967Z","caller":"traceutil/trace.go:171","msg":"trace[2066928139] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25740; }","duration":"114.420047ms","start":"2026-10-09T03:10:51.158533Z","end":"2026-10-09T03:10:51.272953Z","steps":["trace[2066928139] 'range keys from in-memory index tree' (duration: 114.245112ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:52.532831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22292} {"level":"info","ts":"2026-10-09T03:10:52.647348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22292,"took":"110.19225ms","hash":515799630,"current-db-size-bytes":57479168,"current-db-size":"58 MB","current-db-size-in-use-bytes":45350912,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-09T03:10:52.64741Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":515799630,"revision":22292,"compact-revision":19975} {"level":"warn","ts":"2026-10-09T03:10:53.891037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.730176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:10:53.891136Z","caller":"traceutil/trace.go:171","msg":"trace[227917335] linearizableReadLoop","detail":"{readStateIndex:26601; appliedIndex:26600; }","duration":"212.996516ms","start":"2026-10-09T03:10:53.678119Z","end":"2026-10-09T03:10:53.891115Z","steps":["trace[227917335] 'read index received' (duration: 61.115336ms)","trace[227917335] 'applied index is now lower than readState.Index' (duration: 151.87973ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:53.891222Z","caller":"traceutil/trace.go:171","msg":"trace[454177779] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"218.059106ms","start":"2026-10-09T03:10:53.67315Z","end":"2026-10-09T03:10:53.89121Z","steps":["trace[454177779] 'process raft request' (duration: 66.055633ms)","trace[454177779] 'compare' (duration: 151.490599ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:10:53.89136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.51399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:53.891407Z","caller":"traceutil/trace.go:171","msg":"trace[1436348462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25758; }","duration":"184.603393ms","start":"2026-10-09T03:10:53.706791Z","end":"2026-10-09T03:10:53.891394Z","steps":["trace[1436348462] 'agreement among raft nodes before linearized reading' (duration: 184.525991ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:53.891404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.276154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:53.891673Z","caller":"traceutil/trace.go:171","msg":"trace[588729971] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25758; }","duration":"213.636004ms","start":"2026-10-09T03:10:53.678025Z","end":"2026-10-09T03:10:53.891661Z","steps":["trace[588729971] 'agreement among raft nodes before linearized reading' (duration: 213.330475ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:55.569018Z","caller":"traceutil/trace.go:171","msg":"trace[2013652033] linearizableReadLoop","detail":"{readStateIndex:26614; appliedIndex:26613; }","duration":"259.306784ms","start":"2026-10-09T03:10:55.309687Z","end":"2026-10-09T03:10:55.568994Z","steps":["trace[2013652033] 'read index received' (duration: 259.125329ms)","trace[2013652033] 'applied index is now lower than readState.Index' (duration: 180.605µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:55.569065Z","caller":"traceutil/trace.go:171","msg":"trace[376044046] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"263.025816ms","start":"2026-10-09T03:10:55.306018Z","end":"2026-10-09T03:10:55.569044Z","steps":["trace[376044046] 'process raft request' (duration: 262.842811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:55.569167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.466208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:10:55.569209Z","caller":"traceutil/trace.go:171","msg":"trace[1203283412] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25771; }","duration":"259.56659ms","start":"2026-10-09T03:10:55.30963Z","end":"2026-10-09T03:10:55.569197Z","steps":["trace[1203283412] 'agreement among raft nodes before linearized reading' (duration: 259.486138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:10:55.569579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.063551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:10:55.569604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.008153ms","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-10-09T03:10:55.569647Z","caller":"traceutil/trace.go:171","msg":"trace[130906813] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25771; }","duration":"158.220105ms","start":"2026-10-09T03:10:55.411407Z","end":"2026-10-09T03:10:55.569628Z","steps":["trace[130906813] 'agreement among raft nodes before linearized reading' (duration: 158.095442ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:55.569676Z","caller":"traceutil/trace.go:171","msg":"trace[943423116] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25771; }","duration":"194.118256ms","start":"2026-10-09T03:10:55.375538Z","end":"2026-10-09T03:10:55.569656Z","steps":["trace[943423116] 'agreement among raft nodes before linearized reading' (duration: 193.790507ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:55.737538Z","caller":"traceutil/trace.go:171","msg":"trace[2043842220] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"163.235544ms","start":"2026-10-09T03:10:55.574276Z","end":"2026-10-09T03:10:55.737511Z","steps":["trace[2043842220] 'process raft request' (duration: 80.689506ms)","trace[2043842220] 'compare' (duration: 82.311271ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:10:55.737682Z","caller":"traceutil/trace.go:171","msg":"trace[1658927030] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"162.060661ms","start":"2026-10-09T03:10:55.575593Z","end":"2026-10-09T03:10:55.737654Z","steps":["trace[1658927030] 'process raft request' (duration: 161.851655ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:10:55.809876Z","caller":"traceutil/trace.go:171","msg":"trace[1602664225] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"127.151488ms","start":"2026-10-09T03:10:55.682699Z","end":"2026-10-09T03:10:55.80985Z","steps":["trace[1602664225] 'process raft request' (duration: 127.006594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:01.965756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.984435ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T03:11:01.966269Z","caller":"traceutil/trace.go:171","msg":"trace[1800769931] linearizableReadLoop","detail":"{readStateIndex:26661; appliedIndex:26659; }","duration":"257.266887ms","start":"2026-10-09T03:11:01.708985Z","end":"2026-10-09T03:11:01.966252Z","steps":["trace[1800769931] 'read index received' (duration: 1.741758ms)","trace[1800769931] 'applied index is now lower than readState.Index' (duration: 255.523899ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:01.96637Z","caller":"traceutil/trace.go:171","msg":"trace[828031309] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"284.159659ms","start":"2026-10-09T03:11:01.682192Z","end":"2026-10-09T03:11:01.966351Z","steps":["trace[828031309] 'process raft request' (duration: 283.705037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:01.984082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.076628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:11:01.984158Z","caller":"traceutil/trace.go:171","msg":"trace[895487586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25816; }","duration":"275.230873ms","start":"2026-10-09T03:11:01.708909Z","end":"2026-10-09T03:11:01.98414Z","steps":["trace[895487586] 'agreement among raft nodes before linearized reading' (duration: 257.452572ms)","trace[895487586] 'range keys from in-memory index tree' (duration: 17.668067ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:01.984386Z","caller":"traceutil/trace.go:171","msg":"trace[820537898] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"156.052585ms","start":"2026-10-09T03:11:01.828303Z","end":"2026-10-09T03:11:01.984356Z","steps":["trace[820537898] 'process raft request' (duration: 137.942445ms)","trace[820537898] 'compare' (duration: 17.851962ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:11:01.984543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.309931ms","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-10-09T03:11:01.984636Z","caller":"traceutil/trace.go:171","msg":"trace[1409329940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25818; }","duration":"223.451285ms","start":"2026-10-09T03:11:01.761164Z","end":"2026-10-09T03:11:01.984615Z","steps":["trace[1409329940] 'agreement among raft nodes before linearized reading' (duration: 223.192388ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:01.98471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.855349ms","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-10-09T03:11:01.984778Z","caller":"traceutil/trace.go:171","msg":"trace[1990713532] 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:25818; }","duration":"205.958232ms","start":"2026-10-09T03:11:01.778799Z","end":"2026-10-09T03:11:01.984757Z","steps":["trace[1990713532] 'agreement among raft nodes before linearized reading' (duration: 205.862219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:01.984852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.024606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T03:11:01.984907Z","caller":"traceutil/trace.go:171","msg":"trace[535555924] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25818; }","duration":"184.12186ms","start":"2026-10-09T03:11:01.800772Z","end":"2026-10-09T03:11:01.984894Z","steps":["trace[535555924] 'agreement among raft nodes before linearized reading' (duration: 183.884053ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:06.277586Z","caller":"traceutil/trace.go:171","msg":"trace[1796380658] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"217.776528ms","start":"2026-10-09T03:11:06.059778Z","end":"2026-10-09T03:11:06.277555Z","steps":["trace[1796380658] 'process raft request' (duration: 134.746608ms)","trace[1796380658] 'compare' (duration: 82.872596ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:06.42393Z","caller":"traceutil/trace.go:171","msg":"trace[282267714] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"210.127447ms","start":"2026-10-09T03:11:06.213778Z","end":"2026-10-09T03:11:06.423905Z","steps":["trace[282267714] 'process raft request' (duration: 201.89708ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:06.427797Z","caller":"traceutil/trace.go:171","msg":"trace[1751314287] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"213.962102ms","start":"2026-10-09T03:11:06.213813Z","end":"2026-10-09T03:11:06.427775Z","steps":["trace[1751314287] 'process raft request' (duration: 213.827619ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:11.768959Z","caller":"traceutil/trace.go:171","msg":"trace[840633172] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"110.225551ms","start":"2026-10-09T03:11:11.658693Z","end":"2026-10-09T03:11:11.768919Z","steps":["trace[840633172] 'process raft request' (duration: 109.899092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:11.929634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.715687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T03:11:11.929718Z","caller":"traceutil/trace.go:171","msg":"trace[1632492912] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:25889; }","duration":"101.86002ms","start":"2026-10-09T03:11:11.827836Z","end":"2026-10-09T03:11:11.929696Z","steps":["trace[1632492912] 'count revisions from in-memory index tree' (duration: 101.561262ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:12.201677Z","caller":"traceutil/trace.go:171","msg":"trace[915919669] linearizableReadLoop","detail":"{readStateIndex:26738; appliedIndex:26737; }","duration":"116.535645ms","start":"2026-10-09T03:11:12.085123Z","end":"2026-10-09T03:11:12.201658Z","steps":["trace[915919669] 'read index received' (duration: 49.907307ms)","trace[915919669] 'applied index is now lower than readState.Index' (duration: 66.627558ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:11:12.201875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.50297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T03:11:12.201892Z","caller":"traceutil/trace.go:171","msg":"trace[1601829418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25891; }","duration":"155.567232ms","start":"2026-10-09T03:11:12.046318Z","end":"2026-10-09T03:11:12.201886Z","steps":["trace[1601829418] 'agreement among raft nodes before linearized reading' (duration: 155.444879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:12.201961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.495202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T03:11:12.202032Z","caller":"traceutil/trace.go:171","msg":"trace[1901414300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25891; }","duration":"154.642587ms","start":"2026-10-09T03:11:12.047369Z","end":"2026-10-09T03:11:12.202012Z","steps":["trace[1901414300] 'agreement among raft nodes before linearized reading' (duration: 154.39334ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:12.202072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.752313ms","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-10-09T03:11:12.202091Z","caller":"traceutil/trace.go:171","msg":"trace[1437179373] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25891; }","duration":"111.812245ms","start":"2026-10-09T03:11:12.090273Z","end":"2026-10-09T03:11:12.202085Z","steps":["trace[1437179373] 'agreement among raft nodes before linearized reading' (duration: 111.784734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:12.202036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.288179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T03:11:12.202268Z","caller":"traceutil/trace.go:171","msg":"trace[237928479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25891; }","duration":"161.533906ms","start":"2026-10-09T03:11:12.040723Z","end":"2026-10-09T03:11:12.202257Z","steps":["trace[237928479] 'agreement among raft nodes before linearized reading' (duration: 161.282459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:12.20239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.910861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1042"} {"level":"info","ts":"2026-10-09T03:11:12.20249Z","caller":"traceutil/trace.go:171","msg":"trace[1127446819] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:25891; }","duration":"152.073165ms","start":"2026-10-09T03:11:12.050396Z","end":"2026-10-09T03:11:12.202469Z","steps":["trace[1127446819] 'agreement among raft nodes before linearized reading' (duration: 151.811358ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:12.202587Z","caller":"traceutil/trace.go:171","msg":"trace[1958936477] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"181.98376ms","start":"2026-10-09T03:11:12.020595Z","end":"2026-10-09T03:11:12.202578Z","steps":["trace[1958936477] 'process raft request' (duration: 114.505049ms)","trace[1958936477] 'compare' (duration: 66.457863ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:12.39071Z","caller":"traceutil/trace.go:171","msg":"trace[1890176979] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"182.448184ms","start":"2026-10-09T03:11:12.208236Z","end":"2026-10-09T03:11:12.390684Z","steps":["trace[1890176979] 'process raft request' (duration: 182.232158ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:12.390889Z","caller":"traceutil/trace.go:171","msg":"trace[1119806997] linearizableReadLoop","detail":"{readStateIndex:26741; appliedIndex:26738; }","duration":"182.444763ms","start":"2026-10-09T03:11:12.208392Z","end":"2026-10-09T03:11:12.390837Z","steps":["trace[1119806997] 'read index received' (duration: 66.287509ms)","trace[1119806997] 'applied index is now lower than readState.Index' (duration: 116.154134ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:12.391034Z","caller":"traceutil/trace.go:171","msg":"trace[2025703806] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"182.6677ms","start":"2026-10-09T03:11:12.208344Z","end":"2026-10-09T03:11:12.391011Z","steps":["trace[2025703806] 'process raft request' (duration: 182.299399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:12.391094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.67148ms","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-10-09T03:11:12.391148Z","caller":"traceutil/trace.go:171","msg":"trace[1497112725] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25894; }","duration":"182.774072ms","start":"2026-10-09T03:11:12.208361Z","end":"2026-10-09T03:11:12.391135Z","steps":["trace[1497112725] 'agreement among raft nodes before linearized reading' (duration: 182.653259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:12.391215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.00818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:11:12.391264Z","caller":"traceutil/trace.go:171","msg":"trace[432415474] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25894; }","duration":"104.109142ms","start":"2026-10-09T03:11:12.287143Z","end":"2026-10-09T03:11:12.391252Z","steps":["trace[432415474] 'agreement among raft nodes before linearized reading' (duration: 104.01572ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:12.592259Z","caller":"traceutil/trace.go:171","msg":"trace[2062473149] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"144.745933ms","start":"2026-10-09T03:11:12.44749Z","end":"2026-10-09T03:11:12.592236Z","steps":["trace[2062473149] 'process raft request' (duration: 63.757589ms)","trace[2062473149] 'compare' (duration: 80.808139ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:12.592498Z","caller":"traceutil/trace.go:171","msg":"trace[1258622803] transaction","detail":"{read_only:false; response_revision:25897; number_of_response:1; }","duration":"138.597483ms","start":"2026-10-09T03:11:12.453864Z","end":"2026-10-09T03:11:12.592461Z","steps":["trace[1258622803] 'process raft request' (duration: 138.330936ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:36.645248Z","caller":"traceutil/trace.go:171","msg":"trace[231359169] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"124.495424ms","start":"2026-10-09T03:11:36.520725Z","end":"2026-10-09T03:11:36.64522Z","steps":["trace[231359169] 'process raft request' (duration: 124.304039ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:36.7745Z","caller":"traceutil/trace.go:171","msg":"trace[217891213] linearizableReadLoop","detail":"{readStateIndex:27078; appliedIndex:27076; }","duration":"209.492429ms","start":"2026-10-09T03:11:36.564984Z","end":"2026-10-09T03:11:36.774477Z","steps":["trace[217891213] 'read index received' (duration: 80.187292ms)","trace[217891213] 'applied index is now lower than readState.Index' (duration: 129.303827ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:36.774599Z","caller":"traceutil/trace.go:171","msg":"trace[1498780944] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"249.02039ms","start":"2026-10-09T03:11:36.525548Z","end":"2026-10-09T03:11:36.774568Z","steps":["trace[1498780944] 'process raft request' (duration: 248.725311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:36.774747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.728186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:11:36.774857Z","caller":"traceutil/trace.go:171","msg":"trace[940353160] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26224; }","duration":"209.910101ms","start":"2026-10-09T03:11:36.564927Z","end":"2026-10-09T03:11:36.774837Z","steps":["trace[940353160] 'agreement among raft nodes before linearized reading' (duration: 209.703885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:36.775508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.945087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T03:11:36.77553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.264306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T03:11:36.775579Z","caller":"traceutil/trace.go:171","msg":"trace[1664567802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26226; }","duration":"208.314577ms","start":"2026-10-09T03:11:36.567253Z","end":"2026-10-09T03:11:36.775568Z","steps":["trace[1664567802] 'agreement among raft nodes before linearized reading' (duration: 208.103851ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:11:36.775584Z","caller":"traceutil/trace.go:171","msg":"trace[980721670] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26226; }","duration":"104.069881ms","start":"2026-10-09T03:11:36.671493Z","end":"2026-10-09T03:11:36.775563Z","steps":["trace[980721670] 'agreement among raft nodes before linearized reading' (duration: 103.937717ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:37.203887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.551176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T03:11:37.203995Z","caller":"traceutil/trace.go:171","msg":"trace[1097671702] linearizableReadLoop","detail":"{readStateIndex:27083; appliedIndex:27081; }","duration":"333.683966ms","start":"2026-10-09T03:11:36.870293Z","end":"2026-10-09T03:11:37.203977Z","steps":["trace[1097671702] 'read index received' (duration: 26.742688ms)","trace[1097671702] 'applied index is now lower than readState.Index' (duration: 306.939777ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:11:37.204015Z","caller":"traceutil/trace.go:171","msg":"trace[885547420] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"383.440918ms","start":"2026-10-09T03:11:36.820549Z","end":"2026-10-09T03:11:37.20399Z","steps":["trace[885547420] 'process raft request' (duration: 204.679966ms)","trace[885547420] 'compare' (duration: 178.290599ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T03:11:37.204065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.764998ms","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-10-09T03:11:37.204093Z","caller":"traceutil/trace.go:171","msg":"trace[680831852] 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:26229; }","duration":"333.817759ms","start":"2026-10-09T03:11:36.870263Z","end":"2026-10-09T03:11:37.204081Z","steps":["trace[680831852] 'agreement among raft nodes before linearized reading' (duration: 333.759538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:11:37.204144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:11:36.870252Z","time spent":"333.882761ms","remote":"127.0.0.1:43990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-10-09T03:11:37.204117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T03:11:36.820531Z","time spent":"383.53642ms","remote":"127.0.0.1:40986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T03:11:37.515834Z","caller":"traceutil/trace.go:171","msg":"trace[1847657906] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"210.625381ms","start":"2026-10-09T03:11:37.305184Z","end":"2026-10-09T03:11:37.515809Z","steps":["trace[1847657906] 'process raft request' (duration: 203.238087ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:08.43675Z","caller":"traceutil/trace.go:171","msg":"trace[1797756531] transaction","detail":"{read_only:false; response_revision:27026; number_of_response:1; }","duration":"215.754401ms","start":"2026-10-09T03:13:08.220977Z","end":"2026-10-09T03:13:08.436731Z","steps":["trace[1797756531] 'process raft request' (duration: 215.581436ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:08.842249Z","caller":"traceutil/trace.go:171","msg":"trace[1542421103] linearizableReadLoop","detail":"{readStateIndex:27900; appliedIndex:27899; }","duration":"252.768163ms","start":"2026-10-09T03:13:08.589453Z","end":"2026-10-09T03:13:08.842221Z","steps":["trace[1542421103] 'read index received' (duration: 252.603139ms)","trace[1542421103] 'applied index is now lower than readState.Index' (duration: 163.954µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T03:13:08.842299Z","caller":"traceutil/trace.go:171","msg":"trace[756920992] transaction","detail":"{read_only:false; response_revision:27027; number_of_response:1; }","duration":"270.331548ms","start":"2026-10-09T03:13:08.571938Z","end":"2026-10-09T03:13:08.842269Z","steps":["trace[756920992] 'process raft request' (duration: 270.141293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.842417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.944408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T03:13:08.842459Z","caller":"traceutil/trace.go:171","msg":"trace[915387563] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27027; }","duration":"253.00214ms","start":"2026-10-09T03:13:08.589448Z","end":"2026-10-09T03:13:08.84245Z","steps":["trace[915387563] 'agreement among raft nodes before linearized reading' (duration: 252.909617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T03:13:08.893396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.866441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21431"} {"level":"info","ts":"2026-10-09T03:13:08.893445Z","caller":"traceutil/trace.go:171","msg":"trace[242841970] transaction","detail":"{read_only:false; response_revision:27028; number_of_response:1; }","duration":"194.120875ms","start":"2026-10-09T03:13:08.699303Z","end":"2026-10-09T03:13:08.893424Z","steps":["trace[242841970] 'process raft request' (duration: 186.112664ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:13:08.893463Z","caller":"traceutil/trace.go:171","msg":"trace[1992955904] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:27028; }","duration":"280.063536ms","start":"2026-10-09T03:13:08.61339Z","end":"2026-10-09T03:13:08.893453Z","steps":["trace[1992955904] 'agreement among raft nodes before linearized reading' (duration: 279.870471ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T03:15:52.540275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25752} {"level":"info","ts":"2026-10-09T03:15:52.704459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25752,"took":"159.843861ms","hash":4198789070,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":36278272,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-09T03:15:52.704533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4198789070,"revision":25752,"compact-revision":22292} {"level":"info","ts":"2026-10-09T03:16:53.816359Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:16:53.82389Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-09T03:16:53.824005Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-09T03:20:52.546978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28629} {"level":"info","ts":"2026-10-09T03:20:52.698175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28629,"took":"146.398751ms","hash":1975438266,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31027200,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:20:52.698249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1975438266,"revision":28629,"compact-revision":25752} {"level":"info","ts":"2026-10-09T03:25:52.554778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30853} {"level":"info","ts":"2026-10-09T03:25:52.692736Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30853,"took":"131.550843ms","hash":4156781201,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":30556160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:25:52.692835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4156781201,"revision":30853,"compact-revision":28629} {"level":"info","ts":"2026-10-09T03:30:52.567477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33109} {"level":"info","ts":"2026-10-09T03:30:52.745016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33109,"took":"172.976832ms","hash":3035834020,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T03:30:52.7451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3035834020,"revision":33109,"compact-revision":30853} {"level":"info","ts":"2026-10-09T03:35:52.579616Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35405} {"level":"info","ts":"2026-10-09T03:35:52.750245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35405,"took":"165.447225ms","hash":1946575412,"current-db-size-bytes":58322944,"current-db-size":"58 MB","current-db-size-in-use-bytes":31895552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T03:35:52.750333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1946575412,"revision":35405,"compact-revision":33109} {"level":"info","ts":"2026-10-09T03:38:25.484107Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"bb7d59f4041c8abe","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T03:38:25.490186Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-09T03:38:25.490339Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-09T03:40:52.586849Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37635} {"level":"info","ts":"2026-10-09T03:40:52.714993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37635,"took":"123.879597ms","hash":760328967,"current-db-size-bytes":59170816,"current-db-size":"59 MB","current-db-size-in-use-bytes":32485376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T03:40:52.715056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":760328967,"revision":37635,"compact-revision":35405}