{"level":"warn","ts":"2026-10-15T19:05:31.698455Z","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-15T19:05:31.698547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.147: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.147:2380","--initial-cluster=instance=https://162.253.55.147:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.147:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.147: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-15T19:05:31.698622Z","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-15T19:05:31.698636Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.147:2380"]} {"level":"info","ts":"2026-10-15T19:05:31.698662Z","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-15T19:05:31.699271Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"]} {"level":"info","ts":"2026-10-15T19:05:31.699418Z","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.147:2380"],"listen-peer-urls":["https://162.253.55.147:2380"],"advertise-client-urls":["https://162.253.55.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.147: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-15T19:05:31.704517Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.839287ms"} {"level":"info","ts":"2026-10-15T19:05:31.718664Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4d4c1b1786e4e6dd","cluster-id":"c387147481076682"} {"level":"info","ts":"2026-10-15T19:05:31.718778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T19:05:31.718802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became follower at term 0"} {"level":"info","ts":"2026-10-15T19:05:31.718825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4d4c1b1786e4e6dd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T19:05:31.718839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became follower at term 1"} {"level":"info","ts":"2026-10-15T19:05:31.718884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=(5569856627011806941)"} {"level":"warn","ts":"2026-10-15T19:05:31.791401Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T19:05:31.794878Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T19:05:31.797722Z","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-15T19:05:31.802892Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4d4c1b1786e4e6dd","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T19:05:31.803212Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4d4c1b1786e4e6dd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T19:05:31.803413Z","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-15T19:05:31.803646Z","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-15T19:05:31.803739Z","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-15T19:05:31.805514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd switched to configuration voters=(5569856627011806941)"} {"level":"info","ts":"2026-10-15T19:05:31.806105Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c387147481076682","local-member-id":"4d4c1b1786e4e6dd","added-peer-id":"4d4c1b1786e4e6dd","added-peer-peer-urls":["https://162.253.55.147:2380"]} {"level":"info","ts":"2026-10-15T19:05:31.809645Z","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-15T19:05:31.809768Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.147:2380"} {"level":"info","ts":"2026-10-15T19:05:31.809892Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.147:2380"} {"level":"info","ts":"2026-10-15T19:05:31.810041Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4d4c1b1786e4e6dd","initial-advertise-peer-urls":["https://162.253.55.147:2380"],"listen-peer-urls":["https://162.253.55.147:2380"],"advertise-client-urls":["https://162.253.55.147:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.147:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T19:05:31.810146Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T19:05:32.319471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T19:05:32.319617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T19:05:32.319677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd received MsgPreVoteResp from 4d4c1b1786e4e6dd at term 1"} {"level":"info","ts":"2026-10-15T19:05:32.319728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became candidate at term 2"} {"level":"info","ts":"2026-10-15T19:05:32.319771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd received MsgVoteResp from 4d4c1b1786e4e6dd at term 2"} {"level":"info","ts":"2026-10-15T19:05:32.319815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4d4c1b1786e4e6dd became leader at term 2"} {"level":"info","ts":"2026-10-15T19:05:32.319858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4d4c1b1786e4e6dd elected leader 4d4c1b1786e4e6dd at term 2"} {"level":"info","ts":"2026-10-15T19:05:32.32213Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:05:32.323373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:05:32.323378Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4d4c1b1786e4e6dd","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.147:2379]}","request-path":"/0/members/4d4c1b1786e4e6dd/attributes","cluster-id":"c387147481076682","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T19:05:32.323459Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:05:32.323751Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c387147481076682","local-member-id":"4d4c1b1786e4e6dd","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:05:32.323885Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T19:05:32.323924Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T19:05:32.324009Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:05:32.324082Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:05:32.328013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T19:05:32.328054Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.147:2379"} {"level":"info","ts":"2026-10-15T19:05:52.47209Z","caller":"traceutil/trace.go:171","msg":"trace[745737824] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"102.858958ms","start":"2026-10-15T19:05:52.369205Z","end":"2026-10-15T19:05:52.472064Z","steps":["trace[745737824] 'process raft request' (duration: 102.709894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:16.441172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.385351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:06:16.441405Z","caller":"traceutil/trace.go:171","msg":"trace[636694617] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"169.670223ms","start":"2026-10-15T19:06:16.271683Z","end":"2026-10-15T19:06:16.441353Z","steps":["trace[636694617] 'process raft request' (duration: 61.112822ms)","trace[636694617] 'compare' (duration: 107.221515ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:06:22.988522Z","caller":"traceutil/trace.go:171","msg":"trace[1534541911] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"201.899629ms","start":"2026-10-15T19:06:22.786115Z","end":"2026-10-15T19:06:22.988015Z","steps":["trace[1534541911] 'process raft request' (duration: 195.624739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:22.989116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.775162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z5jkh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T19:06:22.987969Z","caller":"traceutil/trace.go:171","msg":"trace[1042895399] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"123.449326ms","start":"2026-10-15T19:06:22.864323Z","end":"2026-10-15T19:06:22.987772Z","steps":["trace[1042895399] 'read index received' (duration: 116.327062ms)","trace[1042895399] 'applied index is now lower than readState.Index' (duration: 7.119754ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:06:22.989184Z","caller":"traceutil/trace.go:171","msg":"trace[769986384] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z5jkh; range_end:; response_count:1; response_revision:645; }","duration":"124.917095ms","start":"2026-10-15T19:06:22.864249Z","end":"2026-10-15T19:06:22.989166Z","steps":["trace[769986384] 'agreement among raft nodes before linearized reading' (duration: 123.60294ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:23.153837Z","caller":"traceutil/trace.go:171","msg":"trace[1317688835] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"142.089593ms","start":"2026-10-15T19:06:23.011707Z","end":"2026-10-15T19:06:23.153796Z","steps":["trace[1317688835] 'process raft request' (duration: 141.866317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:23.42912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.710363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:06:23.42927Z","caller":"traceutil/trace.go:171","msg":"trace[395979029] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"355.052502ms","start":"2026-10-15T19:06:23.074187Z","end":"2026-10-15T19:06:23.42924Z","steps":["trace[395979029] 'process raft request' (duration: 206.119173ms)","trace[395979029] 'compare' (duration: 148.432925ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:06:23.429346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:06:23.074153Z","time spent":"355.161976ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T19:06:23.992141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.618004ms","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-15T19:06:23.992269Z","caller":"traceutil/trace.go:171","msg":"trace[222090083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"417.808939ms","start":"2026-10-15T19:06:23.574429Z","end":"2026-10-15T19:06:23.992238Z","steps":["trace[222090083] 'range keys from in-memory index tree' (duration: 417.326476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:06:23.992325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:06:23.574406Z","time spent":"417.905272ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T19:06:23.997078Z","caller":"traceutil/trace.go:171","msg":"trace[2067522408] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"117.418052ms","start":"2026-10-15T19:06:23.879633Z","end":"2026-10-15T19:06:23.997052Z","steps":["trace[2067522408] 'process raft request' (duration: 116.617071ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:40.146403Z","caller":"traceutil/trace.go:171","msg":"trace[837955329] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"169.345565ms","start":"2026-10-15T19:06:39.977027Z","end":"2026-10-15T19:06:40.146373Z","steps":["trace[837955329] 'process raft request' (duration: 169.087248ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:32.589055Z","caller":"traceutil/trace.go:171","msg":"trace[622351089] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"103.058494ms","start":"2026-10-15T19:07:32.48597Z","end":"2026-10-15T19:07:32.589029Z","steps":["trace[622351089] 'process raft request' (duration: 102.89972ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:56.992841Z","caller":"traceutil/trace.go:171","msg":"trace[1641551879] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"167.177038ms","start":"2026-10-15T19:07:56.825633Z","end":"2026-10-15T19:07:56.99281Z","steps":["trace[1641551879] 'read index received' (duration: 82.205307ms)","trace[1641551879] 'applied index is now lower than readState.Index' (duration: 84.970421ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:07:56.993008Z","caller":"traceutil/trace.go:171","msg":"trace[1778026718] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"257.372505ms","start":"2026-10-15T19:07:56.735589Z","end":"2026-10-15T19:07:56.992962Z","steps":["trace[1778026718] 'process raft request' (duration: 172.357603ms)","trace[1778026718] 'compare' (duration: 84.639721ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:07:56.993231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.576628ms","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-15T19:07:56.993315Z","caller":"traceutil/trace.go:171","msg":"trace[698914535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1872; }","duration":"167.745533ms","start":"2026-10-15T19:07:56.82555Z","end":"2026-10-15T19:07:56.993295Z","steps":["trace[698914535] 'agreement among raft nodes before linearized reading' (duration: 167.427414ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:07:57.225329Z","caller":"traceutil/trace.go:171","msg":"trace[399713511] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"197.683958ms","start":"2026-10-15T19:07:57.027593Z","end":"2026-10-15T19:07:57.225277Z","steps":["trace[399713511] 'process raft request' (duration: 197.41114ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.279673Z","caller":"traceutil/trace.go:171","msg":"trace[2071516356] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"110.890037ms","start":"2026-10-15T19:08:40.16875Z","end":"2026-10-15T19:08:40.27964Z","steps":["trace[2071516356] 'process raft request' (duration: 110.680932ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.67988Z","caller":"traceutil/trace.go:171","msg":"trace[2595842] linearizableReadLoop","detail":"{readStateIndex:2293; appliedIndex:2292; }","duration":"101.554674ms","start":"2026-10-15T19:08:40.578293Z","end":"2026-10-15T19:08:40.679848Z","steps":["trace[2595842] 'read index received' (duration: 101.341868ms)","trace[2595842] 'applied index is now lower than readState.Index' (duration: 211.556µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:40.67997Z","caller":"traceutil/trace.go:171","msg":"trace[20319997] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"166.863401ms","start":"2026-10-15T19:08:40.513056Z","end":"2026-10-15T19:08:40.67992Z","steps":["trace[20319997] 'process raft request' (duration: 166.591444ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:40.68007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.76116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:08:40.680136Z","caller":"traceutil/trace.go:171","msg":"trace[1655831590] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2218; }","duration":"101.891853ms","start":"2026-10-15T19:08:40.578225Z","end":"2026-10-15T19:08:40.680117Z","steps":["trace[1655831590] 'agreement among raft nodes before linearized reading' (duration: 101.749419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:40.898492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.480952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-15T19:08:40.898593Z","caller":"traceutil/trace.go:171","msg":"trace[440435606] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2218; }","duration":"171.667106ms","start":"2026-10-15T19:08:40.726903Z","end":"2026-10-15T19:08:40.898571Z","steps":["trace[440435606] 'range keys from in-memory index tree' (duration: 171.230706ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:40.898804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.417118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:08:40.898941Z","caller":"traceutil/trace.go:171","msg":"trace[1516638267] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2218; }","duration":"192.645735ms","start":"2026-10-15T19:08:40.706265Z","end":"2026-10-15T19:08:40.898911Z","steps":["trace[1516638267] 'count revisions from in-memory index tree' (duration: 192.327696ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:09:52.896331Z","caller":"traceutil/trace.go:171","msg":"trace[73600151] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"138.539759ms","start":"2026-10-15T19:09:52.757761Z","end":"2026-10-15T19:09:52.896301Z","steps":["trace[73600151] 'process raft request' (duration: 138.356575ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:09:52.910229Z","caller":"traceutil/trace.go:171","msg":"trace[1682332725] transaction","detail":"{read_only:false; response_revision:2767; number_of_response:1; }","duration":"151.810227ms","start":"2026-10-15T19:09:52.758401Z","end":"2026-10-15T19:09:52.910211Z","steps":["trace[1682332725] 'process raft request' (duration: 151.665973ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:09:52.910485Z","caller":"traceutil/trace.go:171","msg":"trace[334405232] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"148.994653ms","start":"2026-10-15T19:09:52.761464Z","end":"2026-10-15T19:09:52.910458Z","steps":["trace[334405232] 'process raft request' (duration: 148.702006ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:09:53.272285Z","caller":"traceutil/trace.go:171","msg":"trace[1609177961] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"115.678241ms","start":"2026-10-15T19:09:53.15657Z","end":"2026-10-15T19:09:53.272248Z","steps":["trace[1609177961] 'process raft request' (duration: 115.451975ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:11:00.009491Z","caller":"traceutil/trace.go:171","msg":"trace[630242707] linearizableReadLoop","detail":"{readStateIndex:3524; appliedIndex:3523; }","duration":"231.694387ms","start":"2026-10-15T19:10:59.777767Z","end":"2026-10-15T19:11:00.009461Z","steps":["trace[630242707] 'read index received' (duration: 180.881921ms)","trace[630242707] 'applied index is now lower than readState.Index' (duration: 50.810726ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:11:00.009616Z","caller":"traceutil/trace.go:171","msg":"trace[249247326] transaction","detail":"{read_only:false; response_revision:3387; number_of_response:1; }","duration":"246.859454ms","start":"2026-10-15T19:10:59.762687Z","end":"2026-10-15T19:11:00.009547Z","steps":["trace[249247326] 'process raft request' (duration: 196.015426ms)","trace[249247326] 'compare' (duration: 50.494379ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:11:00.009759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.976174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-10-15T19:11:00.009799Z","caller":"traceutil/trace.go:171","msg":"trace[32847473] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3387; }","duration":"232.050987ms","start":"2026-10-15T19:10:59.777734Z","end":"2026-10-15T19:11:00.009785Z","steps":["trace[32847473] 'agreement among raft nodes before linearized reading' (duration: 231.880172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:11:00.010083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.262724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-15T19:11:00.01012Z","caller":"traceutil/trace.go:171","msg":"trace[2113805968] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3387; }","duration":"219.321486ms","start":"2026-10-15T19:10:59.790788Z","end":"2026-10-15T19:11:00.01011Z","steps":["trace[2113805968] 'agreement among raft nodes before linearized reading' (duration: 219.233974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:11:00.010198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.922034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:11:00.010276Z","caller":"traceutil/trace.go:171","msg":"trace[454830837] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:3387; }","duration":"186.108339ms","start":"2026-10-15T19:10:59.824147Z","end":"2026-10-15T19:11:00.010255Z","steps":["trace[454830837] 'agreement among raft nodes before linearized reading' (duration: 185.934844ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.088223Z","caller":"traceutil/trace.go:171","msg":"trace[1155992700] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"146.584674ms","start":"2026-10-15T19:12:16.941592Z","end":"2026-10-15T19:12:17.088176Z","steps":["trace[1155992700] 'process raft request' (duration: 146.274106ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.088419Z","caller":"traceutil/trace.go:171","msg":"trace[1107505266] linearizableReadLoop","detail":"{readStateIndex:4747; appliedIndex:4743; }","duration":"138.132972ms","start":"2026-10-15T19:12:16.950241Z","end":"2026-10-15T19:12:17.088374Z","steps":["trace[1107505266] 'read index received' (duration: 137.724672ms)","trace[1107505266] 'applied index is now lower than readState.Index' (duration: 405.29µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:17.08847Z","caller":"traceutil/trace.go:171","msg":"trace[2085575744] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"146.676837ms","start":"2026-10-15T19:12:16.941766Z","end":"2026-10-15T19:12:17.088443Z","steps":["trace[2085575744] 'process raft request' (duration: 146.306227ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.0885Z","caller":"traceutil/trace.go:171","msg":"trace[2044425962] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"143.73748ms","start":"2026-10-15T19:12:16.944729Z","end":"2026-10-15T19:12:17.088466Z","steps":["trace[2044425962] 'process raft request' (duration: 143.454282ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.088603Z","caller":"traceutil/trace.go:171","msg":"trace[991586302] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"145.307321ms","start":"2026-10-15T19:12:16.943194Z","end":"2026-10-15T19:12:17.088501Z","steps":["trace[991586302] 'process raft request' (duration: 144.933332ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.088763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.915321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:6 size:42345"} {"level":"info","ts":"2026-10-15T19:12:17.088803Z","caller":"traceutil/trace.go:171","msg":"trace[629251550] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:6; response_revision:4555; }","duration":"145.019454ms","start":"2026-10-15T19:12:16.943773Z","end":"2026-10-15T19:12:17.088792Z","steps":["trace[629251550] 'agreement among raft nodes before linearized reading' (duration: 144.770338ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.126461Z","caller":"traceutil/trace.go:171","msg":"trace[1077539632] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"169.089375ms","start":"2026-10-15T19:12:16.957344Z","end":"2026-10-15T19:12:17.126433Z","steps":["trace[1077539632] 'process raft request' (duration: 168.915529ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.126472Z","caller":"traceutil/trace.go:171","msg":"trace[2047224404] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"167.248306ms","start":"2026-10-15T19:12:16.959199Z","end":"2026-10-15T19:12:17.126448Z","steps":["trace[2047224404] 'process raft request' (duration: 167.185864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.126801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.917993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-15T19:12:17.126875Z","caller":"traceutil/trace.go:171","msg":"trace[1766824389] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4557; }","duration":"168.024367ms","start":"2026-10-15T19:12:16.958831Z","end":"2026-10-15T19:12:17.126856Z","steps":["trace[1766824389] 'agreement among raft nodes before linearized reading' (duration: 167.75985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.126907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.992822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T19:12:17.126986Z","caller":"traceutil/trace.go:171","msg":"trace[632602866] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4557; }","duration":"145.088576ms","start":"2026-10-15T19:12:16.981883Z","end":"2026-10-15T19:12:17.126971Z","steps":["trace[632602866] 'agreement among raft nodes before linearized reading' (duration: 144.85768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.12722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.247882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:74425"} {"level":"info","ts":"2026-10-15T19:12:17.127253Z","caller":"traceutil/trace.go:171","msg":"trace[288492460] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4557; }","duration":"168.340775ms","start":"2026-10-15T19:12:16.958901Z","end":"2026-10-15T19:12:17.127241Z","steps":["trace[288492460] 'agreement among raft nodes before linearized reading' (duration: 167.646727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.127755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.823964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:54 size:2584746"} {"level":"info","ts":"2026-10-15T19:12:17.127844Z","caller":"traceutil/trace.go:171","msg":"trace[441497547] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:54; response_revision:4557; }","duration":"124.971127ms","start":"2026-10-15T19:12:17.002848Z","end":"2026-10-15T19:12:17.127819Z","steps":["trace[441497547] 'agreement among raft nodes before linearized reading' (duration: 123.675614ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.242028Z","caller":"traceutil/trace.go:171","msg":"trace[247830138] transaction","detail":"{read_only:false; response_revision:4558; number_of_response:1; }","duration":"148.311379ms","start":"2026-10-15T19:12:17.093687Z","end":"2026-10-15T19:12:17.241999Z","steps":["trace[247830138] 'process raft request' (duration: 75.082619ms)","trace[247830138] 'compare' (duration: 72.973383ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:17.242443Z","caller":"traceutil/trace.go:171","msg":"trace[1925182854] transaction","detail":"{read_only:false; response_revision:4559; number_of_response:1; }","duration":"147.119169ms","start":"2026-10-15T19:12:17.095256Z","end":"2026-10-15T19:12:17.242375Z","steps":["trace[1925182854] 'process raft request' (duration: 146.655937ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.242641Z","caller":"traceutil/trace.go:171","msg":"trace[1355083349] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"107.620162ms","start":"2026-10-15T19:12:17.134987Z","end":"2026-10-15T19:12:17.242607Z","steps":["trace[1355083349] 'process raft request' (duration: 107.56248ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.242809Z","caller":"traceutil/trace.go:171","msg":"trace[1473229104] transaction","detail":"{read_only:false; response_revision:4560; number_of_response:1; }","duration":"145.570608ms","start":"2026-10-15T19:12:17.097223Z","end":"2026-10-15T19:12:17.242794Z","steps":["trace[1473229104] 'process raft request' (duration: 145.083315ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.242821Z","caller":"traceutil/trace.go:171","msg":"trace[912429959] transaction","detail":"{read_only:false; response_revision:4561; number_of_response:1; }","duration":"144.925871ms","start":"2026-10-15T19:12:17.097867Z","end":"2026-10-15T19:12:17.242793Z","steps":["trace[912429959] 'process raft request' (duration: 144.568291ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.242866Z","caller":"traceutil/trace.go:171","msg":"trace[452636872] linearizableReadLoop","detail":"{readStateIndex:4753; appliedIndex:4749; }","duration":"116.421963ms","start":"2026-10-15T19:12:17.126422Z","end":"2026-10-15T19:12:17.242844Z","steps":["trace[452636872] 'read index received' (duration: 42.347091ms)","trace[452636872] 'applied index is now lower than readState.Index' (duration: 74.071092ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:17.24295Z","caller":"traceutil/trace.go:171","msg":"trace[2134967436] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"109.563443ms","start":"2026-10-15T19:12:17.133327Z","end":"2026-10-15T19:12:17.242891Z","steps":["trace[2134967436] 'process raft request' (duration: 109.164233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.243131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.667891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/loki-gateway\" ","response":"range_response_count:1 size:4362"} {"level":"info","ts":"2026-10-15T19:12:17.24319Z","caller":"traceutil/trace.go:171","msg":"trace[724487898] range","detail":"{range_begin:/registry/deployments/monitoring/loki-gateway; range_end:; response_count:1; response_revision:4563; }","duration":"145.787724ms","start":"2026-10-15T19:12:17.097392Z","end":"2026-10-15T19:12:17.243179Z","steps":["trace[724487898] 'agreement among raft nodes before linearized reading' (duration: 145.591509ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.243232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.664518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-10-15T19:12:17.243281Z","caller":"traceutil/trace.go:171","msg":"trace[155930333] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4563; }","duration":"111.750731ms","start":"2026-10-15T19:12:17.131517Z","end":"2026-10-15T19:12:17.243268Z","steps":["trace[155930333] 'agreement among raft nodes before linearized reading' (duration: 111.592697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.244604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.283066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4849"} {"level":"info","ts":"2026-10-15T19:12:17.24465Z","caller":"traceutil/trace.go:171","msg":"trace[1450786235] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4563; }","duration":"104.373288ms","start":"2026-10-15T19:12:17.140264Z","end":"2026-10-15T19:12:17.244637Z","steps":["trace[1450786235] 'agreement among raft nodes before linearized reading' (duration: 102.93854ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.39197Z","caller":"traceutil/trace.go:171","msg":"trace[1039784137] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"140.919375ms","start":"2026-10-15T19:12:17.25103Z","end":"2026-10-15T19:12:17.391949Z","steps":["trace[1039784137] 'process raft request' (duration: 140.854384ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.391981Z","caller":"traceutil/trace.go:171","msg":"trace[1090110272] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"143.059122ms","start":"2026-10-15T19:12:17.248885Z","end":"2026-10-15T19:12:17.391945Z","steps":["trace[1090110272] 'process raft request' (duration: 120.117331ms)","trace[1090110272] 'compare' (duration: 22.782987ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:17.392011Z","caller":"traceutil/trace.go:171","msg":"trace[291770488] transaction","detail":"{read_only:false; response_revision:4567; number_of_response:1; }","duration":"140.787283ms","start":"2026-10-15T19:12:17.251207Z","end":"2026-10-15T19:12:17.391994Z","steps":["trace[291770488] 'process raft request' (duration: 140.69858ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.392149Z","caller":"traceutil/trace.go:171","msg":"trace[165231553] transaction","detail":"{read_only:false; response_revision:4568; number_of_response:1; }","duration":"138.455901ms","start":"2026-10-15T19:12:17.253671Z","end":"2026-10-15T19:12:17.392127Z","steps":["trace[165231553] 'process raft request' (duration: 138.280426ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:17.392269Z","caller":"traceutil/trace.go:171","msg":"trace[1009815153] linearizableReadLoop","detail":"{readStateIndex:4759; appliedIndex:4755; }","duration":"140.900755ms","start":"2026-10-15T19:12:17.251361Z","end":"2026-10-15T19:12:17.392261Z","steps":["trace[1009815153] 'read index received' (duration: 5.803082ms)","trace[1009815153] 'applied index is now lower than readState.Index' (duration: 135.096743ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:17.392325Z","caller":"traceutil/trace.go:171","msg":"trace[1205938200] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"137.429444ms","start":"2026-10-15T19:12:17.254889Z","end":"2026-10-15T19:12:17.392319Z","steps":["trace[1205938200] 'process raft request' (duration: 137.093175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.392462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.601898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-15T19:12:17.392497Z","caller":"traceutil/trace.go:171","msg":"trace[789567492] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4570; }","duration":"132.697261ms","start":"2026-10-15T19:12:17.259791Z","end":"2026-10-15T19:12:17.392488Z","steps":["trace[789567492] 'agreement among raft nodes before linearized reading' (duration: 132.566167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.392593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.569218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-10-15T19:12:17.392607Z","caller":"traceutil/trace.go:171","msg":"trace[477398502] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4570; }","duration":"116.584088ms","start":"2026-10-15T19:12:17.276018Z","end":"2026-10-15T19:12:17.392602Z","steps":["trace[477398502] 'agreement among raft nodes before linearized reading' (duration: 116.535297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:17.392618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.251565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-5cc45946cd-hvdh7\" ","response":"range_response_count:1 size:3594"} {"level":"info","ts":"2026-10-15T19:12:17.392679Z","caller":"traceutil/trace.go:171","msg":"trace[513992598] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-5cc45946cd-hvdh7; range_end:; response_count:1; response_revision:4570; }","duration":"141.307097ms","start":"2026-10-15T19:12:17.25135Z","end":"2026-10-15T19:12:17.392657Z","steps":["trace[513992598] 'agreement among raft nodes before linearized reading' (duration: 141.211054ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:18.652881Z","caller":"traceutil/trace.go:171","msg":"trace[2079233875] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"137.612659ms","start":"2026-10-15T19:12:18.515226Z","end":"2026-10-15T19:12:18.652839Z","steps":["trace[2079233875] 'process raft request' (duration: 137.559438ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:18.653121Z","caller":"traceutil/trace.go:171","msg":"trace[6046630] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"138.683247ms","start":"2026-10-15T19:12:18.514386Z","end":"2026-10-15T19:12:18.653069Z","steps":["trace[6046630] 'process raft request' (duration: 119.814932ms)","trace[6046630] 'compare' (duration: 18.300089ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:18.653117Z","caller":"traceutil/trace.go:171","msg":"trace[555884941] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"138.674537ms","start":"2026-10-15T19:12:18.514393Z","end":"2026-10-15T19:12:18.653067Z","steps":["trace[555884941] 'process raft request' (duration: 138.337437ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:20.047025Z","caller":"traceutil/trace.go:171","msg":"trace[65564462] linearizableReadLoop","detail":"{readStateIndex:4823; appliedIndex:4821; }","duration":"231.459371ms","start":"2026-10-15T19:12:19.815532Z","end":"2026-10-15T19:12:20.046991Z","steps":["trace[65564462] 'read index received' (duration: 223.297067ms)","trace[65564462] 'applied index is now lower than readState.Index' (duration: 8.160864ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:20.047395Z","caller":"traceutil/trace.go:171","msg":"trace[1882865437] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"246.407372ms","start":"2026-10-15T19:12:19.800972Z","end":"2026-10-15T19:12:20.047379Z","steps":["trace[1882865437] 'process raft request' (duration: 237.799176ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:20.047587Z","caller":"traceutil/trace.go:171","msg":"trace[421318018] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"236.490342ms","start":"2026-10-15T19:12:19.811052Z","end":"2026-10-15T19:12:20.047542Z","steps":["trace[421318018] 'process raft request' (duration: 235.875556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:20.047704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.152348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-rn4bp\" ","response":"range_response_count:1 size:4948"} {"level":"info","ts":"2026-10-15T19:12:20.047786Z","caller":"traceutil/trace.go:171","msg":"trace[217683729] range","detail":"{range_begin:/registry/pods/monitoring/vector-rn4bp; range_end:; response_count:1; response_revision:4628; }","duration":"232.293432ms","start":"2026-10-15T19:12:19.815473Z","end":"2026-10-15T19:12:20.047767Z","steps":["trace[217683729] 'agreement among raft nodes before linearized reading' (duration: 232.044485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:20.048016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.583392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:790539"} {"level":"info","ts":"2026-10-15T19:12:20.048115Z","caller":"traceutil/trace.go:171","msg":"trace[583634149] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4628; }","duration":"149.689455ms","start":"2026-10-15T19:12:19.898401Z","end":"2026-10-15T19:12:20.04809Z","steps":["trace[583634149] 'agreement among raft nodes before linearized reading' (duration: 149.073559ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:24.884416Z","caller":"traceutil/trace.go:171","msg":"trace[1863225590] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"197.47943ms","start":"2026-10-15T19:12:24.686907Z","end":"2026-10-15T19:12:24.884386Z","steps":["trace[1863225590] 'process raft request' (duration: 197.397078ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:24.884531Z","caller":"traceutil/trace.go:171","msg":"trace[2075110596] transaction","detail":"{read_only:false; response_revision:4713; number_of_response:1; }","duration":"198.908397ms","start":"2026-10-15T19:12:24.685599Z","end":"2026-10-15T19:12:24.884508Z","steps":["trace[2075110596] 'process raft request' (duration: 136.900901ms)","trace[2075110596] 'compare' (duration: 61.651077ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:24.884628Z","caller":"traceutil/trace.go:171","msg":"trace[88952608] linearizableReadLoop","detail":"{readStateIndex:4914; appliedIndex:4911; }","duration":"167.520084ms","start":"2026-10-15T19:12:24.71707Z","end":"2026-10-15T19:12:24.88459Z","steps":["trace[88952608] 'read index received' (duration: 105.440476ms)","trace[88952608] 'applied index is now lower than readState.Index' (duration: 62.077078ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:12:24.884758Z","caller":"traceutil/trace.go:171","msg":"trace[1496552572] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"197.215192ms","start":"2026-10-15T19:12:24.68751Z","end":"2026-10-15T19:12:24.884725Z","steps":["trace[1496552572] 'process raft request' (duration: 196.841762ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:24.884854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.75855ms","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-15T19:12:24.88495Z","caller":"traceutil/trace.go:171","msg":"trace[179779408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4715; }","duration":"167.898233ms","start":"2026-10-15T19:12:24.717041Z","end":"2026-10-15T19:12:24.884939Z","steps":["trace[179779408] 'agreement among raft nodes before linearized reading' (duration: 167.687278ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:25.284965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.771209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wwfg7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T19:12:25.285066Z","caller":"traceutil/trace.go:171","msg":"trace[204607708] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wwfg7; range_end:; response_count:1; response_revision:4725; }","duration":"162.964373ms","start":"2026-10-15T19:12:25.12208Z","end":"2026-10-15T19:12:25.285044Z","steps":["trace[204607708] 'range keys from in-memory index tree' (duration: 162.563543ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:25.550991Z","caller":"traceutil/trace.go:171","msg":"trace[1811053674] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"254.6873ms","start":"2026-10-15T19:12:25.296271Z","end":"2026-10-15T19:12:25.550958Z","steps":["trace[1811053674] 'process raft request' (duration: 254.048013ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:25.551579Z","caller":"traceutil/trace.go:171","msg":"trace[922166854] linearizableReadLoop","detail":"{readStateIndex:4928; appliedIndex:4926; }","duration":"223.091561ms","start":"2026-10-15T19:12:25.328473Z","end":"2026-10-15T19:12:25.551565Z","steps":["trace[922166854] 'read index received' (duration: 221.747716ms)","trace[922166854] 'applied index is now lower than readState.Index' (duration: 1.342575ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:12:25.551753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.269416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-10-15T19:12:25.551789Z","caller":"traceutil/trace.go:171","msg":"trace[752779635] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4729; }","duration":"223.364389ms","start":"2026-10-15T19:12:25.328416Z","end":"2026-10-15T19:12:25.55178Z","steps":["trace[752779635] 'agreement among raft nodes before linearized reading' (duration: 223.201435ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:25.552074Z","caller":"traceutil/trace.go:171","msg":"trace[1058536695] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"254.433053ms","start":"2026-10-15T19:12:25.297628Z","end":"2026-10-15T19:12:25.552061Z","steps":["trace[1058536695] 'process raft request' (duration: 253.741295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:37.706268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.743541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:12:37.706315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.012489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wwfg7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T19:12:37.706389Z","caller":"traceutil/trace.go:171","msg":"trace[1747407726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4931; }","duration":"208.916286ms","start":"2026-10-15T19:12:37.497447Z","end":"2026-10-15T19:12:37.706363Z","steps":["trace[1747407726] 'range keys from in-memory index tree' (duration: 208.644169ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:12:37.706432Z","caller":"traceutil/trace.go:171","msg":"trace[461561892] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wwfg7; range_end:; response_count:1; response_revision:4931; }","duration":"360.178104ms","start":"2026-10-15T19:12:37.346232Z","end":"2026-10-15T19:12:37.70641Z","steps":["trace[461561892] 'range keys from in-memory index tree' (duration: 359.829234ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:37.706477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:12:37.346209Z","time spent":"360.254555ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-wwfg7\" "} {"level":"warn","ts":"2026-10-15T19:12:37.706496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.249695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T19:12:37.706984Z","caller":"traceutil/trace.go:171","msg":"trace[118703523] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4931; }","duration":"516.803369ms","start":"2026-10-15T19:12:37.190145Z","end":"2026-10-15T19:12:37.706949Z","steps":["trace[118703523] 'range keys from in-memory index tree' (duration: 515.718191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:37.707075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:12:37.190123Z","time spent":"516.931353ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T19:12:47.275867Z","caller":"traceutil/trace.go:171","msg":"trace[1071813805] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"137.411999ms","start":"2026-10-15T19:12:47.138417Z","end":"2026-10-15T19:12:47.275829Z","steps":["trace[1071813805] 'process raft request' (duration: 137.086531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:47.314032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.977462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T19:12:47.314134Z","caller":"traceutil/trace.go:171","msg":"trace[377186446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5119; }","duration":"128.112416ms","start":"2026-10-15T19:12:47.185998Z","end":"2026-10-15T19:12:47.314111Z","steps":["trace[377186446] 'agreement among raft nodes before linearized reading' (duration: 127.818007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:12:47.314393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.407338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-15T19:12:47.314462Z","caller":"traceutil/trace.go:171","msg":"trace[1207536151] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5119; }","duration":"127.515351ms","start":"2026-10-15T19:12:47.186929Z","end":"2026-10-15T19:12:47.314444Z","steps":["trace[1207536151] 'agreement among raft nodes before linearized reading' (duration: 127.365617ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:35.057058Z","caller":"traceutil/trace.go:171","msg":"trace[788011430] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5642; }","duration":"130.21713ms","start":"2026-10-15T19:13:34.926801Z","end":"2026-10-15T19:13:35.057018Z","steps":["trace[788011430] 'process raft request' (duration: 59.787056ms)","trace[788011430] 'compare' (duration: 70.305921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:13:35.399066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.034702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-10-15T19:13:35.399171Z","caller":"traceutil/trace.go:171","msg":"trace[942740142] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5643; }","duration":"228.189856ms","start":"2026-10-15T19:13:35.170949Z","end":"2026-10-15T19:13:35.399139Z","steps":["trace[942740142] 'range keys from in-memory index tree' (duration: 227.760775ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:35.399197Z","caller":"traceutil/trace.go:171","msg":"trace[598266232] transaction","detail":"{read_only:false; response_revision:5644; number_of_response:1; }","duration":"105.802361ms","start":"2026-10-15T19:13:35.293354Z","end":"2026-10-15T19:13:35.399156Z","steps":["trace[598266232] 'process raft request' (duration: 75.264931ms)","trace[598266232] 'compare' (duration: 30.333834ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:13:35.401255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.172764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2588692"} {"level":"info","ts":"2026-10-15T19:13:35.401373Z","caller":"traceutil/trace.go:171","msg":"trace[971039696] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5643; }","duration":"231.37405ms","start":"2026-10-15T19:13:35.169974Z","end":"2026-10-15T19:13:35.401348Z","steps":["trace[971039696] 'range keys from in-memory index tree' (duration: 228.556176ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:32.363356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3190} {"level":"info","ts":"2026-10-15T19:15:32.469739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3190,"took":"104.244648ms","hash":3859623313,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":33878016,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T19:15:32.469813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3859623313,"revision":3190,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:18:42.186619Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021f5.wal"} {"level":"info","ts":"2026-10-15T19:18:45.285522Z","caller":"traceutil/trace.go:171","msg":"trace[789783835] transaction","detail":"{read_only:false; response_revision:8353; number_of_response:1; }","duration":"144.862626ms","start":"2026-10-15T19:18:45.140621Z","end":"2026-10-15T19:18:45.285483Z","steps":["trace[789783835] 'process raft request' (duration: 144.571079ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:47.057745Z","caller":"traceutil/trace.go:171","msg":"trace[264502728] linearizableReadLoop","detail":"{readStateIndex:8731; appliedIndex:8730; }","duration":"392.889515ms","start":"2026-10-15T19:18:46.664828Z","end":"2026-10-15T19:18:47.057717Z","steps":["trace[264502728] 'read index received' (duration: 392.613788ms)","trace[264502728] 'applied index is now lower than readState.Index' (duration: 274.897µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:18:47.057932Z","caller":"traceutil/trace.go:171","msg":"trace[77688463] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"420.497339ms","start":"2026-10-15T19:18:46.637373Z","end":"2026-10-15T19:18:47.057871Z","steps":["trace[77688463] 'process raft request' (duration: 420.172491ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:18:47.058045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.180953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T19:18:47.058113Z","caller":"traceutil/trace.go:171","msg":"trace[655895094] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8362; }","duration":"393.315786ms","start":"2026-10-15T19:18:46.664776Z","end":"2026-10-15T19:18:47.058092Z","steps":["trace[655895094] 'agreement among raft nodes before linearized reading' (duration: 393.0769ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:18:47.058171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:18:46.664756Z","time spent":"393.401719ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:18:47.058186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:18:46.637355Z","time spent":"420.702124ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:18:58.647425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.834504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-15T19:18:58.647551Z","caller":"traceutil/trace.go:171","msg":"trace[1992445018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8439; }","duration":"148.072821ms","start":"2026-10-15T19:18:58.499456Z","end":"2026-10-15T19:18:58.647529Z","steps":["trace[1992445018] 'range keys from in-memory index tree' (duration: 147.585678ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:58.873969Z","caller":"traceutil/trace.go:171","msg":"trace[182372637] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"220.633842ms","start":"2026-10-15T19:18:58.6533Z","end":"2026-10-15T19:18:58.873934Z","steps":["trace[182372637] 'process raft request' (duration: 220.404756ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:59.494829Z","caller":"traceutil/trace.go:171","msg":"trace[251323] linearizableReadLoop","detail":"{readStateIndex:8815; appliedIndex:8814; }","duration":"231.002355ms","start":"2026-10-15T19:18:59.263805Z","end":"2026-10-15T19:18:59.494807Z","steps":["trace[251323] 'read index received' (duration: 230.851451ms)","trace[251323] 'applied index is now lower than readState.Index' (duration: 149.674µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:18:59.494914Z","caller":"traceutil/trace.go:171","msg":"trace[592477132] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"271.367182ms","start":"2026-10-15T19:18:59.223484Z","end":"2026-10-15T19:18:59.494851Z","steps":["trace[592477132] 'process raft request' (duration: 271.175437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:18:59.495143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.317692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T19:18:59.495248Z","caller":"traceutil/trace.go:171","msg":"trace[1510000209] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8441; }","duration":"231.471146ms","start":"2026-10-15T19:18:59.263753Z","end":"2026-10-15T19:18:59.495224Z","steps":["trace[1510000209] 'agreement among raft nodes before linearized reading' (duration: 231.190679ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:59.740992Z","caller":"traceutil/trace.go:171","msg":"trace[1511288089] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"241.218761ms","start":"2026-10-15T19:18:59.49973Z","end":"2026-10-15T19:18:59.740949Z","steps":["trace[1511288089] 'process raft request' (duration: 191.076918ms)","trace[1511288089] 'compare' (duration: 49.668731ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:18:59.741052Z","caller":"traceutil/trace.go:171","msg":"trace[1020937096] linearizableReadLoop","detail":"{readStateIndex:8818; appliedIndex:8815; }","duration":"215.007854ms","start":"2026-10-15T19:18:59.526019Z","end":"2026-10-15T19:18:59.741026Z","steps":["trace[1020937096] 'read index received' (duration: 164.808628ms)","trace[1020937096] 'applied index is now lower than readState.Index' (duration: 50.197886ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:18:59.741197Z","caller":"traceutil/trace.go:171","msg":"trace[1492071963] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"237.919515ms","start":"2026-10-15T19:18:59.503265Z","end":"2026-10-15T19:18:59.741184Z","steps":["trace[1492071963] 'process raft request' (duration: 237.719989ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:18:59.741214Z","caller":"traceutil/trace.go:171","msg":"trace[695827386] transaction","detail":"{read_only:false; response_revision:8443; number_of_response:1; }","duration":"239.713902ms","start":"2026-10-15T19:18:59.501477Z","end":"2026-10-15T19:18:59.741191Z","steps":["trace[695827386] 'process raft request' (duration: 239.360152ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:18:59.741475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.445006ms","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-15T19:18:59.741541Z","caller":"traceutil/trace.go:171","msg":"trace[185514888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8444; }","duration":"215.569569ms","start":"2026-10-15T19:18:59.525955Z","end":"2026-10-15T19:18:59.741524Z","steps":["trace[185514888] 'agreement among raft nodes before linearized reading' (duration: 215.391294ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:28.193779Z","caller":"traceutil/trace.go:171","msg":"trace[1743044848] linearizableReadLoop","detail":"{readStateIndex:9071; appliedIndex:9070; }","duration":"275.210201ms","start":"2026-10-15T19:19:27.918514Z","end":"2026-10-15T19:19:28.193724Z","steps":["trace[1743044848] 'read index received' (duration: 274.961625ms)","trace[1743044848] 'applied index is now lower than readState.Index' (duration: 246.856µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:19:28.193857Z","caller":"traceutil/trace.go:171","msg":"trace[381378392] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"297.491175ms","start":"2026-10-15T19:19:27.896302Z","end":"2026-10-15T19:19:28.193793Z","steps":["trace[381378392] 'process raft request' (duration: 297.203088ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:28.194118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.318587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:19:28.194167Z","caller":"traceutil/trace.go:171","msg":"trace[722773726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8684; }","duration":"269.44758ms","start":"2026-10-15T19:19:27.924706Z","end":"2026-10-15T19:19:28.194153Z","steps":["trace[722773726] 'agreement among raft nodes before linearized reading' (duration: 269.238885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:28.194258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.96288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-10-15T19:19:28.194167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.525489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-15T19:19:28.194373Z","caller":"traceutil/trace.go:171","msg":"trace[998003375] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8684; }","duration":"121.136784ms","start":"2026-10-15T19:19:28.073205Z","end":"2026-10-15T19:19:28.194342Z","steps":["trace[998003375] 'agreement among raft nodes before linearized reading' (duration: 120.800906ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:28.194379Z","caller":"traceutil/trace.go:171","msg":"trace[132448337] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8684; }","duration":"275.865319ms","start":"2026-10-15T19:19:27.918492Z","end":"2026-10-15T19:19:28.194357Z","steps":["trace[132448337] 'agreement among raft nodes before linearized reading' (duration: 275.419817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:28.194363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.173355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-15T19:19:28.194522Z","caller":"traceutil/trace.go:171","msg":"trace[1469172553] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8684; }","duration":"142.391591ms","start":"2026-10-15T19:19:28.052091Z","end":"2026-10-15T19:19:28.194483Z","steps":["trace[1469172553] 'agreement among raft nodes before linearized reading' (duration: 142.009711ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:28.194554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.716324ms","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-15T19:19:28.19465Z","caller":"traceutil/trace.go:171","msg":"trace[826520274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8684; }","duration":"262.853028ms","start":"2026-10-15T19:19:27.931771Z","end":"2026-10-15T19:19:28.194624Z","steps":["trace[826520274] 'agreement among raft nodes before linearized reading' (duration: 262.518619ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:41.457764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.246437ms","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-15T19:19:41.457841Z","caller":"traceutil/trace.go:171","msg":"trace[1064890074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8814; }","duration":"237.378922ms","start":"2026-10-15T19:19:41.220444Z","end":"2026-10-15T19:19:41.457823Z","steps":["trace[1064890074] 'range keys from in-memory index tree' (duration: 237.082173ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:41.63716Z","caller":"traceutil/trace.go:171","msg":"trace[1542608015] linearizableReadLoop","detail":"{readStateIndex:9209; appliedIndex:9208; }","duration":"199.266822ms","start":"2026-10-15T19:19:41.437852Z","end":"2026-10-15T19:19:41.637119Z","steps":["trace[1542608015] 'read index received' (duration: 117.13116ms)","trace[1542608015] 'applied index is now lower than readState.Index' (duration: 82.131752ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:19:41.637169Z","caller":"traceutil/trace.go:171","msg":"trace[503883801] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"174.193694ms","start":"2026-10-15T19:19:41.462932Z","end":"2026-10-15T19:19:41.637126Z","steps":["trace[503883801] 'process raft request' (duration: 174.00937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:41.658715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.816737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:19:41.658818Z","caller":"traceutil/trace.go:171","msg":"trace[485890171] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:8815; }","duration":"220.94316ms","start":"2026-10-15T19:19:41.437847Z","end":"2026-10-15T19:19:41.65879Z","steps":["trace[485890171] 'agreement among raft nodes before linearized reading' (duration: 199.379815ms)","trace[485890171] 'count revisions from in-memory index tree' (duration: 21.399721ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:19:41.658898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.882317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"warn","ts":"2026-10-15T19:19:41.658809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.922934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:19:41.659031Z","caller":"traceutil/trace.go:171","msg":"trace[1695156635] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8815; }","duration":"216.030761ms","start":"2026-10-15T19:19:41.442958Z","end":"2026-10-15T19:19:41.658989Z","steps":["trace[1695156635] 'agreement among raft nodes before linearized reading' (duration: 194.389474ms)","trace[1695156635] 'range keys from in-memory index tree' (duration: 21.303298ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:19:41.659142Z","caller":"traceutil/trace.go:171","msg":"trace[1311712466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8815; }","duration":"162.241382ms","start":"2026-10-15T19:19:41.496806Z","end":"2026-10-15T19:19:41.659048Z","steps":["trace[1311712466] 'agreement among raft nodes before linearized reading' (duration: 140.566714ms)","trace[1311712466] 'range keys from in-memory index tree' (duration: 21.377921ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:19:42.551795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.307515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:19:42.551881Z","caller":"traceutil/trace.go:171","msg":"trace[98503368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8820; }","duration":"209.48552ms","start":"2026-10-15T19:19:42.342374Z","end":"2026-10-15T19:19:42.55186Z","steps":["trace[98503368] 'range keys from in-memory index tree' (duration: 209.086519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:42.551858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.811092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-10-15T19:19:42.551856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.422141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T19:19:42.551925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.928815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T19:19:42.551946Z","caller":"traceutil/trace.go:171","msg":"trace[1681065769] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8820; }","duration":"208.911216ms","start":"2026-10-15T19:19:42.343012Z","end":"2026-10-15T19:19:42.551923Z","steps":["trace[1681065769] 'range keys from in-memory index tree' (duration: 208.71471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:19:42.551902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.46684ms","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-15T19:19:42.551979Z","caller":"traceutil/trace.go:171","msg":"trace[487688287] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8820; }","duration":"101.024198ms","start":"2026-10-15T19:19:42.450936Z","end":"2026-10-15T19:19:42.55196Z","steps":["trace[487688287] 'range keys from in-memory index tree' (duration: 100.778941ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:42.552023Z","caller":"traceutil/trace.go:171","msg":"trace[1092279337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8820; }","duration":"243.621484ms","start":"2026-10-15T19:19:42.308372Z","end":"2026-10-15T19:19:42.551994Z","steps":["trace[1092279337] 'range keys from in-memory index tree' (duration: 243.222373ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:42.551946Z","caller":"traceutil/trace.go:171","msg":"trace[175659739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8820; }","duration":"290.588095ms","start":"2026-10-15T19:19:42.261342Z","end":"2026-10-15T19:19:42.55193Z","steps":["trace[175659739] 'range keys from in-memory index tree' (duration: 290.175964ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:20:04.875607Z","caller":"traceutil/trace.go:171","msg":"trace[1759386747] transaction","detail":"{read_only:false; response_revision:9085; number_of_response:1; }","duration":"112.903068ms","start":"2026-10-15T19:20:04.762651Z","end":"2026-10-15T19:20:04.875554Z","steps":["trace[1759386747] 'process raft request' (duration: 54.072867ms)","trace[1759386747] 'compare' (duration: 58.311828ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:20:32.372877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6542} {"level":"info","ts":"2026-10-15T19:20:32.496547Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6542,"took":"121.296176ms","hash":4225288202,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":25960448,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T19:20:32.496641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4225288202,"revision":6542,"compact-revision":3190} {"level":"info","ts":"2026-10-15T19:20:47.556358Z","caller":"traceutil/trace.go:171","msg":"trace[1606741181] linearizableReadLoop","detail":"{readStateIndex:9957; appliedIndex:9956; }","duration":"111.665493ms","start":"2026-10-15T19:20:47.444654Z","end":"2026-10-15T19:20:47.55632Z","steps":["trace[1606741181] 'read index received' (duration: 95.848407ms)","trace[1606741181] 'applied index is now lower than readState.Index' (duration: 15.792776ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:20:47.556421Z","caller":"traceutil/trace.go:171","msg":"trace[793841424] transaction","detail":"{read_only:false; response_revision:9529; number_of_response:1; }","duration":"111.884299ms","start":"2026-10-15T19:20:47.444475Z","end":"2026-10-15T19:20:47.556359Z","steps":["trace[793841424] 'process raft request' (duration: 96.055273ms)","trace[793841424] 'compare' (duration: 15.59131ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:20:47.556702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.026273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-10-15T19:20:47.556756Z","caller":"traceutil/trace.go:171","msg":"trace[341100385] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9529; }","duration":"112.133906ms","start":"2026-10-15T19:20:47.444609Z","end":"2026-10-15T19:20:47.556743Z","steps":["trace[341100385] 'agreement among raft nodes before linearized reading' (duration: 111.910571ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:20:53.01281Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:20:53.019532Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T19:20:53.019691Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T19:20:58.528464Z","caller":"traceutil/trace.go:171","msg":"trace[1213171805] transaction","detail":"{read_only:false; response_revision:9606; number_of_response:1; }","duration":"125.539578ms","start":"2026-10-15T19:20:58.402836Z","end":"2026-10-15T19:20:58.528376Z","steps":["trace[1213171805] 'process raft request' (duration: 124.89122ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:24:44.69769Z","caller":"traceutil/trace.go:171","msg":"trace[1753261585] linearizableReadLoop","detail":"{readStateIndex:12357; appliedIndex:12356; }","duration":"143.534392ms","start":"2026-10-15T19:24:44.554127Z","end":"2026-10-15T19:24:44.697662Z","steps":["trace[1753261585] 'read index received' (duration: 143.306516ms)","trace[1753261585] 'applied index is now lower than readState.Index' (duration: 226.436µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:24:44.697768Z","caller":"traceutil/trace.go:171","msg":"trace[1769360395] transaction","detail":"{read_only:false; response_revision:11828; number_of_response:1; }","duration":"183.787318ms","start":"2026-10-15T19:24:44.513924Z","end":"2026-10-15T19:24:44.697712Z","steps":["trace[1769360395] 'process raft request' (duration: 183.54833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:24:44.698006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.792139ms","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-15T19:24:44.698031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.464214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-15T19:24:44.698124Z","caller":"traceutil/trace.go:171","msg":"trace[1282633017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11828; }","duration":"119.935944ms","start":"2026-10-15T19:24:44.578164Z","end":"2026-10-15T19:24:44.6981Z","steps":["trace[1282633017] 'agreement among raft nodes before linearized reading' (duration: 119.708268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:24:44.698105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.947363ms","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-15T19:24:44.698165Z","caller":"traceutil/trace.go:171","msg":"trace[1187976662] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:11828; }","duration":"121.659329ms","start":"2026-10-15T19:24:44.576486Z","end":"2026-10-15T19:24:44.698146Z","steps":["trace[1187976662] 'agreement among raft nodes before linearized reading' (duration: 121.398672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:24:44.69803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.280371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:24:44.698222Z","caller":"traceutil/trace.go:171","msg":"trace[180477567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11828; }","duration":"144.167669ms","start":"2026-10-15T19:24:44.554028Z","end":"2026-10-15T19:24:44.698195Z","steps":["trace[180477567] 'agreement among raft nodes before linearized reading' (duration: 143.782119ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:24:44.698241Z","caller":"traceutil/trace.go:171","msg":"trace[1783345442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11828; }","duration":"123.558758ms","start":"2026-10-15T19:24:44.574664Z","end":"2026-10-15T19:24:44.698223Z","steps":["trace[1783345442] 'agreement among raft nodes before linearized reading' (duration: 123.201619ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:25:32.381486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9314} {"level":"info","ts":"2026-10-15T19:25:32.473056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9314,"took":"88.48473ms","hash":983368930,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":28016640,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T19:25:32.473136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":983368930,"revision":9314,"compact-revision":6542} {"level":"info","ts":"2026-10-15T19:30:32.391559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12233} {"level":"info","ts":"2026-10-15T19:30:32.522405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12233,"took":"127.223515ms","hash":1273015757,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":25739264,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T19:30:32.5225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1273015757,"revision":12233,"compact-revision":9314} {"level":"warn","ts":"2026-10-15T19:30:39.647782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.588345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:30:39.648091Z","caller":"traceutil/trace.go:171","msg":"trace[212410459] transaction","detail":"{read_only:false; response_revision:14732; number_of_response:1; }","duration":"118.264659ms","start":"2026-10-15T19:30:39.529799Z","end":"2026-10-15T19:30:39.648064Z","steps":["trace[212410459] 'process raft request' (duration: 118.191787ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:39.64813Z","caller":"traceutil/trace.go:171","msg":"trace[1328125708] transaction","detail":"{read_only:false; response_revision:14731; number_of_response:1; }","duration":"121.761032ms","start":"2026-10-15T19:30:39.526325Z","end":"2026-10-15T19:30:39.648086Z","steps":["trace[1328125708] 'compare' (duration: 119.429681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:39.762713Z","caller":"traceutil/trace.go:171","msg":"trace[871783141] transaction","detail":"{read_only:false; response_revision:14733; number_of_response:1; }","duration":"106.074491ms","start":"2026-10-15T19:30:39.656614Z","end":"2026-10-15T19:30:39.762689Z","steps":["trace[871783141] 'process raft request' (duration: 105.528087ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:39.763222Z","caller":"traceutil/trace.go:171","msg":"trace[1965389202] transaction","detail":"{read_only:false; response_revision:14734; number_of_response:1; }","duration":"106.116532ms","start":"2026-10-15T19:30:39.657074Z","end":"2026-10-15T19:30:39.76319Z","steps":["trace[1965389202] 'process raft request' (duration: 105.948848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:09.195096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.850566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:31:09.195201Z","caller":"traceutil/trace.go:171","msg":"trace[1318589094] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:14947; }","duration":"103.024491ms","start":"2026-10-15T19:31:09.092151Z","end":"2026-10-15T19:31:09.195175Z","steps":["trace[1318589094] 'count revisions from in-memory index tree' (duration: 102.641251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:18.830049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16635629898929528865,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-15T19:31:19.22701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.871565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:31:19.227591Z","caller":"traceutil/trace.go:171","msg":"trace[1601012449] linearizableReadLoop","detail":"{readStateIndex:15708; appliedIndex:15706; }","duration":"898.312797ms","start":"2026-10-15T19:31:18.329259Z","end":"2026-10-15T19:31:19.227572Z","steps":["trace[1601012449] 'read index received' (duration: 606.754984ms)","trace[1601012449] 'applied index is now lower than readState.Index' (duration: 291.556753ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:31:19.22805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.761979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T19:31:19.228145Z","caller":"traceutil/trace.go:171","msg":"trace[260187093] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:15008; }","duration":"898.928843ms","start":"2026-10-15T19:31:18.329197Z","end":"2026-10-15T19:31:19.228125Z","steps":["trace[260187093] 'agreement among raft nodes before linearized reading' (duration: 898.552014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.228395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.940837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wwfg7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-15T19:31:19.228558Z","caller":"traceutil/trace.go:171","msg":"trace[1719269256] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wwfg7; range_end:; response_count:1; response_revision:15008; }","duration":"481.153012ms","start":"2026-10-15T19:31:18.747388Z","end":"2026-10-15T19:31:19.228541Z","steps":["trace[1719269256] 'agreement among raft nodes before linearized reading' (duration: 480.728251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.228811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:18.747361Z","time spent":"481.405709ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-wwfg7\" "} {"level":"warn","ts":"2026-10-15T19:31:19.229005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.001807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:31:19.229066Z","caller":"traceutil/trace.go:171","msg":"trace[656419200] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:15008; }","duration":"302.072758ms","start":"2026-10-15T19:31:18.92697Z","end":"2026-10-15T19:31:19.229042Z","steps":["trace[656419200] 'agreement among raft nodes before linearized reading' (duration: 301.994936ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.229117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:18.926959Z","time spent":"302.148871ms","remote":"127.0.0.1:59744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:31:19.229171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.061758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-15T19:31:19.229241Z","caller":"traceutil/trace.go:171","msg":"trace[848295933] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:15008; }","duration":"175.11701ms","start":"2026-10-15T19:31:19.054101Z","end":"2026-10-15T19:31:19.229218Z","steps":["trace[848295933] 'agreement among raft nodes before linearized reading' (duration: 174.999287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.229395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.45256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-10-15T19:31:19.229433Z","caller":"traceutil/trace.go:171","msg":"trace[422674978] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15008; }","duration":"407.510992ms","start":"2026-10-15T19:31:18.821913Z","end":"2026-10-15T19:31:19.229424Z","steps":["trace[422674978] 'agreement among raft nodes before linearized reading' (duration: 407.403289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.229459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:18.821903Z","time spent":"407.549763ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11520,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T19:31:19.228896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.956687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-15T19:31:19.229555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.384244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T19:31:19.229595Z","caller":"traceutil/trace.go:171","msg":"trace[609363322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15008; }","duration":"176.447755ms","start":"2026-10-15T19:31:19.053137Z","end":"2026-10-15T19:31:19.229585Z","steps":["trace[609363322] 'agreement among raft nodes before linearized reading' (duration: 176.350763ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:19.229601Z","caller":"traceutil/trace.go:171","msg":"trace[456999366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15008; }","duration":"167.672425ms","start":"2026-10-15T19:31:19.06188Z","end":"2026-10-15T19:31:19.229552Z","steps":["trace[456999366] 'agreement among raft nodes before linearized reading' (duration: 166.847324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.229686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.512203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-10-15T19:31:19.229726Z","caller":"traceutil/trace.go:171","msg":"trace[1564720120] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:15008; }","duration":"441.569994ms","start":"2026-10-15T19:31:18.788143Z","end":"2026-10-15T19:31:19.229713Z","steps":["trace[1564720120] 'agreement among raft nodes before linearized reading' (duration: 441.478212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.229752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:18.788132Z","time spent":"441.613715ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-15T19:31:19.229781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.24066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-15T19:31:19.229025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.594028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T19:31:19.229821Z","caller":"traceutil/trace.go:171","msg":"trace[3942342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15008; }","duration":"221.334132ms","start":"2026-10-15T19:31:19.008476Z","end":"2026-10-15T19:31:19.22981Z","steps":["trace[3942342] 'agreement among raft nodes before linearized reading' (duration: 221.235609ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:19.229847Z","caller":"traceutil/trace.go:171","msg":"trace[24185721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15008; }","duration":"230.497822ms","start":"2026-10-15T19:31:18.999339Z","end":"2026-10-15T19:31:19.229837Z","steps":["trace[24185721] 'agreement among raft nodes before linearized reading' (duration: 229.410834ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:19.229954Z","caller":"traceutil/trace.go:171","msg":"trace[1337746441] transaction","detail":"{read_only:false; response_revision:15007; number_of_response:1; }","duration":"912.704504ms","start":"2026-10-15T19:31:18.317225Z","end":"2026-10-15T19:31:19.22993Z","steps":["trace[1337746441] 'process raft request' (duration: 618.769519ms)","trace[1337746441] 'compare' (duration: 290.31023ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:31:19.23008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:18.317203Z","time spent":"912.829827ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:31:19.230412Z","caller":"traceutil/trace.go:171","msg":"trace[859589099] transaction","detail":"{read_only:false; response_revision:15008; number_of_response:1; }","duration":"796.868038ms","start":"2026-10-15T19:31:18.433509Z","end":"2026-10-15T19:31:19.230377Z","steps":["trace[859589099] 'process raft request' (duration: 793.941201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:19.230514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:18.433483Z","time spent":"796.983422ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:31:19.228187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:18.329174Z","time spent":"899.003766ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-10-15T19:32:36.059685Z","caller":"traceutil/trace.go:171","msg":"trace[306170799] transaction","detail":"{read_only:false; response_revision:16189; number_of_response:1; }","duration":"118.913007ms","start":"2026-10-15T19:32:35.94075Z","end":"2026-10-15T19:32:36.059663Z","steps":["trace[306170799] 'process raft request' (duration: 116.411051ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:32:36.059843Z","caller":"traceutil/trace.go:171","msg":"trace[1122903635] transaction","detail":"{read_only:false; response_revision:16190; number_of_response:1; }","duration":"115.61836ms","start":"2026-10-15T19:32:35.944187Z","end":"2026-10-15T19:32:36.059806Z","steps":["trace[1122903635] 'process raft request' (duration: 115.417755ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:35:32.399471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14678} {"level":"info","ts":"2026-10-15T19:35:32.510782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14678,"took":"108.765998ms","hash":1542882466,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":30027776,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T19:35:32.510899Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1542882466,"revision":14678,"compact-revision":12233} {"level":"info","ts":"2026-10-15T19:38:13.710545Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:38:13.718073Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T19:38:13.718283Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T19:38:44.441766Z","caller":"traceutil/trace.go:171","msg":"trace[1532814764] linearizableReadLoop","detail":"{readStateIndex:20315; appliedIndex:20313; }","duration":"111.771387ms","start":"2026-10-15T19:38:44.329968Z","end":"2026-10-15T19:38:44.441739Z","steps":["trace[1532814764] 'read index received' (duration: 2.234669ms)","trace[1532814764] 'applied index is now lower than readState.Index' (duration: 109.535338ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:38:44.441929Z","caller":"traceutil/trace.go:171","msg":"trace[1664277609] transaction","detail":"{read_only:false; response_revision:19404; number_of_response:1; }","duration":"138.404493ms","start":"2026-10-15T19:38:44.303509Z","end":"2026-10-15T19:38:44.441913Z","steps":["trace[1664277609] 'process raft request' (duration: 102.716819ms)","trace[1664277609] 'compare' (duration: 35.402387ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:38:44.442297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.30447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-senlin-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-10-15T19:38:44.442363Z","caller":"traceutil/trace.go:171","msg":"trace[1414895909] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-senlin-default-user; range_end:; response_count:1; response_revision:19404; }","duration":"112.406803ms","start":"2026-10-15T19:38:44.329938Z","end":"2026-10-15T19:38:44.442345Z","steps":["trace[1414895909] 'agreement among raft nodes before linearized reading' (duration: 112.203318ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:38:44.814102Z","caller":"traceutil/trace.go:171","msg":"trace[409531315] transaction","detail":"{read_only:false; response_revision:19406; number_of_response:1; }","duration":"142.370638ms","start":"2026-10-15T19:38:44.671689Z","end":"2026-10-15T19:38:44.81406Z","steps":["trace[409531315] 'process raft request' (duration: 141.482505ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:38:45.410686Z","caller":"traceutil/trace.go:171","msg":"trace[278908569] linearizableReadLoop","detail":"{readStateIndex:20318; appliedIndex:20317; }","duration":"161.13827ms","start":"2026-10-15T19:38:45.249524Z","end":"2026-10-15T19:38:45.410662Z","steps":["trace[278908569] 'read index received' (duration: 160.897714ms)","trace[278908569] 'applied index is now lower than readState.Index' (duration: 239.126µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:38:45.410746Z","caller":"traceutil/trace.go:171","msg":"trace[1180015059] transaction","detail":"{read_only:false; response_revision:19407; number_of_response:1; }","duration":"507.839317ms","start":"2026-10-15T19:38:44.902874Z","end":"2026-10-15T19:38:45.410714Z","steps":["trace[1180015059] 'process raft request' (duration: 507.593541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:38:45.410942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:38:44.902852Z","time spent":"507.969381ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20850,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:38:45.411032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.460988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-10-15T19:38:45.411142Z","caller":"traceutil/trace.go:171","msg":"trace[630748909] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19407; }","duration":"161.645423ms","start":"2026-10-15T19:38:45.24947Z","end":"2026-10-15T19:38:45.411115Z","steps":["trace[630748909] 'agreement among raft nodes before linearized reading' (duration: 161.293424ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:38:45.526901Z","caller":"traceutil/trace.go:171","msg":"trace[840306902] transaction","detail":"{read_only:false; response_revision:19408; number_of_response:1; }","duration":"103.754237ms","start":"2026-10-15T19:38:45.423103Z","end":"2026-10-15T19:38:45.526857Z","steps":["trace[840306902] 'process raft request' (duration: 47.440822ms)","trace[840306902] 'compare' (duration: 55.985946ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:40:32.406827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17664} {"level":"info","ts":"2026-10-15T19:40:32.538192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17664,"took":"126.675206ms","hash":446152329,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:40:32.538295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":446152329,"revision":17664,"compact-revision":14678} {"level":"info","ts":"2026-10-15T19:41:18.851359Z","caller":"traceutil/trace.go:171","msg":"trace[1591591018] transaction","detail":"{read_only:false; response_revision:21211; number_of_response:1; }","duration":"102.014061ms","start":"2026-10-15T19:41:18.749319Z","end":"2026-10-15T19:41:18.851333Z","steps":["trace[1591591018] 'process raft request' (duration: 101.851137ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:41:18.995016Z","caller":"traceutil/trace.go:171","msg":"trace[814485368] transaction","detail":"{read_only:false; response_revision:21212; number_of_response:1; }","duration":"106.796276ms","start":"2026-10-15T19:41:18.888199Z","end":"2026-10-15T19:41:18.994995Z","steps":["trace[814485368] 'process raft request' (duration: 98.869779ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:41:18.995548Z","caller":"traceutil/trace.go:171","msg":"trace[658251056] transaction","detail":"{read_only:false; response_revision:21213; number_of_response:1; }","duration":"106.777076ms","start":"2026-10-15T19:41:18.888757Z","end":"2026-10-15T19:41:18.995534Z","steps":["trace[658251056] 'process raft request' (duration: 106.18212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:41:21.968861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.307865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-10-15T19:41:21.969093Z","caller":"traceutil/trace.go:171","msg":"trace[1994480804] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21234; }","duration":"145.580902ms","start":"2026-10-15T19:41:21.823483Z","end":"2026-10-15T19:41:21.969064Z","steps":["trace[1994480804] 'range keys from in-memory index tree' (duration: 145.144431ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:45:32.414668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20675} {"level":"info","ts":"2026-10-15T19:45:32.547413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20675,"took":"128.89815ms","hash":1080616651,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":32813056,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T19:45:32.547531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1080616651,"revision":20675,"compact-revision":17664} {"level":"info","ts":"2026-10-15T19:45:43.428238Z","caller":"traceutil/trace.go:171","msg":"trace[2065211105] transaction","detail":"{read_only:false; response_revision:23470; number_of_response:1; }","duration":"150.145135ms","start":"2026-10-15T19:45:43.278062Z","end":"2026-10-15T19:45:43.428208Z","steps":["trace[2065211105] 'process raft request' (duration: 149.95423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:43.637681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.186447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:45:43.637884Z","caller":"traceutil/trace.go:171","msg":"trace[2135511036] transaction","detail":"{read_only:false; response_revision:23471; number_of_response:1; }","duration":"201.851736ms","start":"2026-10-15T19:45:43.435988Z","end":"2026-10-15T19:45:43.637839Z","steps":["trace[2135511036] 'process raft request' (duration: 90.422003ms)","trace[2135511036] 'compare' (duration: 111.061704ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:45:43.63796Z","caller":"traceutil/trace.go:171","msg":"trace[1472970905] linearizableReadLoop","detail":"{readStateIndex:24573; appliedIndex:24572; }","duration":"140.144003ms","start":"2026-10-15T19:45:43.497763Z","end":"2026-10-15T19:45:43.637907Z","steps":["trace[1472970905] 'read index received' (duration: 28.669459ms)","trace[1472970905] 'applied index is now lower than readState.Index' (duration: 111.468514ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:45:43.638199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.990222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:45:43.638244Z","caller":"traceutil/trace.go:171","msg":"trace[174418933] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:23472; }","duration":"108.063654ms","start":"2026-10-15T19:45:43.530162Z","end":"2026-10-15T19:45:43.638226Z","steps":["trace[174418933] 'agreement among raft nodes before linearized reading' (duration: 107.989742ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:43.63824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.41923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:45:43.638359Z","caller":"traceutil/trace.go:171","msg":"trace[1137986380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23472; }","duration":"140.654897ms","start":"2026-10-15T19:45:43.497664Z","end":"2026-10-15T19:45:43.638319Z","steps":["trace[1137986380] 'agreement among raft nodes before linearized reading' (duration: 140.41379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:45:43.638355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.043818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11828"} {"level":"info","ts":"2026-10-15T19:45:43.638462Z","caller":"traceutil/trace.go:171","msg":"trace[663881337] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:23472; }","duration":"125.225323ms","start":"2026-10-15T19:45:43.513213Z","end":"2026-10-15T19:45:43.638438Z","steps":["trace[663881337] 'agreement among raft nodes before linearized reading' (duration: 124.942565ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:45:53.940226Z","caller":"traceutil/trace.go:171","msg":"trace[687867934] transaction","detail":"{read_only:false; response_revision:23537; number_of_response:1; }","duration":"173.132166ms","start":"2026-10-15T19:45:53.76706Z","end":"2026-10-15T19:45:53.940193Z","steps":["trace[687867934] 'process raft request' (duration: 103.061384ms)","trace[687867934] 'compare' (duration: 69.784564ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:45:59.991751Z","caller":"traceutil/trace.go:171","msg":"trace[1410239536] transaction","detail":"{read_only:false; response_revision:23574; number_of_response:1; }","duration":"286.18257ms","start":"2026-10-15T19:45:59.705543Z","end":"2026-10-15T19:45:59.991726Z","steps":["trace[1410239536] 'process raft request' (duration: 286.024656ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:46:00.186491Z","caller":"traceutil/trace.go:171","msg":"trace[1616053483] transaction","detail":"{read_only:false; response_revision:23575; number_of_response:1; }","duration":"165.183768ms","start":"2026-10-15T19:46:00.02127Z","end":"2026-10-15T19:46:00.186454Z","steps":["trace[1616053483] 'process raft request' (duration: 139.889296ms)","trace[1616053483] 'compare' (duration: 24.572323ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:46:00.479175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.995517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:46:00.479383Z","caller":"traceutil/trace.go:171","msg":"trace[350179887] transaction","detail":"{read_only:false; response_revision:23576; number_of_response:1; }","duration":"284.680392ms","start":"2026-10-15T19:46:00.194671Z","end":"2026-10-15T19:46:00.479351Z","steps":["trace[350179887] 'process raft request' (duration: 159.385417ms)","trace[350179887] 'compare' (duration: 124.663319ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:46:00.479496Z","caller":"traceutil/trace.go:171","msg":"trace[1601897855] transaction","detail":"{read_only:false; response_revision:23577; number_of_response:1; }","duration":"192.885852ms","start":"2026-10-15T19:46:00.286591Z","end":"2026-10-15T19:46:00.479477Z","steps":["trace[1601897855] 'process raft request' (duration: 192.724498ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:46:00.479597Z","caller":"traceutil/trace.go:171","msg":"trace[778711425] linearizableReadLoop","detail":"{readStateIndex:24684; appliedIndex:24683; }","duration":"268.812827ms","start":"2026-10-15T19:46:00.210769Z","end":"2026-10-15T19:46:00.479582Z","steps":["trace[778711425] 'read index received' (duration: 143.294516ms)","trace[778711425] 'applied index is now lower than readState.Index' (duration: 125.515961ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:46:00.47985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.068143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:46:00.479939Z","caller":"traceutil/trace.go:171","msg":"trace[479653357] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:23577; }","duration":"269.196247ms","start":"2026-10-15T19:46:00.210725Z","end":"2026-10-15T19:46:00.479921Z","steps":["trace[479653357] 'agreement among raft nodes before linearized reading' (duration: 269.038183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:46:00.48005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.249515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-10-15T19:46:00.480111Z","caller":"traceutil/trace.go:171","msg":"trace[852665006] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23577; }","duration":"218.331657ms","start":"2026-10-15T19:46:00.261759Z","end":"2026-10-15T19:46:00.48009Z","steps":["trace[852665006] 'agreement among raft nodes before linearized reading' (duration: 218.094421ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:46:00.754875Z","caller":"traceutil/trace.go:171","msg":"trace[242613231] linearizableReadLoop","detail":"{readStateIndex:24687; appliedIndex:24686; }","duration":"211.918569ms","start":"2026-10-15T19:46:00.542928Z","end":"2026-10-15T19:46:00.754846Z","steps":["trace[242613231] 'read index received' (duration: 211.723464ms)","trace[242613231] 'applied index is now lower than readState.Index' (duration: 193.945µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:46:00.75495Z","caller":"traceutil/trace.go:171","msg":"trace[478291323] transaction","detail":"{read_only:false; response_revision:23579; number_of_response:1; }","duration":"246.335008ms","start":"2026-10-15T19:46:00.508572Z","end":"2026-10-15T19:46:00.754907Z","steps":["trace[478291323] 'process raft request' (duration: 246.107833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:46:00.755178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.041687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T19:46:00.755253Z","caller":"traceutil/trace.go:171","msg":"trace[1468462615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23579; }","duration":"203.15615ms","start":"2026-10-15T19:46:00.552081Z","end":"2026-10-15T19:46:00.755237Z","steps":["trace[1468462615] 'agreement among raft nodes before linearized reading' (duration: 202.957915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:46:00.75523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.284989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T19:46:00.755277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.443831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:46:00.755324Z","caller":"traceutil/trace.go:171","msg":"trace[1831258541] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:23579; }","duration":"109.567174ms","start":"2026-10-15T19:46:00.645744Z","end":"2026-10-15T19:46:00.755311Z","steps":["trace[1831258541] 'agreement among raft nodes before linearized reading' (duration: 109.485772ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:46:00.755329Z","caller":"traceutil/trace.go:171","msg":"trace[177377183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23579; }","duration":"212.460403ms","start":"2026-10-15T19:46:00.542844Z","end":"2026-10-15T19:46:00.755305Z","steps":["trace[177377183] 'agreement among raft nodes before linearized reading' (duration: 212.144055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:06.987735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.449225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T19:49:06.987841Z","caller":"traceutil/trace.go:171","msg":"trace[827138142] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25367; }","duration":"113.623519ms","start":"2026-10-15T19:49:06.874194Z","end":"2026-10-15T19:49:06.987818Z","steps":["trace[827138142] 'range keys from in-memory index tree' (duration: 113.24945ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:08.5485Z","caller":"traceutil/trace.go:171","msg":"trace[2055861865] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"126.347213ms","start":"2026-10-15T19:49:08.422112Z","end":"2026-10-15T19:49:08.548459Z","steps":["trace[2055861865] 'process raft request' (duration: 125.696736ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:30.464392Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a79.wal"} {"level":"info","ts":"2026-10-15T19:49:37.170899Z","caller":"traceutil/trace.go:171","msg":"trace[157147516] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"188.736444ms","start":"2026-10-15T19:49:36.982128Z","end":"2026-10-15T19:49:37.170865Z","steps":["trace[157147516] 'process raft request' (duration: 188.435935ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:50:32.430525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23399} {"level":"info","ts":"2026-10-15T19:50:32.558367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23399,"took":"122.275192ms","hash":2931996915,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":44851200,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-15T19:50:32.558477Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2931996915,"revision":23399,"compact-revision":20675} {"level":"warn","ts":"2026-10-15T19:53:02.732304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.217376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:53:02.7324Z","caller":"traceutil/trace.go:171","msg":"trace[749516220] transaction","detail":"{read_only:false; response_revision:28551; number_of_response:1; }","duration":"101.111817ms","start":"2026-10-15T19:53:02.631262Z","end":"2026-10-15T19:53:02.732374Z","steps":["trace[749516220] 'process raft request' (duration: 95.46065ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:53:02.732442Z","caller":"traceutil/trace.go:171","msg":"trace[832462304] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:28550; }","duration":"115.406831ms","start":"2026-10-15T19:53:02.617008Z","end":"2026-10-15T19:53:02.732415Z","steps":["trace[832462304] 'count revisions from in-memory index tree' (duration: 115.103913ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:53:08.438206Z","caller":"traceutil/trace.go:171","msg":"trace[1693988480] transaction","detail":"{read_only:false; response_revision:28598; number_of_response:1; }","duration":"415.846918ms","start":"2026-10-15T19:53:08.022331Z","end":"2026-10-15T19:53:08.438178Z","steps":["trace[1693988480] 'process raft request' (duration: 415.696884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:53:08.438362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:53:08.022304Z","time spent":"415.97611ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:53:08.645218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.313505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:53:08.645402Z","caller":"traceutil/trace.go:171","msg":"trace[1851795706] transaction","detail":"{read_only:false; response_revision:28599; number_of_response:1; }","duration":"178.688449ms","start":"2026-10-15T19:53:08.466682Z","end":"2026-10-15T19:53:08.645371Z","steps":["trace[1851795706] 'process raft request' (duration: 15.135417ms)","trace[1851795706] 'compare' (duration: 163.143731ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:53:08.645525Z","caller":"traceutil/trace.go:171","msg":"trace[195521486] transaction","detail":"{read_only:false; response_revision:28600; number_of_response:1; }","duration":"170.292839ms","start":"2026-10-15T19:53:08.475218Z","end":"2026-10-15T19:53:08.645511Z","steps":["trace[195521486] 'process raft request' (duration: 170.165056ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:53:08.645493Z","caller":"traceutil/trace.go:171","msg":"trace[1898484123] linearizableReadLoop","detail":"{readStateIndex:29912; appliedIndex:29911; }","duration":"176.079061ms","start":"2026-10-15T19:53:08.469374Z","end":"2026-10-15T19:53:08.645453Z","steps":["trace[1898484123] 'read index received' (duration: 12.423896ms)","trace[1898484123] 'applied index is now lower than readState.Index' (duration: 163.649435ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:53:08.645708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.408859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-10-15T19:53:08.645821Z","caller":"traceutil/trace.go:171","msg":"trace[1488832436] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28600; }","duration":"176.578024ms","start":"2026-10-15T19:53:08.469222Z","end":"2026-10-15T19:53:08.6458Z","steps":["trace[1488832436] 'agreement among raft nodes before linearized reading' (duration: 176.329907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:53:08.646501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.076812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-10-15T19:53:08.646572Z","caller":"traceutil/trace.go:171","msg":"trace[1572483820] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28600; }","duration":"165.189255ms","start":"2026-10-15T19:53:08.481362Z","end":"2026-10-15T19:53:08.646551Z","steps":["trace[1572483820] 'agreement among raft nodes before linearized reading' (duration: 164.360484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:53:09.024638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.288633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:53:09.024739Z","caller":"traceutil/trace.go:171","msg":"trace[1409354408] linearizableReadLoop","detail":"{readStateIndex:29915; appliedIndex:29914; }","duration":"350.380604ms","start":"2026-10-15T19:53:08.674343Z","end":"2026-10-15T19:53:09.024723Z","steps":["trace[1409354408] 'read index received' (duration: 162.919155ms)","trace[1409354408] 'applied index is now lower than readState.Index' (duration: 187.460039ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:53:09.024863Z","caller":"traceutil/trace.go:171","msg":"trace[2016807153] transaction","detail":"{read_only:false; response_revision:28602; number_of_response:1; }","duration":"369.560346ms","start":"2026-10-15T19:53:08.655291Z","end":"2026-10-15T19:53:09.024851Z","steps":["trace[2016807153] 'process raft request' (duration: 181.963664ms)","trace[2016807153] 'compare' (duration: 186.827202ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:53:09.024963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:53:08.655276Z","time spent":"369.620847ms","remote":"127.0.0.1:41564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:53:09.024992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.63019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T19:53:09.025074Z","caller":"traceutil/trace.go:171","msg":"trace[1637582980] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28602; }","duration":"350.756013ms","start":"2026-10-15T19:53:08.674295Z","end":"2026-10-15T19:53:09.025051Z","steps":["trace[1637582980] 'agreement among raft nodes before linearized reading' (duration: 350.543968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:53:09.025118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:53:08.674271Z","time spent":"350.837746ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:53:09.026546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.411489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-10-15T19:53:09.026618Z","caller":"traceutil/trace.go:171","msg":"trace[2078836190] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:28603; }","duration":"273.529352ms","start":"2026-10-15T19:53:08.753072Z","end":"2026-10-15T19:53:09.026602Z","steps":["trace[2078836190] 'agreement among raft nodes before linearized reading' (duration: 273.321957ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:53:09.026723Z","caller":"traceutil/trace.go:171","msg":"trace[964923887] transaction","detail":"{read_only:false; response_revision:28603; number_of_response:1; }","duration":"271.460988ms","start":"2026-10-15T19:53:08.75524Z","end":"2026-10-15T19:53:09.026701Z","steps":["trace[964923887] 'process raft request' (duration: 271.035357ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:53:09.026868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.150793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:53:09.026927Z","caller":"traceutil/trace.go:171","msg":"trace[1721465116] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:28603; }","duration":"110.220545ms","start":"2026-10-15T19:53:08.916692Z","end":"2026-10-15T19:53:09.026913Z","steps":["trace[1721465116] 'agreement among raft nodes before linearized reading' (duration: 110.01968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:53:09.026893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.668708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:53:09.027043Z","caller":"traceutil/trace.go:171","msg":"trace[1803355212] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:28603; }","duration":"187.028707ms","start":"2026-10-15T19:53:08.839992Z","end":"2026-10-15T19:53:09.027021Z","steps":["trace[1803355212] 'agreement among raft nodes before linearized reading' (duration: 186.829572ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:53:17.670613Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:53:17.678581Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T19:53:17.678781Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T19:54:12.765374Z","caller":"traceutil/trace.go:171","msg":"trace[30050986] linearizableReadLoop","detail":"{readStateIndex:30604; appliedIndex:30603; }","duration":"100.82043ms","start":"2026-10-15T19:54:12.664526Z","end":"2026-10-15T19:54:12.765346Z","steps":["trace[30050986] 'read index received' (duration: 100.624085ms)","trace[30050986] 'applied index is now lower than readState.Index' (duration: 194.875µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:54:12.765477Z","caller":"traceutil/trace.go:171","msg":"trace[1047430477] transaction","detail":"{read_only:false; response_revision:29264; number_of_response:1; }","duration":"195.395266ms","start":"2026-10-15T19:54:12.570039Z","end":"2026-10-15T19:54:12.765434Z","steps":["trace[1047430477] 'process raft request' (duration: 195.139009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:12.765716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.156628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-10-15T19:54:12.765824Z","caller":"traceutil/trace.go:171","msg":"trace[1564099368] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29264; }","duration":"101.333463ms","start":"2026-10-15T19:54:12.66447Z","end":"2026-10-15T19:54:12.765803Z","steps":["trace[1564099368] 'agreement among raft nodes before linearized reading' (duration: 101.008174ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:12.808084Z","caller":"traceutil/trace.go:171","msg":"trace[1960697197] transaction","detail":"{read_only:false; response_revision:29265; number_of_response:1; }","duration":"101.686002ms","start":"2026-10-15T19:54:12.706366Z","end":"2026-10-15T19:54:12.808052Z","steps":["trace[1960697197] 'process raft request' (duration: 101.461246ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:13.012665Z","caller":"traceutil/trace.go:171","msg":"trace[1240240167] transaction","detail":"{read_only:false; response_revision:29266; number_of_response:1; }","duration":"147.0167ms","start":"2026-10-15T19:54:12.865617Z","end":"2026-10-15T19:54:13.012634Z","steps":["trace[1240240167] 'process raft request' (duration: 146.821065ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:13.023029Z","caller":"traceutil/trace.go:171","msg":"trace[69725968] transaction","detail":"{read_only:false; response_revision:29267; number_of_response:1; }","duration":"133.492476ms","start":"2026-10-15T19:54:12.889499Z","end":"2026-10-15T19:54:13.022991Z","steps":["trace[69725968] 'process raft request' (duration: 133.308861ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:13.204011Z","caller":"traceutil/trace.go:171","msg":"trace[1049968491] transaction","detail":"{read_only:false; number_of_response:0; response_revision:29267; }","duration":"177.903917ms","start":"2026-10-15T19:54:13.026063Z","end":"2026-10-15T19:54:13.203967Z","steps":["trace[1049968491] 'process raft request' (duration: 177.765574ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:13.610306Z","caller":"traceutil/trace.go:171","msg":"trace[305922761] transaction","detail":"{read_only:false; response_revision:29268; number_of_response:1; }","duration":"392.713862ms","start":"2026-10-15T19:54:13.217565Z","end":"2026-10-15T19:54:13.610278Z","steps":["trace[305922761] 'process raft request' (duration: 392.563998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:13.610468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.217538Z","time spent":"392.842735ms","remote":"127.0.0.1:41926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:54:13.774232Z","caller":"traceutil/trace.go:171","msg":"trace[860837070] linearizableReadLoop","detail":"{readStateIndex:30610; appliedIndex:30608; }","duration":"318.596582ms","start":"2026-10-15T19:54:13.455606Z","end":"2026-10-15T19:54:13.774202Z","steps":["trace[860837070] 'read index received' (duration: 154.605408ms)","trace[860837070] 'applied index is now lower than readState.Index' (duration: 163.989524ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:54:13.77426Z","caller":"traceutil/trace.go:171","msg":"trace[1204637098] transaction","detail":"{read_only:false; response_revision:29269; number_of_response:1; }","duration":"478.426127ms","start":"2026-10-15T19:54:13.29581Z","end":"2026-10-15T19:54:13.774237Z","steps":["trace[1204637098] 'process raft request' (duration: 465.137769ms)","trace[1204637098] 'compare' (duration: 13.055002ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:54:13.774526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.295791Z","time spent":"478.642142ms","remote":"127.0.0.1:41738","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-15T19:54:13.774562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.948301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:54:13.77485Z","caller":"traceutil/trace.go:171","msg":"trace[371094062] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:29269; }","duration":"319.270219ms","start":"2026-10-15T19:54:13.455563Z","end":"2026-10-15T19:54:13.774833Z","steps":["trace[371094062] 'agreement among raft nodes before linearized reading' (duration: 318.766056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:13.775029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.455548Z","time spent":"319.464094ms","remote":"127.0.0.1:34324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:54:13.951374Z","caller":"traceutil/trace.go:171","msg":"trace[1706454086] linearizableReadLoop","detail":"{readStateIndex:30611; appliedIndex:30610; }","duration":"177.049585ms","start":"2026-10-15T19:54:13.774301Z","end":"2026-10-15T19:54:13.951351Z","steps":["trace[1706454086] 'read index received' (duration: 171.380437ms)","trace[1706454086] 'applied index is now lower than readState.Index' (duration: 5.668098ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:54:13.951847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.716244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-10-15T19:54:13.951875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.110821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-10-15T19:54:13.951947Z","caller":"traceutil/trace.go:171","msg":"trace[1737757616] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29270; }","duration":"140.855777ms","start":"2026-10-15T19:54:13.811075Z","end":"2026-10-15T19:54:13.95193Z","steps":["trace[1737757616] 'agreement among raft nodes before linearized reading' (duration: 140.629761ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:13.952068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.531661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T19:54:13.95213Z","caller":"traceutil/trace.go:171","msg":"trace[1954784755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29270; }","duration":"336.621514ms","start":"2026-10-15T19:54:13.615489Z","end":"2026-10-15T19:54:13.952111Z","steps":["trace[1954784755] 'agreement among raft nodes before linearized reading' (duration: 336.47668ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:13.951999Z","caller":"traceutil/trace.go:171","msg":"trace[1850922982] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29270; }","duration":"333.293336ms","start":"2026-10-15T19:54:13.618678Z","end":"2026-10-15T19:54:13.951971Z","steps":["trace[1850922982] 'agreement among raft nodes before linearized reading' (duration: 332.890956ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:13.952123Z","caller":"traceutil/trace.go:171","msg":"trace[457384736] transaction","detail":"{read_only:false; response_revision:29270; number_of_response:1; }","duration":"333.83703ms","start":"2026-10-15T19:54:13.618251Z","end":"2026-10-15T19:54:13.952088Z","steps":["trace[457384736] 'process raft request' (duration: 327.483054ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:13.952128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.665057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T19:54:13.952185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.615467Z","time spent":"336.706775ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T19:54:13.952229Z","caller":"traceutil/trace.go:171","msg":"trace[1463572280] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:29270; }","duration":"249.827031ms","start":"2026-10-15T19:54:13.70238Z","end":"2026-10-15T19:54:13.952207Z","steps":["trace[1463572280] 'agreement among raft nodes before linearized reading' (duration: 249.640626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:13.95226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.618658Z","time spent":"333.541713ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":551,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-15T19:54:13.952308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.618227Z","time spent":"333.976174ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:54:13.952288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.399926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-10-15T19:54:13.951898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.065791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T19:54:13.95214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.401549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-10-15T19:54:13.952366Z","caller":"traceutil/trace.go:171","msg":"trace[1394760843] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29270; }","duration":"140.498258ms","start":"2026-10-15T19:54:13.811849Z","end":"2026-10-15T19:54:13.952347Z","steps":["trace[1394760843] 'agreement among raft nodes before linearized reading' (duration: 140.297883ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:54:13.952462Z","caller":"traceutil/trace.go:171","msg":"trace[1582425483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29270; }","duration":"401.756968ms","start":"2026-10-15T19:54:13.550693Z","end":"2026-10-15T19:54:13.95245Z","steps":["trace[1582425483] 'agreement among raft nodes before linearized reading' (duration: 400.931127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:13.952543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.550676Z","time spent":"401.857491ms","remote":"127.0.0.1:41738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T19:54:13.952507Z","caller":"traceutil/trace.go:171","msg":"trace[1310726643] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29270; }","duration":"306.233018ms","start":"2026-10-15T19:54:13.646262Z","end":"2026-10-15T19:54:13.952495Z","steps":["trace[1310726643] 'agreement among raft nodes before linearized reading' (duration: 305.777166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:54:13.952661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:54:13.646243Z","time spent":"306.403592ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11521,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T19:55:32.438126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26857} {"level":"info","ts":"2026-10-15T19:55:32.610852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26857,"took":"168.676576ms","hash":531331822,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":35725312,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T19:55:32.610929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":531331822,"revision":26857,"compact-revision":23399} {"level":"info","ts":"2026-10-15T19:57:30.894342Z","caller":"traceutil/trace.go:171","msg":"trace[1069596980] transaction","detail":"{read_only:false; response_revision:31558; number_of_response:1; }","duration":"113.45311ms","start":"2026-10-15T19:57:30.780851Z","end":"2026-10-15T19:57:30.894304Z","steps":["trace[1069596980] 'process raft request' (duration: 113.184753ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:57:30.902126Z","caller":"traceutil/trace.go:171","msg":"trace[1534296266] transaction","detail":"{read_only:false; response_revision:31559; number_of_response:1; }","duration":"103.90046ms","start":"2026-10-15T19:57:30.798205Z","end":"2026-10-15T19:57:30.902105Z","steps":["trace[1534296266] 'process raft request' (duration: 103.50878ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T20:00:32.44539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30240} {"level":"info","ts":"2026-10-15T20:00:32.613334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30240,"took":"163.250659ms","hash":748759872,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":37560320,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-15T20:00:32.613423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":748759872,"revision":30240,"compact-revision":26857} {"level":"info","ts":"2026-10-15T20:05:32.45388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33402} {"level":"info","ts":"2026-10-15T20:05:32.629726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33402,"took":"170.856769ms","hash":3703841854,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":35172352,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T20:05:32.629789Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703841854,"revision":33402,"compact-revision":30240} {"level":"info","ts":"2026-10-15T20:09:38.835173Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:09:38.841889Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T20:09:38.84208Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T20:10:32.468963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36102} {"level":"info","ts":"2026-10-15T20:10:32.619391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36102,"took":"143.596653ms","hash":2229596946,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":34832384,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T20:10:32.61986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2229596946,"revision":36102,"compact-revision":33402} {"level":"info","ts":"2026-10-15T20:15:32.477075Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38812} {"level":"info","ts":"2026-10-15T20:15:32.673885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38812,"took":"191.388829ms","hash":4079202594,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":35278848,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T20:15:32.674023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4079202594,"revision":38812,"compact-revision":36102} {"level":"info","ts":"2026-10-15T20:20:32.48317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41552} {"level":"info","ts":"2026-10-15T20:20:32.659225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41552,"took":"169.997156ms","hash":1629961428,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":34115584,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T20:20:32.659316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1629961428,"revision":41552,"compact-revision":38812} {"level":"info","ts":"2026-10-15T20:25:32.492994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44225} {"level":"info","ts":"2026-10-15T20:25:32.661609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44225,"took":"161.241367ms","hash":4202411512,"current-db-size-bytes":61009920,"current-db-size":"61 MB","current-db-size-in-use-bytes":33148928,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T20:25:32.661709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4202411512,"revision":44225,"compact-revision":41552} {"level":"info","ts":"2026-10-15T20:27:12.506557Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4d4c1b1786e4e6dd","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:27:12.51492Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-10-15T20:27:12.515074Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}