{"level":"warn","ts":"2026-10-15T14:16:31.761245Z","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-15T14:16:31.761361Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.10:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.10:2380","--initial-cluster=instance=https://199.204.45.10:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.10:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.10: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-15T14:16:31.761468Z","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-15T14:16:31.761482Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.10:2380"]} {"level":"info","ts":"2026-10-15T14:16:31.761504Z","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-15T14:16:31.762375Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.10:2379"]} {"level":"info","ts":"2026-10-15T14:16:31.762574Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.10:2380"],"listen-peer-urls":["https://199.204.45.10:2380"],"advertise-client-urls":["https://199.204.45.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.10: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-15T14:16:31.768082Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.246889ms"} {"level":"info","ts":"2026-10-15T14:16:31.776986Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dd765eb5fac2932d","cluster-id":"c121f7599823c646"} {"level":"info","ts":"2026-10-15T14:16:31.777101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T14:16:31.777263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d became follower at term 0"} {"level":"info","ts":"2026-10-15T14:16:31.777286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dd765eb5fac2932d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T14:16:31.777301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d became follower at term 1"} {"level":"info","ts":"2026-10-15T14:16:31.777348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d switched to configuration voters=(15958046465323078445)"} {"level":"warn","ts":"2026-10-15T14:16:31.785261Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T14:16:31.787453Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T14:16:31.78912Z","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-15T14:16:31.791723Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dd765eb5fac2932d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T14:16:31.792333Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dd765eb5fac2932d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T14:16:31.792504Z","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-15T14:16:31.79254Z","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-15T14:16:31.79255Z","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-15T14:16:31.793233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d switched to configuration voters=(15958046465323078445)"} {"level":"info","ts":"2026-10-15T14:16:31.793366Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c121f7599823c646","local-member-id":"dd765eb5fac2932d","added-peer-id":"dd765eb5fac2932d","added-peer-peer-urls":["https://199.204.45.10:2380"]} {"level":"info","ts":"2026-10-15T14:16:31.799503Z","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-15T14:16:31.799638Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.10:2380"} {"level":"info","ts":"2026-10-15T14:16:31.799721Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.10:2380"} {"level":"info","ts":"2026-10-15T14:16:31.799843Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dd765eb5fac2932d","initial-advertise-peer-urls":["https://199.204.45.10:2380"],"listen-peer-urls":["https://199.204.45.10:2380"],"advertise-client-urls":["https://199.204.45.10:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.10:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T14:16:31.799902Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T14:16:31.877637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T14:16:31.877713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T14:16:31.877803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d received MsgPreVoteResp from dd765eb5fac2932d at term 1"} {"level":"info","ts":"2026-10-15T14:16:31.877862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d became candidate at term 2"} {"level":"info","ts":"2026-10-15T14:16:31.877881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d received MsgVoteResp from dd765eb5fac2932d at term 2"} {"level":"info","ts":"2026-10-15T14:16:31.877927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd765eb5fac2932d became leader at term 2"} {"level":"info","ts":"2026-10-15T14:16:31.877947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dd765eb5fac2932d elected leader dd765eb5fac2932d at term 2"} {"level":"info","ts":"2026-10-15T14:16:31.879235Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dd765eb5fac2932d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.10:2379]}","request-path":"/0/members/dd765eb5fac2932d/attributes","cluster-id":"c121f7599823c646","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T14:16:31.879247Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:16:31.879256Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:31.87929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T14:16:31.879611Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T14:16:31.879683Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T14:16:31.880409Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c121f7599823c646","local-member-id":"dd765eb5fac2932d","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:31.880574Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:31.880704Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T14:16:31.881913Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.10:2379"} {"level":"info","ts":"2026-10-15T14:16:31.883068Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T14:16:46.52399Z","caller":"traceutil/trace.go:171","msg":"trace[1988221389] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"114.717574ms","start":"2026-10-15T14:16:46.409235Z","end":"2026-10-15T14:16:46.523953Z","steps":["trace[1988221389] 'process raft request' (duration: 114.51455ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:17:22.629724Z","caller":"traceutil/trace.go:171","msg":"trace[659610986] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"167.67542ms","start":"2026-10-15T14:17:22.461996Z","end":"2026-10-15T14:17:22.629671Z","steps":["trace[659610986] 'process raft request' (duration: 96.492013ms)","trace[659610986] 'compare' (duration: 70.964972ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:22.764102Z","caller":"traceutil/trace.go:171","msg":"trace[2112351889] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"115.615768ms","start":"2026-10-15T14:17:22.648461Z","end":"2026-10-15T14:17:22.764077Z","steps":["trace[2112351889] 'process raft request' (duration: 115.481925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:23.26108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.347946ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:17:23.261285Z","caller":"traceutil/trace.go:171","msg":"trace[949245600] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"221.571411ms","start":"2026-10-15T14:17:23.039692Z","end":"2026-10-15T14:17:23.261264Z","steps":["trace[949245600] 'range keys from in-memory index tree' (duration: 221.329055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:23.262077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.36718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:17:23.262288Z","caller":"traceutil/trace.go:171","msg":"trace[1898282917] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"448.197958ms","start":"2026-10-15T14:17:22.814028Z","end":"2026-10-15T14:17:23.262226Z","steps":["trace[1898282917] 'process raft request' (duration: 273.939049ms)","trace[1898282917] 'compare' (duration: 173.196646ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:17:23.262346Z","caller":"traceutil/trace.go:171","msg":"trace[1860629425] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"365.086078ms","start":"2026-10-15T14:17:22.897232Z","end":"2026-10-15T14:17:23.262318Z","steps":["trace[1860629425] 'read index received' (duration: 190.813869ms)","trace[1860629425] 'applied index is now lower than readState.Index' (duration: 174.269029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:17:23.262489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.241221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:17:23.262534Z","caller":"traceutil/trace.go:171","msg":"trace[185552562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"365.354374ms","start":"2026-10-15T14:17:22.897166Z","end":"2026-10-15T14:17:23.262521Z","steps":["trace[185552562] 'agreement among raft nodes before linearized reading' (duration: 365.257663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:23.26258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:22.897145Z","time spent":"365.427395ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:17:23.262531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:22.814005Z","time spent":"448.384951ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:17:23.263068Z","caller":"traceutil/trace.go:171","msg":"trace[308349400] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"206.386319ms","start":"2026-10-15T14:17:23.056667Z","end":"2026-10-15T14:17:23.263053Z","steps":["trace[308349400] 'process raft request' (duration: 205.738095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:23.263105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.175632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:17:23.263151Z","caller":"traceutil/trace.go:171","msg":"trace[1421998102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"135.257273ms","start":"2026-10-15T14:17:23.127879Z","end":"2026-10-15T14:17:23.263136Z","steps":["trace[1421998102] 'agreement among raft nodes before linearized reading' (duration: 135.10092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.359428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.501317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:17:39.359563Z","caller":"traceutil/trace.go:171","msg":"trace[878279236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"460.662932ms","start":"2026-10-15T14:17:38.898856Z","end":"2026-10-15T14:17:39.359519Z","steps":["trace[878279236] 'range keys from in-memory index tree' (duration: 460.399565ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.359598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.898825Z","time spent":"460.766044ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T14:17:39.359339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.209807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T14:17:39.359663Z","caller":"traceutil/trace.go:171","msg":"trace[1676988296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"439.591475ms","start":"2026-10-15T14:17:38.920045Z","end":"2026-10-15T14:17:39.359636Z","steps":["trace[1676988296] 'range keys from in-memory index tree' (duration: 439.037402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:17:39.359705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:17:38.92002Z","time spent":"439.676177ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T14:18:55.482664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.588139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-15T14:18:55.482754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.987162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3a0e6cc3-5faa-4a63-9863-93ccb3b19200\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-15T14:18:55.482819Z","caller":"traceutil/trace.go:171","msg":"trace[1361775211] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1829; }","duration":"178.793383ms","start":"2026-10-15T14:18:55.303998Z","end":"2026-10-15T14:18:55.482792Z","steps":["trace[1361775211] 'range keys from in-memory index tree' (duration: 178.366305ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:55.482836Z","caller":"traceutil/trace.go:171","msg":"trace[1025107387] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3a0e6cc3-5faa-4a63-9863-93ccb3b19200; range_end:; response_count:1; response_revision:1829; }","duration":"126.128924ms","start":"2026-10-15T14:18:55.356689Z","end":"2026-10-15T14:18:55.482818Z","steps":["trace[1025107387] 'range keys from in-memory index tree' (duration: 125.644683ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:55.820268Z","caller":"traceutil/trace.go:171","msg":"trace[1342678555] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"279.160586ms","start":"2026-10-15T14:18:55.541067Z","end":"2026-10-15T14:18:55.820228Z","steps":["trace[1342678555] 'process raft request' (duration: 211.799722ms)","trace[1342678555] 'compare' (duration: 67.090988ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:18:55.820302Z","caller":"traceutil/trace.go:171","msg":"trace[345277925] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"173.119292ms","start":"2026-10-15T14:18:55.647146Z","end":"2026-10-15T14:18:55.820265Z","steps":["trace[345277925] 'read index received' (duration: 105.732667ms)","trace[345277925] 'applied index is now lower than readState.Index' (duration: 67.380125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:18:55.820629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.469809ms","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-15T14:18:55.82069Z","caller":"traceutil/trace.go:171","msg":"trace[2024811416] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1831; }","duration":"173.595382ms","start":"2026-10-15T14:18:55.647073Z","end":"2026-10-15T14:18:55.820668Z","steps":["trace[2024811416] 'agreement among raft nodes before linearized reading' (duration: 173.310946ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:56.019519Z","caller":"traceutil/trace.go:171","msg":"trace[1837177333] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"193.315255ms","start":"2026-10-15T14:18:55.826175Z","end":"2026-10-15T14:18:56.01949Z","steps":["trace[1837177333] 'process raft request' (duration: 193.137991ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:56.02051Z","caller":"traceutil/trace.go:171","msg":"trace[619216374] linearizableReadLoop","detail":"{readStateIndex:1905; appliedIndex:1903; }","duration":"156.338602ms","start":"2026-10-15T14:18:55.864145Z","end":"2026-10-15T14:18:56.020484Z","steps":["trace[619216374] 'read index received' (duration: 155.228269ms)","trace[619216374] 'applied index is now lower than readState.Index' (duration: 1.109323ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:18:56.020725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.115302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:18:56.020789Z","caller":"traceutil/trace.go:171","msg":"trace[1212118150] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"191.454326ms","start":"2026-10-15T14:18:55.829324Z","end":"2026-10-15T14:18:56.020779Z","steps":["trace[1212118150] 'process raft request' (duration: 191.002206ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:18:56.020802Z","caller":"traceutil/trace.go:171","msg":"trace[1744285901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1833; }","duration":"124.226964ms","start":"2026-10-15T14:18:55.896558Z","end":"2026-10-15T14:18:56.020785Z","steps":["trace[1744285901] 'agreement among raft nodes before linearized reading' (duration: 124.108471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:18:56.020747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.583677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T14:18:56.020863Z","caller":"traceutil/trace.go:171","msg":"trace[130798967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1833; }","duration":"156.756252ms","start":"2026-10-15T14:18:55.8641Z","end":"2026-10-15T14:18:56.020857Z","steps":["trace[130798967] 'agreement among raft nodes before linearized reading' (duration: 156.520017ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:38.911484Z","caller":"traceutil/trace.go:171","msg":"trace[1417926404] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"118.282989ms","start":"2026-10-15T14:19:38.793178Z","end":"2026-10-15T14:19:38.911461Z","steps":["trace[1417926404] 'read index received' (duration: 84.046813ms)","trace[1417926404] 'applied index is now lower than readState.Index' (duration: 34.234846ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:19:38.911525Z","caller":"traceutil/trace.go:171","msg":"trace[613509196] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"152.787321ms","start":"2026-10-15T14:19:38.758701Z","end":"2026-10-15T14:19:38.911489Z","steps":["trace[613509196] 'process raft request' (duration: 118.612096ms)","trace[613509196] 'compare' (duration: 34.046922ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:19:38.91181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.724736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T14:19:38.911838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.572605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T14:19:38.911904Z","caller":"traceutil/trace.go:171","msg":"trace[1477376249] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"118.7501ms","start":"2026-10-15T14:19:38.793134Z","end":"2026-10-15T14:19:38.911884Z","steps":["trace[1477376249] 'agreement among raft nodes before linearized reading' (duration: 118.444833ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:38.911875Z","caller":"traceutil/trace.go:171","msg":"trace[2071811643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2124; }","duration":"116.817708ms","start":"2026-10-15T14:19:38.795041Z","end":"2026-10-15T14:19:38.911859Z","steps":["trace[2071811643] 'agreement among raft nodes before linearized reading' (duration: 116.551672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:19:44.993892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.562686ms","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-15T14:19:44.993974Z","caller":"traceutil/trace.go:171","msg":"trace[201479514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"235.71588ms","start":"2026-10-15T14:19:44.758242Z","end":"2026-10-15T14:19:44.993958Z","steps":["trace[201479514] 'range keys from in-memory index tree' (duration: 235.27532ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:45.141167Z","caller":"traceutil/trace.go:171","msg":"trace[1218210252] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"141.832027ms","start":"2026-10-15T14:19:44.999308Z","end":"2026-10-15T14:19:45.14114Z","steps":["trace[1218210252] 'process raft request' (duration: 141.683455ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:45.176267Z","caller":"traceutil/trace.go:171","msg":"trace[429687436] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"176.732118ms","start":"2026-10-15T14:19:44.999502Z","end":"2026-10-15T14:19:45.176234Z","steps":["trace[429687436] 'process raft request' (duration: 176.558555ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:45.176375Z","caller":"traceutil/trace.go:171","msg":"trace[98574734] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"152.416522ms","start":"2026-10-15T14:19:45.023928Z","end":"2026-10-15T14:19:45.176345Z","steps":["trace[98574734] 'process raft request' (duration: 152.241719ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:19:45.414653Z","caller":"traceutil/trace.go:171","msg":"trace[166647840] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"232.637494ms","start":"2026-10-15T14:19:45.181974Z","end":"2026-10-15T14:19:45.414611Z","steps":["trace[166647840] 'process raft request' (duration: 219.213169ms)","trace[166647840] 'compare' (duration: 13.048427ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:21:12.944531Z","caller":"traceutil/trace.go:171","msg":"trace[319864100] linearizableReadLoop","detail":"{readStateIndex:2934; appliedIndex:2933; }","duration":"101.463504ms","start":"2026-10-15T14:21:12.843045Z","end":"2026-10-15T14:21:12.944508Z","steps":["trace[319864100] 'read index received' (duration: 101.26314ms)","trace[319864100] 'applied index is now lower than readState.Index' (duration: 199.174µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:21:12.944712Z","caller":"traceutil/trace.go:171","msg":"trace[1588406416] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"107.022541ms","start":"2026-10-15T14:21:12.837648Z","end":"2026-10-15T14:21:12.94467Z","steps":["trace[1588406416] 'process raft request' (duration: 106.734586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:21:12.944856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.783421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T14:21:12.944944Z","caller":"traceutil/trace.go:171","msg":"trace[2117694927] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2826; }","duration":"101.904863ms","start":"2026-10-15T14:21:12.84302Z","end":"2026-10-15T14:21:12.944924Z","steps":["trace[2117694927] 'agreement among raft nodes before linearized reading' (duration: 101.628238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:21:12.945592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.171257ms","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-15T14:21:12.94566Z","caller":"traceutil/trace.go:171","msg":"trace[905474197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2826; }","duration":"100.284939ms","start":"2026-10-15T14:21:12.845358Z","end":"2026-10-15T14:21:12.945643Z","steps":["trace[905474197] 'agreement among raft nodes before linearized reading' (duration: 99.427061ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:55.447544Z","caller":"traceutil/trace.go:171","msg":"trace[776546421] transaction","detail":"{read_only:false; response_revision:3812; number_of_response:1; }","duration":"114.090464ms","start":"2026-10-15T14:23:55.333394Z","end":"2026-10-15T14:23:55.447484Z","steps":["trace[776546421] 'process raft request' (duration: 107.425662ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:55.448378Z","caller":"traceutil/trace.go:171","msg":"trace[8367432] transaction","detail":"{read_only:false; response_revision:3814; number_of_response:1; }","duration":"111.201164ms","start":"2026-10-15T14:23:55.337153Z","end":"2026-10-15T14:23:55.448354Z","steps":["trace[8367432] 'process raft request' (duration: 111.157233ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:23:55.448449Z","caller":"traceutil/trace.go:171","msg":"trace[1970779367] transaction","detail":"{read_only:false; response_revision:3813; number_of_response:1; }","duration":"113.972613ms","start":"2026-10-15T14:23:55.334457Z","end":"2026-10-15T14:23:55.448429Z","steps":["trace[1970779367] 'process raft request' (duration: 113.734928ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:24:30.520461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.841548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbtwb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:24:30.520571Z","caller":"traceutil/trace.go:171","msg":"trace[1256991965] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbtwb; range_end:; response_count:1; response_revision:4064; }","duration":"120.998771ms","start":"2026-10-15T14:24:30.399549Z","end":"2026-10-15T14:24:30.520547Z","steps":["trace[1256991965] 'range keys from in-memory index tree' (duration: 120.622713ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:25:58.495516Z","caller":"traceutil/trace.go:171","msg":"trace[1683966561] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"136.296401ms","start":"2026-10-15T14:25:58.359197Z","end":"2026-10-15T14:25:58.495493Z","steps":["trace[1683966561] 'process raft request' (duration: 136.157328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:01.515933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.102835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:26:01.516123Z","caller":"traceutil/trace.go:171","msg":"trace[299303108] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5520; }","duration":"175.20561ms","start":"2026-10-15T14:26:01.340901Z","end":"2026-10-15T14:26:01.516106Z","steps":["trace[299303108] 'read index received' (duration: 32.838939ms)","trace[299303108] 'applied index is now lower than readState.Index' (duration: 142.365571ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:01.516192Z","caller":"traceutil/trace.go:171","msg":"trace[2050926963] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"183.233271ms","start":"2026-10-15T14:26:01.33293Z","end":"2026-10-15T14:26:01.516164Z","steps":["trace[2050926963] 'process raft request' (duration: 40.823209ms)","trace[2050926963] 'compare' (duration: 141.974792ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:01.516223Z","caller":"traceutil/trace.go:171","msg":"trace[1536793838] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"183.263351ms","start":"2026-10-15T14:26:01.33293Z","end":"2026-10-15T14:26:01.516194Z","steps":["trace[1536793838] 'process raft request' (duration: 183.111338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:01.516286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.423575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-10-15T14:26:01.516318Z","caller":"traceutil/trace.go:171","msg":"trace[1300896956] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5321; }","duration":"175.467835ms","start":"2026-10-15T14:26:01.340837Z","end":"2026-10-15T14:26:01.516305Z","steps":["trace[1300896956] 'agreement among raft nodes before linearized reading' (duration: 175.341973ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:01.573196Z","caller":"traceutil/trace.go:171","msg":"trace[1143538549] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"226.637325ms","start":"2026-10-15T14:26:01.346536Z","end":"2026-10-15T14:26:01.573173Z","steps":["trace[1143538549] 'process raft request' (duration: 225.776906ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:12.146122Z","caller":"traceutil/trace.go:171","msg":"trace[305003337] transaction","detail":"{read_only:false; response_revision:5492; number_of_response:1; }","duration":"232.599952ms","start":"2026-10-15T14:26:11.913491Z","end":"2026-10-15T14:26:12.146091Z","steps":["trace[305003337] 'process raft request' (duration: 149.720247ms)","trace[305003337] 'compare' (duration: 82.65605ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:12.619079Z","caller":"traceutil/trace.go:171","msg":"trace[1059034205] linearizableReadLoop","detail":"{readStateIndex:5702; appliedIndex:5701; }","duration":"167.728751ms","start":"2026-10-15T14:26:12.451316Z","end":"2026-10-15T14:26:12.619045Z","steps":["trace[1059034205] 'read index received' (duration: 167.518076ms)","trace[1059034205] 'applied index is now lower than readState.Index' (duration: 209.385µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:12.619221Z","caller":"traceutil/trace.go:171","msg":"trace[1798267040] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"183.307312ms","start":"2026-10-15T14:26:12.435901Z","end":"2026-10-15T14:26:12.619209Z","steps":["trace[1798267040] 'process raft request' (duration: 182.959005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:12.619508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.161219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:26:12.619612Z","caller":"traceutil/trace.go:171","msg":"trace[1659431355] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5496; }","duration":"168.341344ms","start":"2026-10-15T14:26:12.451252Z","end":"2026-10-15T14:26:12.619593Z","steps":["trace[1659431355] 'agreement among raft nodes before linearized reading' (duration: 168.16316ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:12.725626Z","caller":"traceutil/trace.go:171","msg":"trace[1327752512] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"101.374128ms","start":"2026-10-15T14:26:12.624224Z","end":"2026-10-15T14:26:12.725598Z","steps":["trace[1327752512] 'process raft request' (duration: 99.503119ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:12.747813Z","caller":"traceutil/trace.go:171","msg":"trace[625133704] linearizableReadLoop","detail":"{readStateIndex:5704; appliedIndex:5702; }","duration":"119.81386ms","start":"2026-10-15T14:26:12.627979Z","end":"2026-10-15T14:26:12.747792Z","steps":["trace[625133704] 'read index received' (duration: 95.790449ms)","trace[625133704] 'applied index is now lower than readState.Index' (duration: 24.022351ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:12.748122Z","caller":"traceutil/trace.go:171","msg":"trace[1486383896] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"120.489636ms","start":"2026-10-15T14:26:12.627619Z","end":"2026-10-15T14:26:12.748109Z","steps":["trace[1486383896] 'process raft request' (duration: 119.724729ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:12.748367Z","caller":"traceutil/trace.go:171","msg":"trace[1865008805] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"120.170528ms","start":"2026-10-15T14:26:12.628187Z","end":"2026-10-15T14:26:12.748357Z","steps":["trace[1865008805] 'process raft request' (duration: 119.337821ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:12.748739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.72433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-10-15T14:26:12.748852Z","caller":"traceutil/trace.go:171","msg":"trace[1951371283] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5502; }","duration":"120.873553ms","start":"2026-10-15T14:26:12.627955Z","end":"2026-10-15T14:26:12.748828Z","steps":["trace[1951371283] 'agreement among raft nodes before linearized reading' (duration: 120.538835ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:12.749178Z","caller":"traceutil/trace.go:171","msg":"trace[1438438334] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"114.965628ms","start":"2026-10-15T14:26:12.634193Z","end":"2026-10-15T14:26:12.749158Z","steps":["trace[1438438334] 'process raft request' (duration: 113.440745ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:12.749603Z","caller":"traceutil/trace.go:171","msg":"trace[170167333] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"115.048149ms","start":"2026-10-15T14:26:12.634537Z","end":"2026-10-15T14:26:12.749585Z","steps":["trace[170167333] 'process raft request' (duration: 113.157679ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:17.401802Z","caller":"traceutil/trace.go:171","msg":"trace[2078954406] linearizableReadLoop","detail":"{readStateIndex:5763; appliedIndex:5761; }","duration":"103.339999ms","start":"2026-10-15T14:26:17.298418Z","end":"2026-10-15T14:26:17.401758Z","steps":["trace[2078954406] 'read index received' (duration: 46.747455ms)","trace[2078954406] 'applied index is now lower than readState.Index' (duration: 56.591314ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:17.401966Z","caller":"traceutil/trace.go:171","msg":"trace[756024654] transaction","detail":"{read_only:false; response_revision:5556; number_of_response:1; }","duration":"135.376712ms","start":"2026-10-15T14:26:17.266539Z","end":"2026-10-15T14:26:17.401916Z","steps":["trace[756024654] 'process raft request' (duration: 135.000744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:17.402129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.659317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:26:17.402268Z","caller":"traceutil/trace.go:171","msg":"trace[2047561326] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:5556; }","duration":"103.887982ms","start":"2026-10-15T14:26:17.298351Z","end":"2026-10-15T14:26:17.402239Z","steps":["trace[2047561326] 'agreement among raft nodes before linearized reading' (duration: 103.606585ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:20.926865Z","caller":"traceutil/trace.go:171","msg":"trace[1825717877] transaction","detail":"{read_only:false; response_revision:5660; number_of_response:1; }","duration":"153.039167ms","start":"2026-10-15T14:26:20.773797Z","end":"2026-10-15T14:26:20.926836Z","steps":["trace[1825717877] 'process raft request' (duration: 134.663927ms)","trace[1825717877] 'compare' (duration: 18.250538ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:20.929932Z","caller":"traceutil/trace.go:171","msg":"trace[1152030296] transaction","detail":"{read_only:false; response_revision:5661; number_of_response:1; }","duration":"155.118062ms","start":"2026-10-15T14:26:20.774799Z","end":"2026-10-15T14:26:20.929917Z","steps":["trace[1152030296] 'process raft request' (duration: 154.975959ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:20.930119Z","caller":"traceutil/trace.go:171","msg":"trace[928745969] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"153.61693ms","start":"2026-10-15T14:26:20.776492Z","end":"2026-10-15T14:26:20.930109Z","steps":["trace[928745969] 'process raft request' (duration: 153.383885ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:20.930144Z","caller":"traceutil/trace.go:171","msg":"trace[986941648] linearizableReadLoop","detail":"{readStateIndex:5870; appliedIndex:5867; }","duration":"129.164199ms","start":"2026-10-15T14:26:20.800939Z","end":"2026-10-15T14:26:20.930103Z","steps":["trace[986941648] 'read index received' (duration: 107.529199ms)","trace[986941648] 'applied index is now lower than readState.Index' (duration: 21.6262ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:26:20.930386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.425015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T14:26:20.930425Z","caller":"traceutil/trace.go:171","msg":"trace[1425567113] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5662; }","duration":"129.527038ms","start":"2026-10-15T14:26:20.800886Z","end":"2026-10-15T14:26:20.930414Z","steps":["trace[1425567113] 'agreement among raft nodes before linearized reading' (duration: 129.365574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:20.930439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.253791ms","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-15T14:26:20.930533Z","caller":"traceutil/trace.go:171","msg":"trace[1211151828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5662; }","duration":"128.358473ms","start":"2026-10-15T14:26:20.802161Z","end":"2026-10-15T14:26:20.93052Z","steps":["trace[1211151828] 'agreement among raft nodes before linearized reading' (duration: 128.104127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:20.930685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.862688ms","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":"info","ts":"2026-10-15T14:26:20.930724Z","caller":"traceutil/trace.go:171","msg":"trace[1155482100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5662; }","duration":"116.933129ms","start":"2026-10-15T14:26:20.813782Z","end":"2026-10-15T14:26:20.930716Z","steps":["trace[1155482100] 'agreement among raft nodes before linearized reading' (duration: 116.828297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:21.469899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.521496ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-15T14:26:21.470155Z","caller":"traceutil/trace.go:171","msg":"trace[2123366457] linearizableReadLoop","detail":"{readStateIndex:5874; appliedIndex:5873; }","duration":"447.447976ms","start":"2026-10-15T14:26:21.022657Z","end":"2026-10-15T14:26:21.470105Z","steps":["trace[2123366457] 'read index received' (duration: 23.630553ms)","trace[2123366457] 'applied index is now lower than readState.Index' (duration: 423.809513ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:21.470191Z","caller":"traceutil/trace.go:171","msg":"trace[80593001] transaction","detail":"{read_only:false; response_revision:5666; number_of_response:1; }","duration":"443.646085ms","start":"2026-10-15T14:26:21.02653Z","end":"2026-10-15T14:26:21.470176Z","steps":["trace[80593001] 'process raft request' (duration: 443.47797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:21.470325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:21.026499Z","time spent":"443.739946ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4777,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:26:21.470357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.712216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-15T14:26:21.470429Z","caller":"traceutil/trace.go:171","msg":"trace[377816767] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5666; }","duration":"497.832909ms","start":"2026-10-15T14:26:20.972575Z","end":"2026-10-15T14:26:21.470408Z","steps":["trace[377816767] 'agreement among raft nodes before linearized reading' (duration: 497.605594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:21.470461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.920039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbtwb\" ","response":"range_response_count:1 size:6526"} {"level":"warn","ts":"2026-10-15T14:26:21.470503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:20.97253Z","time spent":"497.964211ms","remote":"127.0.0.1:53406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1841,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-10-15T14:26:21.470576Z","caller":"traceutil/trace.go:171","msg":"trace[1270919880] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbtwb; range_end:; response_count:1; response_revision:5666; }","duration":"350.049553ms","start":"2026-10-15T14:26:21.120478Z","end":"2026-10-15T14:26:21.470528Z","steps":["trace[1270919880] 'agreement among raft nodes before linearized reading' (duration: 349.822228ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:21.470635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:26:21.120453Z","time spent":"350.169205ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-dbtwb\" "} {"level":"warn","ts":"2026-10-15T14:26:21.470762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.432766ms","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-15T14:26:21.470821Z","caller":"traceutil/trace.go:171","msg":"trace[858161796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5666; }","duration":"106.527239ms","start":"2026-10-15T14:26:21.36428Z","end":"2026-10-15T14:26:21.470807Z","steps":["trace[858161796] 'agreement among raft nodes before linearized reading' (duration: 106.360236ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:32.180179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2934} {"level":"info","ts":"2026-10-15T14:26:32.255187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2934,"took":"73.607646ms","hash":363925962,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:26:32.255259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":363925962,"revision":2934,"compact-revision":-1} {"level":"warn","ts":"2026-10-15T14:26:33.919165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.438949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:26:33.919414Z","caller":"traceutil/trace.go:171","msg":"trace[97296320] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"132.314687ms","start":"2026-10-15T14:26:33.787081Z","end":"2026-10-15T14:26:33.919395Z","steps":["trace[97296320] 'process raft request' (duration: 132.193734ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:33.919712Z","caller":"traceutil/trace.go:171","msg":"trace[1224946545] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"283.235932ms","start":"2026-10-15T14:26:33.636459Z","end":"2026-10-15T14:26:33.919695Z","steps":["trace[1224946545] 'process raft request' (duration: 135.040747ms)","trace[1224946545] 'compare' (duration: 147.237904ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:26:33.91997Z","caller":"traceutil/trace.go:171","msg":"trace[1947242548] linearizableReadLoop","detail":"{readStateIndex:6040; appliedIndex:6039; }","duration":"279.635005ms","start":"2026-10-15T14:26:33.640308Z","end":"2026-10-15T14:26:33.919943Z","steps":["trace[1947242548] 'read index received' (duration: 131.162034ms)","trace[1947242548] 'applied index is now lower than readState.Index' (duration: 148.471141ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:26:33.920179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.852979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbtwb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:26:33.920219Z","caller":"traceutil/trace.go:171","msg":"trace[1178792397] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbtwb; range_end:; response_count:1; response_revision:5826; }","duration":"279.933541ms","start":"2026-10-15T14:26:33.640273Z","end":"2026-10-15T14:26:33.920207Z","steps":["trace[1178792397] 'agreement among raft nodes before linearized reading' (duration: 279.792718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:26:33.920587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.058737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-10-15T14:26:33.920572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.34184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-15T14:26:33.920819Z","caller":"traceutil/trace.go:171","msg":"trace[1090505039] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5826; }","duration":"212.628467ms","start":"2026-10-15T14:26:33.708161Z","end":"2026-10-15T14:26:33.920789Z","steps":["trace[1090505039] 'agreement among raft nodes before linearized reading' (duration: 212.227048ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:26:33.920699Z","caller":"traceutil/trace.go:171","msg":"trace[91770217] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5826; }","duration":"221.19619ms","start":"2026-10-15T14:26:33.699475Z","end":"2026-10-15T14:26:33.920671Z","steps":["trace[91770217] 'agreement among raft nodes before linearized reading' (duration: 220.832062ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:31:32.188046Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5814} {"level":"info","ts":"2026-10-15T14:31:32.275451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5814,"took":"85.975616ms","hash":574377122,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":26222592,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T14:31:32.275522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":574377122,"revision":5814,"compact-revision":2934} {"level":"info","ts":"2026-10-15T14:32:29.946216Z","caller":"traceutil/trace.go:171","msg":"trace[920361087] linearizableReadLoop","detail":"{readStateIndex:8826; appliedIndex:8825; }","duration":"152.280312ms","start":"2026-10-15T14:32:29.793912Z","end":"2026-10-15T14:32:29.946192Z","steps":["trace[920361087] 'read index received' (duration: 152.026606ms)","trace[920361087] 'applied index is now lower than readState.Index' (duration: 252.746µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:32:29.946298Z","caller":"traceutil/trace.go:171","msg":"trace[33368839] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"180.202428ms","start":"2026-10-15T14:32:29.766065Z","end":"2026-10-15T14:32:29.946267Z","steps":["trace[33368839] 'process raft request' (duration: 179.984903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:32:29.946536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.612589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-15T14:32:29.946596Z","caller":"traceutil/trace.go:171","msg":"trace[1832583437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8523; }","duration":"152.717102ms","start":"2026-10-15T14:32:29.793859Z","end":"2026-10-15T14:32:29.946576Z","steps":["trace[1832583437] 'agreement among raft nodes before linearized reading' (duration: 152.515087ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:41.434284Z","caller":"traceutil/trace.go:171","msg":"trace[458647756] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"321.155857ms","start":"2026-10-15T14:32:41.113102Z","end":"2026-10-15T14:32:41.434258Z","steps":["trace[458647756] 'process raft request' (duration: 320.976514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:32:41.434504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:32:41.113076Z","time spent":"321.294121ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:32:42.214168Z","caller":"traceutil/trace.go:171","msg":"trace[867439307] linearizableReadLoop","detail":"{readStateIndex:8894; appliedIndex:8893; }","duration":"184.840057ms","start":"2026-10-15T14:32:42.029301Z","end":"2026-10-15T14:32:42.214141Z","steps":["trace[867439307] 'read index received' (duration: 184.672023ms)","trace[867439307] 'applied index is now lower than readState.Index' (duration: 167.344µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:32:42.214353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.034721ms","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-15T14:32:42.214381Z","caller":"traceutil/trace.go:171","msg":"trace[856963244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8587; }","duration":"185.107903ms","start":"2026-10-15T14:32:42.029264Z","end":"2026-10-15T14:32:42.214372Z","steps":["trace[856963244] 'agreement among raft nodes before linearized reading' (duration: 184.98817ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:42.214855Z","caller":"traceutil/trace.go:171","msg":"trace[168804842] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"224.873012ms","start":"2026-10-15T14:32:41.989884Z","end":"2026-10-15T14:32:42.214757Z","steps":["trace[168804842] 'process raft request' (duration: 224.141506ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:32:42.319975Z","caller":"traceutil/trace.go:171","msg":"trace[842783262] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"100.800682ms","start":"2026-10-15T14:32:42.21915Z","end":"2026-10-15T14:32:42.319951Z","steps":["trace[842783262] 'process raft request' (duration: 60.39515ms)","trace[842783262] 'compare' (duration: 40.28478ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:09.643422Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002392.wal"} {"level":"info","ts":"2026-10-15T14:33:11.700018Z","caller":"traceutil/trace.go:171","msg":"trace[1662185887] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"118.97715ms","start":"2026-10-15T14:33:11.581018Z","end":"2026-10-15T14:33:11.699995Z","steps":["trace[1662185887] 'process raft request' (duration: 115.044556ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:11.858239Z","caller":"traceutil/trace.go:171","msg":"trace[1683606350] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"128.256909ms","start":"2026-10-15T14:33:11.729937Z","end":"2026-10-15T14:33:11.858193Z","steps":["trace[1683606350] 'process raft request' (duration: 128.214018ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:11.858618Z","caller":"traceutil/trace.go:171","msg":"trace[1293501042] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"154.878898ms","start":"2026-10-15T14:33:11.703728Z","end":"2026-10-15T14:33:11.858607Z","steps":["trace[1293501042] 'process raft request' (duration: 154.314986ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:13.434563Z","caller":"traceutil/trace.go:171","msg":"trace[1943654400] linearizableReadLoop","detail":"{readStateIndex:9122; appliedIndex:9121; }","duration":"211.141808ms","start":"2026-10-15T14:33:13.223395Z","end":"2026-10-15T14:33:13.434537Z","steps":["trace[1943654400] 'read index received' (duration: 210.956054ms)","trace[1943654400] 'applied index is now lower than readState.Index' (duration: 184.644µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:13.434675Z","caller":"traceutil/trace.go:171","msg":"trace[1695717038] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"211.28624ms","start":"2026-10-15T14:33:13.223351Z","end":"2026-10-15T14:33:13.434637Z","steps":["trace[1695717038] 'process raft request' (duration: 210.975074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:13.434779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.361642ms","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-15T14:33:13.434992Z","caller":"traceutil/trace.go:171","msg":"trace[1711176784] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8807; }","duration":"211.621168ms","start":"2026-10-15T14:33:13.223353Z","end":"2026-10-15T14:33:13.434975Z","steps":["trace[1711176784] 'agreement among raft nodes before linearized reading' (duration: 211.300251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:13.437532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.05062ms","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-15T14:33:13.437611Z","caller":"traceutil/trace.go:171","msg":"trace[951127302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8808; }","duration":"214.150282ms","start":"2026-10-15T14:33:13.223442Z","end":"2026-10-15T14:33:13.437592Z","steps":["trace[951127302] 'agreement among raft nodes before linearized reading' (duration: 213.926948ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:24.879316Z","caller":"traceutil/trace.go:171","msg":"trace[339862556] transaction","detail":"{read_only:false; response_revision:8880; number_of_response:1; }","duration":"155.337706ms","start":"2026-10-15T14:33:24.723948Z","end":"2026-10-15T14:33:24.879286Z","steps":["trace[339862556] 'process raft request' (duration: 101.488767ms)","trace[339862556] 'compare' (duration: 53.587033ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:25.306876Z","caller":"traceutil/trace.go:171","msg":"trace[1851586010] linearizableReadLoop","detail":"{readStateIndex:9198; appliedIndex:9197; }","duration":"115.050617ms","start":"2026-10-15T14:33:25.191798Z","end":"2026-10-15T14:33:25.306848Z","steps":["trace[1851586010] 'read index received' (duration: 114.628137ms)","trace[1851586010] 'applied index is now lower than readState.Index' (duration: 421.06µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:25.306933Z","caller":"traceutil/trace.go:171","msg":"trace[819229996] transaction","detail":"{read_only:false; response_revision:8881; number_of_response:1; }","duration":"115.512747ms","start":"2026-10-15T14:33:25.191384Z","end":"2026-10-15T14:33:25.306897Z","steps":["trace[819229996] 'process raft request' (duration: 115.272112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:33:25.307153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.325223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-cklx2\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-10-15T14:33:25.30725Z","caller":"traceutil/trace.go:171","msg":"trace[1554548867] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-cklx2; range_end:; response_count:1; response_revision:8881; }","duration":"115.473685ms","start":"2026-10-15T14:33:25.191757Z","end":"2026-10-15T14:33:25.307231Z","steps":["trace[1554548867] 'agreement among raft nodes before linearized reading' (duration: 115.20959ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:25.447423Z","caller":"traceutil/trace.go:171","msg":"trace[1815566770] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"135.422251ms","start":"2026-10-15T14:33:25.311965Z","end":"2026-10-15T14:33:25.447388Z","steps":["trace[1815566770] 'process raft request' (duration: 105.471072ms)","trace[1815566770] 'compare' (duration: 29.817227ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:33:25.517824Z","caller":"traceutil/trace.go:171","msg":"trace[986646941] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"199.775086ms","start":"2026-10-15T14:33:25.318012Z","end":"2026-10-15T14:33:25.517787Z","steps":["trace[986646941] 'process raft request' (duration: 199.52544ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:33:39.038625Z","caller":"traceutil/trace.go:171","msg":"trace[870007018] transaction","detail":"{read_only:false; response_revision:9022; number_of_response:1; }","duration":"115.484328ms","start":"2026-10-15T14:33:38.923118Z","end":"2026-10-15T14:33:39.038602Z","steps":["trace[870007018] 'process raft request' (duration: 115.295383ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:34:42.021104Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd765eb5fac2932d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:34:42.031862Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T14:34:42.031977Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T14:34:51.147607Z","caller":"traceutil/trace.go:171","msg":"trace[656116895] transaction","detail":"{read_only:false; response_revision:9715; number_of_response:1; }","duration":"117.499721ms","start":"2026-10-15T14:34:51.030074Z","end":"2026-10-15T14:34:51.147574Z","steps":["trace[656116895] 'process raft request' (duration: 73.884069ms)","trace[656116895] 'compare' (duration: 43.463568ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:36:32.195697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8007} {"level":"info","ts":"2026-10-15T14:36:32.273808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8007,"took":"75.81163ms","hash":210502313,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":28659712,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T14:36:32.273907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":210502313,"revision":8007,"compact-revision":5814} {"level":"info","ts":"2026-10-15T14:41:32.201437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10417} {"level":"info","ts":"2026-10-15T14:41:32.297264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10417,"took":"93.483358ms","hash":191139065,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":26038272,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T14:41:32.297341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":191139065,"revision":10417,"compact-revision":8007} {"level":"info","ts":"2026-10-15T14:46:32.208688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12583} {"level":"info","ts":"2026-10-15T14:46:32.292733Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12583,"took":"81.633333ms","hash":1332551772,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":27504640,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T14:46:32.292802Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1332551772,"revision":12583,"compact-revision":10417} {"level":"warn","ts":"2026-10-15T14:46:59.278356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.790792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbtwb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:46:59.278459Z","caller":"traceutil/trace.go:171","msg":"trace[1191197569] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbtwb; range_end:; response_count:1; response_revision:14759; }","duration":"249.926516ms","start":"2026-10-15T14:46:59.028506Z","end":"2026-10-15T14:46:59.278432Z","steps":["trace[1191197569] 'range keys from in-memory index tree' (duration: 249.630989ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:47:59.660687Z","caller":"traceutil/trace.go:171","msg":"trace[1548475013] transaction","detail":"{read_only:false; response_revision:15317; number_of_response:1; }","duration":"127.486512ms","start":"2026-10-15T14:47:59.533172Z","end":"2026-10-15T14:47:59.660659Z","steps":["trace[1548475013] 'process raft request' (duration: 115.206769ms)","trace[1548475013] 'compare' (duration: 12.086578ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:48:00.448822Z","caller":"traceutil/trace.go:171","msg":"trace[1410431053] transaction","detail":"{read_only:false; response_revision:15414; number_of_response:1; }","duration":"116.765512ms","start":"2026-10-15T14:48:00.33203Z","end":"2026-10-15T14:48:00.448796Z","steps":["trace[1410431053] 'process raft request' (duration: 78.112568ms)","trace[1410431053] 'compare' (duration: 38.481111ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:48:00.458395Z","caller":"traceutil/trace.go:171","msg":"trace[1961210569] transaction","detail":"{read_only:false; response_revision:15415; number_of_response:1; }","duration":"126.080992ms","start":"2026-10-15T14:48:00.332282Z","end":"2026-10-15T14:48:00.458363Z","steps":["trace[1961210569] 'process raft request' (duration: 125.934219ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.458553Z","caller":"traceutil/trace.go:171","msg":"trace[270513670] transaction","detail":"{read_only:false; response_revision:15417; number_of_response:1; }","duration":"126.097221ms","start":"2026-10-15T14:48:00.332427Z","end":"2026-10-15T14:48:00.458524Z","steps":["trace[270513670] 'process raft request' (duration: 125.915058ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.45845Z","caller":"traceutil/trace.go:171","msg":"trace[845081693] transaction","detail":"{read_only:false; response_revision:15416; number_of_response:1; }","duration":"126.048252ms","start":"2026-10-15T14:48:00.332377Z","end":"2026-10-15T14:48:00.458425Z","steps":["trace[845081693] 'process raft request' (duration: 125.931489ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.458657Z","caller":"traceutil/trace.go:171","msg":"trace[234898577] transaction","detail":"{read_only:false; response_revision:15418; number_of_response:1; }","duration":"126.176684ms","start":"2026-10-15T14:48:00.332443Z","end":"2026-10-15T14:48:00.45862Z","steps":["trace[234898577] 'process raft request' (duration: 125.932228ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.458711Z","caller":"traceutil/trace.go:171","msg":"trace[2040990625] linearizableReadLoop","detail":"{readStateIndex:15956; appliedIndex:15950; }","duration":"124.204702ms","start":"2026-10-15T14:48:00.33449Z","end":"2026-10-15T14:48:00.458695Z","steps":["trace[2040990625] 'read index received' (duration: 75.670835ms)","trace[2040990625] 'applied index is now lower than readState.Index' (duration: 48.532217ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:48:00.458743Z","caller":"traceutil/trace.go:171","msg":"trace[166375015] transaction","detail":"{read_only:false; response_revision:15419; number_of_response:1; }","duration":"125.0706ms","start":"2026-10-15T14:48:00.333627Z","end":"2026-10-15T14:48:00.458697Z","steps":["trace[166375015] 'process raft request' (duration: 124.922116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:00.458864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.374385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:48:00.458922Z","caller":"traceutil/trace.go:171","msg":"trace[234638012] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:15419; }","duration":"124.474108ms","start":"2026-10-15T14:48:00.334432Z","end":"2026-10-15T14:48:00.458906Z","steps":["trace[234638012] 'agreement among raft nodes before linearized reading' (duration: 124.338195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:00.458918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.293704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T14:48:00.459026Z","caller":"traceutil/trace.go:171","msg":"trace[2107749433] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:15419; }","duration":"124.413026ms","start":"2026-10-15T14:48:00.334585Z","end":"2026-10-15T14:48:00.458998Z","steps":["trace[2107749433] 'agreement among raft nodes before linearized reading' (duration: 124.247553ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.6322Z","caller":"traceutil/trace.go:171","msg":"trace[1944697078] transaction","detail":"{read_only:false; response_revision:15428; number_of_response:1; }","duration":"131.927236ms","start":"2026-10-15T14:48:00.500247Z","end":"2026-10-15T14:48:00.632174Z","steps":["trace[1944697078] 'process raft request' (duration: 56.504356ms)","trace[1944697078] 'compare' (duration: 75.074733ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:48:00.632297Z","caller":"traceutil/trace.go:171","msg":"trace[1395884874] transaction","detail":"{read_only:false; response_revision:15429; number_of_response:1; }","duration":"130.268191ms","start":"2026-10-15T14:48:00.502001Z","end":"2026-10-15T14:48:00.632269Z","steps":["trace[1395884874] 'process raft request' (duration: 130.063286ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.632571Z","caller":"traceutil/trace.go:171","msg":"trace[1250132265] transaction","detail":"{read_only:false; response_revision:15430; number_of_response:1; }","duration":"123.618739ms","start":"2026-10-15T14:48:00.508939Z","end":"2026-10-15T14:48:00.632558Z","steps":["trace[1250132265] 'process raft request' (duration: 123.236271ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.63257Z","caller":"traceutil/trace.go:171","msg":"trace[1486395053] transaction","detail":"{read_only:false; response_revision:15431; number_of_response:1; }","duration":"122.871274ms","start":"2026-10-15T14:48:00.509638Z","end":"2026-10-15T14:48:00.632509Z","steps":["trace[1486395053] 'process raft request' (duration: 122.606228ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.632585Z","caller":"traceutil/trace.go:171","msg":"trace[23215505] linearizableReadLoop","detail":"{readStateIndex:15968; appliedIndex:15964; }","duration":"106.090285ms","start":"2026-10-15T14:48:00.526291Z","end":"2026-10-15T14:48:00.632381Z","steps":["trace[23215505] 'read index received' (duration: 30.561882ms)","trace[23215505] 'applied index is now lower than readState.Index' (duration: 75.526192ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:48:00.632864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.494708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-10-15T14:48:00.632921Z","caller":"traceutil/trace.go:171","msg":"trace[1058935801] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:15432; }","duration":"132.586441ms","start":"2026-10-15T14:48:00.500319Z","end":"2026-10-15T14:48:00.632905Z","steps":["trace[1058935801] 'agreement among raft nodes before linearized reading' (duration: 132.323905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:00.685481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.162642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-15T14:48:00.685512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.651993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"warn","ts":"2026-10-15T14:48:00.685513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.556838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T14:48:00.685556Z","caller":"traceutil/trace.go:171","msg":"trace[1472225850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15446; }","duration":"154.277873ms","start":"2026-10-15T14:48:00.531262Z","end":"2026-10-15T14:48:00.68554Z","steps":["trace[1472225850] 'agreement among raft nodes before linearized reading' (duration: 154.093059ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.685564Z","caller":"traceutil/trace.go:171","msg":"trace[406167294] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:15446; }","duration":"155.732424ms","start":"2026-10-15T14:48:00.529818Z","end":"2026-10-15T14:48:00.68555Z","steps":["trace[406167294] 'agreement among raft nodes before linearized reading' (duration: 155.585561ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.685585Z","caller":"traceutil/trace.go:171","msg":"trace[221226831] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15446; }","duration":"108.67127ms","start":"2026-10-15T14:48:00.576895Z","end":"2026-10-15T14:48:00.685567Z","steps":["trace[221226831] 'agreement among raft nodes before linearized reading' (duration: 108.463925ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.903527Z","caller":"traceutil/trace.go:171","msg":"trace[1930382520] transaction","detail":"{read_only:false; response_revision:15487; number_of_response:1; }","duration":"124.788814ms","start":"2026-10-15T14:48:00.778716Z","end":"2026-10-15T14:48:00.903505Z","steps":["trace[1930382520] 'process raft request' (duration: 122.214689ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.90363Z","caller":"traceutil/trace.go:171","msg":"trace[1532218576] transaction","detail":"{read_only:false; response_revision:15488; number_of_response:1; }","duration":"124.775043ms","start":"2026-10-15T14:48:00.778836Z","end":"2026-10-15T14:48:00.903611Z","steps":["trace[1532218576] 'process raft request' (duration: 124.61081ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:00.904211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.579014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111932"} {"level":"info","ts":"2026-10-15T14:48:00.904289Z","caller":"traceutil/trace.go:171","msg":"trace[554967436] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:15489; }","duration":"120.689157ms","start":"2026-10-15T14:48:00.783584Z","end":"2026-10-15T14:48:00.904273Z","steps":["trace[554967436] 'agreement among raft nodes before linearized reading' (duration: 120.421061ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.978985Z","caller":"traceutil/trace.go:171","msg":"trace[1501135575] transaction","detail":"{read_only:false; response_revision:15490; number_of_response:1; }","duration":"141.082382ms","start":"2026-10-15T14:48:00.837881Z","end":"2026-10-15T14:48:00.978964Z","steps":["trace[1501135575] 'process raft request' (duration: 140.880797ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.979034Z","caller":"traceutil/trace.go:171","msg":"trace[187431853] transaction","detail":"{read_only:false; response_revision:15491; number_of_response:1; }","duration":"137.24163ms","start":"2026-10-15T14:48:00.841759Z","end":"2026-10-15T14:48:00.979001Z","steps":["trace[187431853] 'process raft request' (duration: 137.123657ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.979173Z","caller":"traceutil/trace.go:171","msg":"trace[1886276503] transaction","detail":"{read_only:false; response_revision:15494; number_of_response:1; }","duration":"129.888703ms","start":"2026-10-15T14:48:00.849269Z","end":"2026-10-15T14:48:00.979158Z","steps":["trace[1886276503] 'process raft request' (duration: 129.74918ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.979166Z","caller":"traceutil/trace.go:171","msg":"trace[477831823] transaction","detail":"{read_only:false; response_revision:15493; number_of_response:1; }","duration":"129.892232ms","start":"2026-10-15T14:48:00.849245Z","end":"2026-10-15T14:48:00.979138Z","steps":["trace[477831823] 'process raft request' (duration: 129.735539ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.979299Z","caller":"traceutil/trace.go:171","msg":"trace[1663858963] transaction","detail":"{read_only:false; response_revision:15497; number_of_response:1; }","duration":"125.192582ms","start":"2026-10-15T14:48:00.854097Z","end":"2026-10-15T14:48:00.979289Z","steps":["trace[1663858963] 'process raft request' (duration: 125.003848ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.979331Z","caller":"traceutil/trace.go:171","msg":"trace[957738104] transaction","detail":"{read_only:false; response_revision:15492; number_of_response:1; }","duration":"130.478695ms","start":"2026-10-15T14:48:00.848842Z","end":"2026-10-15T14:48:00.979321Z","steps":["trace[957738104] 'process raft request' (duration: 130.075786ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.979415Z","caller":"traceutil/trace.go:171","msg":"trace[349713927] transaction","detail":"{read_only:false; response_revision:15496; number_of_response:1; }","duration":"127.727527ms","start":"2026-10-15T14:48:00.851675Z","end":"2026-10-15T14:48:00.979403Z","steps":["trace[349713927] 'process raft request' (duration: 127.4031ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:00.97948Z","caller":"traceutil/trace.go:171","msg":"trace[146712677] transaction","detail":"{read_only:false; response_revision:15495; number_of_response:1; }","duration":"129.186348ms","start":"2026-10-15T14:48:00.850271Z","end":"2026-10-15T14:48:00.979458Z","steps":["trace[146712677] 'process raft request' (duration: 128.77794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:00.979534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.785664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111932"} {"level":"info","ts":"2026-10-15T14:48:00.979559Z","caller":"traceutil/trace.go:171","msg":"trace[728103438] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:15497; }","duration":"138.846694ms","start":"2026-10-15T14:48:00.840705Z","end":"2026-10-15T14:48:00.979552Z","steps":["trace[728103438] 'agreement among raft nodes before linearized reading' (duration: 138.66231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:00.97959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.894994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-15T14:48:00.979621Z","caller":"traceutil/trace.go:171","msg":"trace[1600019419] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:15497; }","duration":"138.958926ms","start":"2026-10-15T14:48:00.840655Z","end":"2026-10-15T14:48:00.979613Z","steps":["trace[1600019419] 'agreement among raft nodes before linearized reading' (duration: 138.853225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:00.979744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.046918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-api-osapi-5f75c45bc-t8vx6\" ","response":"range_response_count:1 size:6884"} {"level":"info","ts":"2026-10-15T14:48:00.979783Z","caller":"traceutil/trace.go:171","msg":"trace[455722393] range","detail":"{range_begin:/registry/pods/openstack/nova-api-osapi-5f75c45bc-t8vx6; range_end:; response_count:1; response_revision:15497; }","duration":"139.113569ms","start":"2026-10-15T14:48:00.840657Z","end":"2026-10-15T14:48:00.979771Z","steps":["trace[455722393] 'agreement among raft nodes before linearized reading' (duration: 138.940666ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:02.846638Z","caller":"traceutil/trace.go:171","msg":"trace[1744750355] linearizableReadLoop","detail":"{readStateIndex:16089; appliedIndex:16086; }","duration":"105.492713ms","start":"2026-10-15T14:48:02.741126Z","end":"2026-10-15T14:48:02.846618Z","steps":["trace[1744750355] 'read index received' (duration: 56.943616ms)","trace[1744750355] 'applied index is now lower than readState.Index' (duration: 48.547967ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:48:02.846855Z","caller":"traceutil/trace.go:171","msg":"trace[1601839162] transaction","detail":"{read_only:false; response_revision:15549; number_of_response:1; }","duration":"107.372292ms","start":"2026-10-15T14:48:02.739472Z","end":"2026-10-15T14:48:02.846844Z","steps":["trace[1601839162] 'process raft request' (duration: 106.958604ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:02.846995Z","caller":"traceutil/trace.go:171","msg":"trace[55822204] transaction","detail":"{read_only:false; response_revision:15550; number_of_response:1; }","duration":"107.255889ms","start":"2026-10-15T14:48:02.739732Z","end":"2026-10-15T14:48:02.846988Z","steps":["trace[55822204] 'process raft request' (duration: 106.82041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:02.847247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.106645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-15T14:48:02.847278Z","caller":"traceutil/trace.go:171","msg":"trace[1443782432] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:15551; }","duration":"106.146786ms","start":"2026-10-15T14:48:02.741122Z","end":"2026-10-15T14:48:02.847269Z","steps":["trace[1443782432] 'agreement among raft nodes before linearized reading' (duration: 106.049214ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:48:16.141624Z","caller":"traceutil/trace.go:171","msg":"trace[1377755857] linearizableReadLoop","detail":"{readStateIndex:16258; appliedIndex:16257; }","duration":"101.191931ms","start":"2026-10-15T14:48:16.040413Z","end":"2026-10-15T14:48:16.141605Z","steps":["trace[1377755857] 'read index received' (duration: 77.76766ms)","trace[1377755857] 'applied index is now lower than readState.Index' (duration: 23.423141ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:48:16.141746Z","caller":"traceutil/trace.go:171","msg":"trace[644575622] transaction","detail":"{read_only:false; response_revision:15714; number_of_response:1; }","duration":"168.402204ms","start":"2026-10-15T14:48:15.973331Z","end":"2026-10-15T14:48:16.141733Z","steps":["trace[644575622] 'process raft request' (duration: 144.940044ms)","trace[644575622] 'compare' (duration: 23.237435ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:48:16.141939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.518548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dbtwb\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-10-15T14:48:16.141998Z","caller":"traceutil/trace.go:171","msg":"trace[1926986174] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dbtwb; range_end:; response_count:1; response_revision:15714; }","duration":"101.625531ms","start":"2026-10-15T14:48:16.040362Z","end":"2026-10-15T14:48:16.141988Z","steps":["trace[1926986174] 'agreement among raft nodes before linearized reading' (duration: 101.482177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:35.9076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.255957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T14:48:35.907729Z","caller":"traceutil/trace.go:171","msg":"trace[1207953066] linearizableReadLoop","detail":"{readStateIndex:16367; appliedIndex:16366; }","duration":"259.356546ms","start":"2026-10-15T14:48:35.648355Z","end":"2026-10-15T14:48:35.907712Z","steps":["trace[1207953066] 'read index received' (duration: 100.833213ms)","trace[1207953066] 'applied index is now lower than readState.Index' (duration: 158.522062ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T14:48:35.907801Z","caller":"traceutil/trace.go:171","msg":"trace[625413039] transaction","detail":"{read_only:false; response_revision:15819; number_of_response:1; }","duration":"260.349186ms","start":"2026-10-15T14:48:35.647427Z","end":"2026-10-15T14:48:35.907777Z","steps":["trace[625413039] 'process raft request' (duration: 101.818983ms)","trace[625413039] 'compare' (duration: 157.944281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:48:35.908133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.781575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-15T14:48:35.908171Z","caller":"traceutil/trace.go:171","msg":"trace[201647764] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:15819; }","duration":"259.861037ms","start":"2026-10-15T14:48:35.6483Z","end":"2026-10-15T14:48:35.908161Z","steps":["trace[201647764] 'agreement among raft nodes before linearized reading' (duration: 259.706403ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:35.908321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.290964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kkwjg\" ","response":"range_response_count:1 size:20656"} {"level":"info","ts":"2026-10-15T14:48:35.90837Z","caller":"traceutil/trace.go:171","msg":"trace[1193404246] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-kkwjg; range_end:; response_count:1; response_revision:15819; }","duration":"221.361015ms","start":"2026-10-15T14:48:35.686996Z","end":"2026-10-15T14:48:35.908357Z","steps":["trace[1193404246] 'agreement among raft nodes before linearized reading' (duration: 221.221101ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:48:35.908607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.067994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cjwzf\" ","response":"range_response_count:1 size:9798"} {"level":"info","ts":"2026-10-15T14:48:35.908699Z","caller":"traceutil/trace.go:171","msg":"trace[1761123139] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cjwzf; range_end:; response_count:1; response_revision:15819; }","duration":"165.215796ms","start":"2026-10-15T14:48:35.743452Z","end":"2026-10-15T14:48:35.908668Z","steps":["trace[1761123139] 'agreement among raft nodes before linearized reading' (duration: 164.990831ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:51:32.215757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14606} {"level":"info","ts":"2026-10-15T14:51:32.298986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14606,"took":"80.16626ms","hash":3184818714,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":31952896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T14:51:32.299262Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3184818714,"revision":14606,"compact-revision":12583} {"level":"info","ts":"2026-10-15T14:51:50.422723Z","caller":"traceutil/trace.go:171","msg":"trace[337322042] transaction","detail":"{read_only:false; response_revision:17356; number_of_response:1; }","duration":"106.211978ms","start":"2026-10-15T14:51:50.316485Z","end":"2026-10-15T14:51:50.422697Z","steps":["trace[337322042] 'process raft request' (duration: 106.047553ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:54:37.281657Z","caller":"traceutil/trace.go:171","msg":"trace[1928299326] transaction","detail":"{read_only:false; response_revision:18478; number_of_response:1; }","duration":"199.698325ms","start":"2026-10-15T14:54:37.081934Z","end":"2026-10-15T14:54:37.281632Z","steps":["trace[1928299326] 'process raft request' (duration: 126.018821ms)","trace[1928299326] 'compare' (duration: 73.570332ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:54:37.671073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.270826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T14:54:37.671178Z","caller":"traceutil/trace.go:171","msg":"trace[57427105] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:18479; }","duration":"236.425299ms","start":"2026-10-15T14:54:37.434736Z","end":"2026-10-15T14:54:37.671161Z","steps":["trace[57427105] 'count revisions from in-memory index tree' (duration: 236.190464ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:54:37.88217Z","caller":"traceutil/trace.go:171","msg":"trace[600249222] transaction","detail":"{read_only:false; response_revision:18480; number_of_response:1; }","duration":"430.460393ms","start":"2026-10-15T14:54:37.451688Z","end":"2026-10-15T14:54:37.882148Z","steps":["trace[600249222] 'process raft request' (duration: 430.273069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:54:37.882329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:54:37.451656Z","time spent":"430.580934ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T14:54:37.9027Z","caller":"traceutil/trace.go:171","msg":"trace[1543275045] transaction","detail":"{read_only:false; response_revision:18481; number_of_response:1; }","duration":"344.739672ms","start":"2026-10-15T14:54:37.557937Z","end":"2026-10-15T14:54:37.902676Z","steps":["trace[1543275045] 'process raft request' (duration: 344.528467ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:54:37.902859Z","caller":"traceutil/trace.go:171","msg":"trace[1001858173] linearizableReadLoop","detail":"{readStateIndex:19115; appliedIndex:19113; }","duration":"285.565218ms","start":"2026-10-15T14:54:37.617267Z","end":"2026-10-15T14:54:37.902832Z","steps":["trace[1001858173] 'read index received' (duration: 264.761073ms)","trace[1001858173] 'applied index is now lower than readState.Index' (duration: 20.802895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T14:54:37.902868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T14:54:37.557898Z","time spent":"344.884314ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T14:54:37.903002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.739232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T14:54:37.903034Z","caller":"traceutil/trace.go:171","msg":"trace[1091272769] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:18482; }","duration":"285.817674ms","start":"2026-10-15T14:54:37.617206Z","end":"2026-10-15T14:54:37.903023Z","steps":["trace[1091272769] 'agreement among raft nodes before linearized reading' (duration: 285.726452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T14:54:37.903345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.821082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T14:54:37.903374Z","caller":"traceutil/trace.go:171","msg":"trace[715691577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18482; }","duration":"114.888463ms","start":"2026-10-15T14:54:37.788476Z","end":"2026-10-15T14:54:37.903365Z","steps":["trace[715691577] 'agreement among raft nodes before linearized reading' (duration: 114.781251ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:55:56.250868Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd765eb5fac2932d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T14:55:56.257584Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T14:55:56.257794Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T14:56:32.22448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17057} {"level":"info","ts":"2026-10-15T14:56:32.330042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17057,"took":"101.145319ms","hash":3153526226,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":34258944,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T14:56:32.330115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3153526226,"revision":17057,"compact-revision":14606} {"level":"info","ts":"2026-10-15T14:59:52.674896Z","caller":"traceutil/trace.go:171","msg":"trace[713368186] transaction","detail":"{read_only:false; response_revision:20973; number_of_response:1; }","duration":"117.798565ms","start":"2026-10-15T14:59:52.557065Z","end":"2026-10-15T14:59:52.674863Z","steps":["trace[713368186] 'process raft request' (duration: 117.576161ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:59:58.20775Z","caller":"traceutil/trace.go:171","msg":"trace[569562357] transaction","detail":"{read_only:false; response_revision:21000; number_of_response:1; }","duration":"180.516244ms","start":"2026-10-15T14:59:58.027205Z","end":"2026-10-15T14:59:58.207721Z","steps":["trace[569562357] 'process raft request' (duration: 180.233379ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T14:59:58.317979Z","caller":"traceutil/trace.go:171","msg":"trace[1867461201] transaction","detail":"{read_only:false; response_revision:21002; number_of_response:1; }","duration":"100.362303ms","start":"2026-10-15T14:59:58.217592Z","end":"2026-10-15T14:59:58.317954Z","steps":["trace[1867461201] 'process raft request' (duration: 74.099923ms)","trace[1867461201] 'compare' (duration: 26.079797ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:00:03.657246Z","caller":"traceutil/trace.go:171","msg":"trace[246936883] transaction","detail":"{read_only:false; response_revision:21074; number_of_response:1; }","duration":"300.288382ms","start":"2026-10-15T15:00:03.356922Z","end":"2026-10-15T15:00:03.65721Z","steps":["trace[246936883] 'process raft request' (duration: 300.093078ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:03.657464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:03.356902Z","time spent":"300.459326ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:00:04.236399Z","caller":"traceutil/trace.go:171","msg":"trace[499546384] transaction","detail":"{read_only:false; response_revision:21075; number_of_response:1; }","duration":"561.253225ms","start":"2026-10-15T15:00:03.675073Z","end":"2026-10-15T15:00:04.236327Z","steps":["trace[499546384] 'process raft request' (duration: 462.832574ms)","trace[499546384] 'compare' (duration: 98.117275ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:00:04.236627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:03.675056Z","time spent":"561.454549ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:00:04.628607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.249323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:00:04.628708Z","caller":"traceutil/trace.go:171","msg":"trace[606393977] linearizableReadLoop","detail":"{readStateIndex:21793; appliedIndex:21791; }","duration":"731.827947ms","start":"2026-10-15T15:00:03.896862Z","end":"2026-10-15T15:00:04.62869Z","steps":["trace[606393977] 'read index received' (duration: 240.973795ms)","trace[606393977] 'applied index is now lower than readState.Index' (duration: 490.852962ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:00:04.628812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.948309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:00:04.628843Z","caller":"traceutil/trace.go:171","msg":"trace[1110658557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21076; }","duration":"732.020592ms","start":"2026-10-15T15:00:03.896813Z","end":"2026-10-15T15:00:04.628834Z","steps":["trace[1110658557] 'agreement among raft nodes before linearized reading' (duration: 731.95061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.628871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:03.896787Z","time spent":"732.077653ms","remote":"127.0.0.1:53324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T15:00:04.62893Z","caller":"traceutil/trace.go:171","msg":"trace[1534849294] transaction","detail":"{read_only:false; response_revision:21076; number_of_response:1; }","duration":"951.387996ms","start":"2026-10-15T15:00:03.67731Z","end":"2026-10-15T15:00:04.628698Z","steps":["trace[1534849294] 'process raft request' (duration: 737.947848ms)","trace[1534849294] 'compare' (duration: 212.59525ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:00:04.629055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:03.677298Z","time spent":"951.701962ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T15:00:04.636796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.097894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:00:04.636851Z","caller":"traceutil/trace.go:171","msg":"trace[1162914523] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21077; }","duration":"393.196236ms","start":"2026-10-15T15:00:04.24364Z","end":"2026-10-15T15:00:04.636837Z","steps":["trace[1162914523] 'agreement among raft nodes before linearized reading' (duration: 393.106884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.636882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:04.243621Z","time spent":"393.251668ms","remote":"127.0.0.1:36458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:00:04.63691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.348593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-15T15:00:04.636986Z","caller":"traceutil/trace.go:171","msg":"trace[1078965316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21077; }","duration":"374.453936ms","start":"2026-10-15T15:00:04.262511Z","end":"2026-10-15T15:00:04.636965Z","steps":["trace[1078965316] 'agreement among raft nodes before linearized reading' (duration: 374.234701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.636981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.594861ms","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":"warn","ts":"2026-10-15T15:00:04.637026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:04.2625Z","time spent":"374.517237ms","remote":"127.0.0.1:35434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T15:00:04.637047Z","caller":"traceutil/trace.go:171","msg":"trace[1969439979] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:21077; }","duration":"457.754574ms","start":"2026-10-15T15:00:04.179277Z","end":"2026-10-15T15:00:04.637032Z","steps":["trace[1969439979] 'agreement among raft nodes before linearized reading' (duration: 457.640261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.637099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:04.179247Z","time spent":"457.838517ms","remote":"127.0.0.1:52042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true "} {"level":"warn","ts":"2026-10-15T15:00:04.63711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.176597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-15T15:00:04.637134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.202744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-15T15:00:04.637184Z","caller":"traceutil/trace.go:171","msg":"trace[1527518195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21077; }","duration":"297.289519ms","start":"2026-10-15T15:00:04.339877Z","end":"2026-10-15T15:00:04.637167Z","steps":["trace[1527518195] 'agreement among raft nodes before linearized reading' (duration: 297.082084ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:00:04.637191Z","caller":"traceutil/trace.go:171","msg":"trace[832583223] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21077; }","duration":"480.296906ms","start":"2026-10-15T15:00:04.156878Z","end":"2026-10-15T15:00:04.637175Z","steps":["trace[832583223] 'agreement among raft nodes before linearized reading' (duration: 480.127343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.637228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:04.156855Z","time spent":"480.365577ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-15T15:00:04.636909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.240464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-846bd7f8f9-ttw8n.18debb0ee29ebe65\" ","response":"range_response_count:1 size:1178"} {"level":"info","ts":"2026-10-15T15:00:04.637407Z","caller":"traceutil/trace.go:171","msg":"trace[1757025404] range","detail":"{range_begin:/registry/events/openstack/cinder-api-846bd7f8f9-ttw8n.18debb0ee29ebe65; range_end:; response_count:1; response_revision:21077; }","duration":"287.694484ms","start":"2026-10-15T15:00:04.349601Z","end":"2026-10-15T15:00:04.637296Z","steps":["trace[1757025404] 'agreement among raft nodes before linearized reading' (duration: 287.185743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.637328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.60772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-15T15:00:04.637561Z","caller":"traceutil/trace.go:171","msg":"trace[1484681913] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:21077; }","duration":"640.924357ms","start":"2026-10-15T15:00:03.996609Z","end":"2026-10-15T15:00:04.637533Z","steps":["trace[1484681913] 'agreement among raft nodes before linearized reading' (duration: 640.252803ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.637625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:00:03.996593Z","time spent":"641.017977ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11859,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"info","ts":"2026-10-15T15:00:04.903659Z","caller":"traceutil/trace.go:171","msg":"trace[2130430676] transaction","detail":"{read_only:false; response_revision:21078; number_of_response:1; }","duration":"263.338913ms","start":"2026-10-15T15:00:04.640299Z","end":"2026-10-15T15:00:04.903638Z","steps":["trace[2130430676] 'process raft request' (duration: 254.549185ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:00:04.903641Z","caller":"traceutil/trace.go:171","msg":"trace[2015993225] linearizableReadLoop","detail":"{readStateIndex:21795; appliedIndex:21794; }","duration":"262.32115ms","start":"2026-10-15T15:00:04.641289Z","end":"2026-10-15T15:00:04.90361Z","steps":["trace[2015993225] 'read index received' (duration: 253.666575ms)","trace[2015993225] 'applied index is now lower than readState.Index' (duration: 8.653465ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:00:04.903847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.536595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-15T15:00:04.903908Z","caller":"traceutil/trace.go:171","msg":"trace[981205523] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21078; }","duration":"262.613147ms","start":"2026-10-15T15:00:04.64128Z","end":"2026-10-15T15:00:04.903893Z","steps":["trace[981205523] 'agreement among raft nodes before linearized reading' (duration: 262.419193ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:00:04.904158Z","caller":"traceutil/trace.go:171","msg":"trace[747533888] transaction","detail":"{read_only:false; response_revision:21079; number_of_response:1; }","duration":"262.592666ms","start":"2026-10-15T15:00:04.641535Z","end":"2026-10-15T15:00:04.904127Z","steps":["trace[747533888] 'process raft request' (duration: 262.346041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.904436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.99528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-10-15T15:00:04.904394Z","caller":"traceutil/trace.go:171","msg":"trace[1873732600] transaction","detail":"{read_only:false; response_revision:21080; number_of_response:1; }","duration":"262.745321ms","start":"2026-10-15T15:00:04.641587Z","end":"2026-10-15T15:00:04.904333Z","steps":["trace[1873732600] 'process raft request' (duration: 262.478295ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:00:04.904489Z","caller":"traceutil/trace.go:171","msg":"trace[314691382] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:21081; }","duration":"259.072322ms","start":"2026-10-15T15:00:04.645401Z","end":"2026-10-15T15:00:04.904473Z","steps":["trace[314691382] 'agreement among raft nodes before linearized reading' (duration: 258.930819ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:00:04.904621Z","caller":"traceutil/trace.go:171","msg":"trace[1480275054] transaction","detail":"{read_only:false; response_revision:21081; number_of_response:1; }","duration":"180.140706ms","start":"2026-10-15T15:00:04.724462Z","end":"2026-10-15T15:00:04.904602Z","steps":["trace[1480275054] 'process raft request' (duration: 179.773589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.904619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.127169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T15:00:04.904705Z","caller":"traceutil/trace.go:171","msg":"trace[525998821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21081; }","duration":"168.270994ms","start":"2026-10-15T15:00:04.736413Z","end":"2026-10-15T15:00:04.904684Z","steps":["trace[525998821] 'agreement among raft nodes before linearized reading' (duration: 167.993957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.90477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.44912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:00:04.904815Z","caller":"traceutil/trace.go:171","msg":"trace[850258516] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:21081; }","duration":"229.520061ms","start":"2026-10-15T15:00:04.67528Z","end":"2026-10-15T15:00:04.9048Z","steps":["trace[850258516] 'agreement among raft nodes before linearized reading' (duration: 229.427869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:00:04.905128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.776333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-15T15:00:04.905193Z","caller":"traceutil/trace.go:171","msg":"trace[368681457] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21081; }","duration":"138.910166ms","start":"2026-10-15T15:00:04.766263Z","end":"2026-10-15T15:00:04.905173Z","steps":["trace[368681457] 'agreement among raft nodes before linearized reading' (duration: 138.680631ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:01:32.231575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19607} {"level":"info","ts":"2026-10-15T15:01:32.35284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19607,"took":"117.954349ms","hash":2309500286,"current-db-size-bytes":56651776,"current-db-size":"57 MB","current-db-size-in-use-bytes":30216192,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:01:32.352905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2309500286,"revision":19607,"compact-revision":17057} {"level":"info","ts":"2026-10-15T15:04:04.773759Z","caller":"traceutil/trace.go:171","msg":"trace[170071282] linearizableReadLoop","detail":"{readStateIndex:24374; appliedIndex:24373; }","duration":"262.658852ms","start":"2026-10-15T15:04:04.511071Z","end":"2026-10-15T15:04:04.77373Z","steps":["trace[170071282] 'read index received' (duration: 262.459988ms)","trace[170071282] 'applied index is now lower than readState.Index' (duration: 198.144µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:04:04.774147Z","caller":"traceutil/trace.go:171","msg":"trace[2116867933] transaction","detail":"{read_only:false; response_revision:23584; number_of_response:1; }","duration":"305.995697ms","start":"2026-10-15T15:04:04.468106Z","end":"2026-10-15T15:04:04.774102Z","steps":["trace[2116867933] 'process raft request' (duration: 305.494616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:04.77421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.133998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:70568"} {"level":"warn","ts":"2026-10-15T15:04:04.774183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.310989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T15:04:04.774283Z","caller":"traceutil/trace.go:171","msg":"trace[210171905] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23584; }","duration":"232.24228ms","start":"2026-10-15T15:04:04.54202Z","end":"2026-10-15T15:04:04.774262Z","steps":["trace[210171905] 'agreement among raft nodes before linearized reading' (duration: 232.031936ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:04:04.774313Z","caller":"traceutil/trace.go:171","msg":"trace[622777091] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23584; }","duration":"112.502783ms","start":"2026-10-15T15:04:04.661787Z","end":"2026-10-15T15:04:04.77429Z","steps":["trace[622777091] 'agreement among raft nodes before linearized reading' (duration: 112.264058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:04:04.774209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.111251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-10-15T15:04:04.774359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T15:04:04.468064Z","time spent":"306.170081ms","remote":"127.0.0.1:53360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10698,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T15:04:04.77439Z","caller":"traceutil/trace.go:171","msg":"trace[797317715] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:23584; }","duration":"263.339455ms","start":"2026-10-15T15:04:04.511032Z","end":"2026-10-15T15:04:04.774371Z","steps":["trace[797317715] 'agreement among raft nodes before linearized reading' (duration: 262.993427ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:06:32.243856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21873} {"level":"info","ts":"2026-10-15T15:06:32.381952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21873,"took":"131.615281ms","hash":4028555435,"current-db-size-bytes":57495552,"current-db-size":"58 MB","current-db-size-in-use-bytes":45133824,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-15T15:06:32.38207Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4028555435,"revision":21873,"compact-revision":19607} {"level":"info","ts":"2026-10-15T15:07:16.238697Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006714.wal"} {"level":"info","ts":"2026-10-15T15:07:26.081135Z","caller":"traceutil/trace.go:171","msg":"trace[1882915369] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"101.291454ms","start":"2026-10-15T15:07:25.979814Z","end":"2026-10-15T15:07:26.081106Z","steps":["trace[1882915369] 'process raft request' (duration: 101.143512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:07:26.367923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.651129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:07:26.36802Z","caller":"traceutil/trace.go:171","msg":"trace[1621751335] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25630; }","duration":"121.802322ms","start":"2026-10-15T15:07:26.246195Z","end":"2026-10-15T15:07:26.367997Z","steps":["trace[1621751335] 'count revisions from in-memory index tree' (duration: 121.564007ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:26.761904Z","caller":"traceutil/trace.go:171","msg":"trace[773331681] linearizableReadLoop","detail":"{readStateIndex:26475; appliedIndex:26474; }","duration":"238.417792ms","start":"2026-10-15T15:07:26.523458Z","end":"2026-10-15T15:07:26.761876Z","steps":["trace[773331681] 'read index received' (duration: 222.639135ms)","trace[773331681] 'applied index is now lower than readState.Index' (duration: 15.777587ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:07:26.761952Z","caller":"traceutil/trace.go:171","msg":"trace[308786497] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"272.652425ms","start":"2026-10-15T15:07:26.489269Z","end":"2026-10-15T15:07:26.761921Z","steps":["trace[308786497] 'process raft request' (duration: 256.754735ms)","trace[308786497] 'compare' (duration: 15.732275ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:07:26.762093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.621137ms","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-15T15:07:26.762131Z","caller":"traceutil/trace.go:171","msg":"trace[2079545097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25632; }","duration":"238.706049ms","start":"2026-10-15T15:07:26.523415Z","end":"2026-10-15T15:07:26.762121Z","steps":["trace[2079545097] 'agreement among raft nodes before linearized reading' (duration: 238.558765ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:26.786788Z","caller":"traceutil/trace.go:171","msg":"trace[1604373043] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"174.527819ms","start":"2026-10-15T15:07:26.612227Z","end":"2026-10-15T15:07:26.786755Z","steps":["trace[1604373043] 'process raft request' (duration: 174.1258ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:26.786934Z","caller":"traceutil/trace.go:171","msg":"trace[594184307] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"156.132586ms","start":"2026-10-15T15:07:26.630748Z","end":"2026-10-15T15:07:26.786881Z","steps":["trace[594184307] 'process raft request' (duration: 155.847219ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:07:26.896115Z","caller":"traceutil/trace.go:171","msg":"trace[805880978] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"129.054967ms","start":"2026-10-15T15:07:26.767037Z","end":"2026-10-15T15:07:26.896092Z","steps":["trace[805880978] 'process raft request' (duration: 128.922683ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:21.087117Z","caller":"traceutil/trace.go:171","msg":"trace[1466984326] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"105.505553ms","start":"2026-10-15T15:08:20.981587Z","end":"2026-10-15T15:08:21.087092Z","steps":["trace[1466984326] 'process raft request' (duration: 102.11009ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:27.198744Z","caller":"traceutil/trace.go:171","msg":"trace[2000662880] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"216.626577ms","start":"2026-10-15T15:08:26.982075Z","end":"2026-10-15T15:08:27.198701Z","steps":["trace[2000662880] 'process raft request' (duration: 216.323881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:08:27.394915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.451392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T15:08:27.395165Z","caller":"traceutil/trace.go:171","msg":"trace[1061333052] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"289.936074ms","start":"2026-10-15T15:08:27.105192Z","end":"2026-10-15T15:08:27.395128Z","steps":["trace[1061333052] 'process raft request' (duration: 155.185565ms)","trace[1061333052] 'compare' (duration: 134.30403ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:08:27.39521Z","caller":"traceutil/trace.go:171","msg":"trace[853353045] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"267.876573ms","start":"2026-10-15T15:08:27.12731Z","end":"2026-10-15T15:08:27.395186Z","steps":["trace[853353045] 'process raft request' (duration: 267.766501ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:27.395256Z","caller":"traceutil/trace.go:171","msg":"trace[1059480276] linearizableReadLoop","detail":"{readStateIndex:27034; appliedIndex:27031; }","duration":"192.312138ms","start":"2026-10-15T15:08:27.202935Z","end":"2026-10-15T15:08:27.395247Z","steps":["trace[1059480276] 'read index received' (duration: 57.468348ms)","trace[1059480276] 'applied index is now lower than readState.Index' (duration: 134.84292ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:08:27.395217Z","caller":"traceutil/trace.go:171","msg":"trace[79124066] transaction","detail":"{read_only:false; response_revision:26179; number_of_response:1; }","duration":"155.583063ms","start":"2026-10-15T15:08:27.239616Z","end":"2026-10-15T15:08:27.395199Z","steps":["trace[79124066] 'process raft request' (duration: 155.522922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:08:27.395426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.783009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T15:08:27.39546Z","caller":"traceutil/trace.go:171","msg":"trace[770405799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26179; }","duration":"186.843361ms","start":"2026-10-15T15:08:27.208608Z","end":"2026-10-15T15:08:27.395451Z","steps":["trace[770405799] 'agreement among raft nodes before linearized reading' (duration: 186.720159ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:27.395483Z","caller":"traceutil/trace.go:171","msg":"trace[958453956] transaction","detail":"{read_only:false; response_revision:26177; number_of_response:1; }","duration":"272.610713ms","start":"2026-10-15T15:08:27.122841Z","end":"2026-10-15T15:08:27.395451Z","steps":["trace[958453956] 'process raft request' (duration: 272.176004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:08:27.395887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.929272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-15T15:08:27.395952Z","caller":"traceutil/trace.go:171","msg":"trace[529912169] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26179; }","duration":"193.041115ms","start":"2026-10-15T15:08:27.202893Z","end":"2026-10-15T15:08:27.395935Z","steps":["trace[529912169] 'agreement among raft nodes before linearized reading' (duration: 192.409122ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:27.604029Z","caller":"traceutil/trace.go:171","msg":"trace[2038685746] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"203.237752ms","start":"2026-10-15T15:08:27.400762Z","end":"2026-10-15T15:08:27.603999Z","steps":["trace[2038685746] 'process raft request' (duration: 203.084639ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:08:27.605282Z","caller":"traceutil/trace.go:171","msg":"trace[1783470441] linearizableReadLoop","detail":"{readStateIndex:27037; appliedIndex:27035; }","duration":"132.804317ms","start":"2026-10-15T15:08:27.472453Z","end":"2026-10-15T15:08:27.605257Z","steps":["trace[1783470441] 'read index received' (duration: 131.462389ms)","trace[1783470441] 'applied index is now lower than readState.Index' (duration: 1.340668ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:08:27.605401Z","caller":"traceutil/trace.go:171","msg":"trace[1934081156] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"204.279475ms","start":"2026-10-15T15:08:27.401112Z","end":"2026-10-15T15:08:27.605391Z","steps":["trace[1934081156] 'process raft request' (duration: 203.975748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:08:27.605582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.839661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T15:08:27.60567Z","caller":"traceutil/trace.go:171","msg":"trace[588154194] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26181; }","duration":"112.979792ms","start":"2026-10-15T15:08:27.49267Z","end":"2026-10-15T15:08:27.60565Z","steps":["trace[588154194] 'agreement among raft nodes before linearized reading' (duration: 112.803639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T15:08:27.60572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.246887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T15:08:27.605769Z","caller":"traceutil/trace.go:171","msg":"trace[34864964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26181; }","duration":"133.347939ms","start":"2026-10-15T15:08:27.472408Z","end":"2026-10-15T15:08:27.605756Z","steps":["trace[34864964] 'agreement among raft nodes before linearized reading' (duration: 133.185986ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:11:32.256113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25132} {"level":"info","ts":"2026-10-15T15:11:32.413828Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25132,"took":"145.270794ms","hash":679684366,"current-db-size-bytes":59183104,"current-db-size":"59 MB","current-db-size-in-use-bytes":38043648,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-15T15:11:32.41391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":679684366,"revision":25132,"compact-revision":21873} {"level":"info","ts":"2026-10-15T15:13:31.232115Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd765eb5fac2932d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:13:31.237807Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T15:13:31.237906Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T15:16:32.26271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28044} {"level":"info","ts":"2026-10-15T15:16:32.406861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28044,"took":"138.836757ms","hash":2441429050,"current-db-size-bytes":59183104,"current-db-size":"59 MB","current-db-size-in-use-bytes":32317440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:16:32.406936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2441429050,"revision":28044,"compact-revision":25132} {"level":"info","ts":"2026-10-15T15:21:32.283716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30431} {"level":"info","ts":"2026-10-15T15:21:32.482833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30431,"took":"195.19192ms","hash":2280064702,"current-db-size-bytes":59183104,"current-db-size":"59 MB","current-db-size-in-use-bytes":30494720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:21:32.482979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2280064702,"revision":30431,"compact-revision":28044} {"level":"info","ts":"2026-10-15T15:25:32.498933Z","caller":"traceutil/trace.go:171","msg":"trace[1459866269] transaction","detail":"{read_only:false; response_revision:34472; number_of_response:1; }","duration":"120.926555ms","start":"2026-10-15T15:25:32.377984Z","end":"2026-10-15T15:25:32.498911Z","steps":["trace[1459866269] 'process raft request' (duration: 118.079563ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:26:32.290884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32683} {"level":"info","ts":"2026-10-15T15:26:32.463758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32683,"took":"168.275746ms","hash":490040602,"current-db-size-bytes":60227584,"current-db-size":"60 MB","current-db-size-in-use-bytes":30150656,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:26:32.463823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":490040602,"revision":32683,"compact-revision":30431} {"level":"info","ts":"2026-10-15T15:27:03.738859Z","caller":"traceutil/trace.go:171","msg":"trace[1437874838] linearizableReadLoop","detail":"{readStateIndex:36238; appliedIndex:36236; }","duration":"152.61001ms","start":"2026-10-15T15:27:03.586217Z","end":"2026-10-15T15:27:03.738827Z","steps":["trace[1437874838] 'read index received' (duration: 24.950354ms)","trace[1437874838] 'applied index is now lower than readState.Index' (duration: 127.658506ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T15:27:03.739008Z","caller":"traceutil/trace.go:171","msg":"trace[616699756] transaction","detail":"{read_only:false; response_revision:35134; number_of_response:1; }","duration":"154.157164ms","start":"2026-10-15T15:27:03.584826Z","end":"2026-10-15T15:27:03.738983Z","steps":["trace[616699756] 'process raft request' (duration: 77.012006ms)","trace[616699756] 'compare' (duration: 76.873982ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T15:27:03.739154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.925977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-15T15:27:03.739209Z","caller":"traceutil/trace.go:171","msg":"trace[1010644103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:35134; }","duration":"153.03391ms","start":"2026-10-15T15:27:03.58616Z","end":"2026-10-15T15:27:03.739194Z","steps":["trace[1010644103] 'agreement among raft nodes before linearized reading' (duration: 152.845876ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T15:31:32.297813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34910} {"level":"info","ts":"2026-10-15T15:31:32.422743Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34910,"took":"120.815582ms","hash":3300444413,"current-db-size-bytes":60227584,"current-db-size":"60 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:31:32.422794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3300444413,"revision":34910,"compact-revision":32683} {"level":"info","ts":"2026-10-15T15:35:02.21762Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd765eb5fac2932d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T15:35:02.223382Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T15:35:02.223507Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T15:36:32.305194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37203} {"level":"info","ts":"2026-10-15T15:36:32.43515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37203,"took":"125.697494ms","hash":3010845405,"current-db-size-bytes":60227584,"current-db-size":"60 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T15:36:32.435223Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3010845405,"revision":37203,"compact-revision":34910} {"level":"info","ts":"2026-10-15T15:41:32.312389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39479} {"level":"info","ts":"2026-10-15T15:41:32.456256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39479,"took":"139.996209ms","hash":608887046,"current-db-size-bytes":60227584,"current-db-size":"60 MB","current-db-size-in-use-bytes":30253056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T15:41:32.456326Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":608887046,"revision":39479,"compact-revision":37203}