{"level":"warn","ts":"2026-09-09T06:13:48.406826Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:13:48.406931Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.127:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.127:2380","--initial-cluster=instance=https://162.253.55.127:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.127:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.127:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-09T06:13:48.407Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T06:13:48.407015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-09-09T06:13:48.407033Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T06:13:48.407821Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"]} {"level":"info","ts":"2026-09-09T06:13:48.407913Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.127:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-09T06:13:48.422519Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.38748ms"} {"level":"info","ts":"2026-09-09T06:13:48.438448Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9e5e76b85fbfd435","cluster-id":"7d97e4b11cbd31c2"} {"level":"info","ts":"2026-09-09T06:13:48.438616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T06:13:48.438645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 0"} {"level":"info","ts":"2026-09-09T06:13:48.438786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9e5e76b85fbfd435 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T06:13:48.43947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became follower at term 1"} {"level":"info","ts":"2026-09-09T06:13:48.439527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"warn","ts":"2026-09-09T06:13:48.457358Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T06:13:48.45972Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T06:13:48.461261Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-09T06:13:48.463414Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9e5e76b85fbfd435","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T06:13:48.464596Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9e5e76b85fbfd435","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T06:13:48.464939Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T06:13:48.467361Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T06:13:48.467478Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-09T06:13:48.46755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 switched to configuration voters=(11411689040055882805)"} {"level":"info","ts":"2026-09-09T06:13:48.468056Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","added-peer-id":"9e5e76b85fbfd435","added-peer-peer-urls":["https://162.253.55.127:2380"]} {"level":"info","ts":"2026-09-09T06:13:48.46988Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-09T06:13:48.470033Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-09-09T06:13:48.470108Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.127:2380"} {"level":"info","ts":"2026-09-09T06:13:48.470483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9e5e76b85fbfd435","initial-advertise-peer-urls":["https://162.253.55.127:2380"],"listen-peer-urls":["https://162.253.55.127:2380"],"advertise-client-urls":["https://162.253.55.127:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.127:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T06:13:48.470552Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T06:13:49.440329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T06:13:49.44041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T06:13:49.440449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgPreVoteResp from 9e5e76b85fbfd435 at term 1"} {"level":"info","ts":"2026-09-09T06:13:49.440487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became candidate at term 2"} {"level":"info","ts":"2026-09-09T06:13:49.440505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 received MsgVoteResp from 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-09-09T06:13:49.440529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9e5e76b85fbfd435 became leader at term 2"} {"level":"info","ts":"2026-09-09T06:13:49.440556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9e5e76b85fbfd435 elected leader 9e5e76b85fbfd435 at term 2"} {"level":"info","ts":"2026-09-09T06:13:49.441677Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:13:49.442678Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9e5e76b85fbfd435","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.127:2379]}","request-path":"/0/members/9e5e76b85fbfd435/attributes","cluster-id":"7d97e4b11cbd31c2","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T06:13:49.442718Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:13:49.443005Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T06:13:49.443319Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7d97e4b11cbd31c2","local-member-id":"9e5e76b85fbfd435","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:13:49.443465Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:13:49.443516Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T06:13:49.444276Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T06:13:49.444335Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T06:13:49.447874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T06:13:49.45098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.127:2379"} {"level":"warn","ts":"2026-09-09T06:14:27.659036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.487786ms","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-09-09T06:14:27.659256Z","caller":"traceutil/trace.go:171","msg":"trace[1636546016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"134.775146ms","start":"2026-09-09T06:14:27.524449Z","end":"2026-09-09T06:14:27.659224Z","steps":["trace[1636546016] 'range keys from in-memory index tree' (duration: 134.349116ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:29.529608Z","caller":"traceutil/trace.go:171","msg":"trace[2122823835] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"131.493354ms","start":"2026-09-09T06:14:29.398093Z","end":"2026-09-09T06:14:29.529586Z","steps":["trace[2122823835] 'process raft request' (duration: 131.075583ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:29.53553Z","caller":"traceutil/trace.go:171","msg":"trace[356732567] transaction","detail":"{read_only:false; number_of_response:1; response_revision:547; }","duration":"128.608517ms","start":"2026-09-09T06:14:29.4069Z","end":"2026-09-09T06:14:29.535508Z","steps":["trace[356732567] 'process raft request' (duration: 128.326286ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:29.7072Z","caller":"traceutil/trace.go:171","msg":"trace[1570684617] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"114.496413ms","start":"2026-09-09T06:14:29.592676Z","end":"2026-09-09T06:14:29.707173Z","steps":["trace[1570684617] 'process raft request' (duration: 109.09814ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:36.43232Z","caller":"traceutil/trace.go:171","msg":"trace[1589098224] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"182.122112ms","start":"2026-09-09T06:14:36.250164Z","end":"2026-09-09T06:14:36.432286Z","steps":["trace[1589098224] 'process raft request' (duration: 181.511438ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:14:37.099547Z","caller":"traceutil/trace.go:171","msg":"trace[1730352387] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"156.653415ms","start":"2026-09-09T06:14:36.942856Z","end":"2026-09-09T06:14:37.09951Z","steps":["trace[1730352387] 'read index received' (duration: 156.529834ms)","trace[1730352387] 'applied index is now lower than readState.Index' (duration: 121.841µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:14:37.099731Z","caller":"traceutil/trace.go:171","msg":"trace[1282630736] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"577.481292ms","start":"2026-09-09T06:14:36.522207Z","end":"2026-09-09T06:14:37.099688Z","steps":["trace[1282630736] 'process raft request' (duration: 577.122489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:37.099843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.965297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-09T06:14:37.099947Z","caller":"traceutil/trace.go:171","msg":"trace[64003971] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"157.139338ms","start":"2026-09-09T06:14:36.942786Z","end":"2026-09-09T06:14:37.099925Z","steps":["trace[64003971] 'agreement among raft nodes before linearized reading' (duration: 156.857486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:14:37.100362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:14:36.52218Z","time spent":"577.624303ms","remote":"127.0.0.1:43874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-09T06:15:25.686232Z","caller":"traceutil/trace.go:171","msg":"trace[1850568291] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"228.954711ms","start":"2026-09-09T06:15:25.45725Z","end":"2026-09-09T06:15:25.686204Z","steps":["trace[1850568291] 'process raft request' (duration: 228.787616ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:14.616165Z","caller":"traceutil/trace.go:171","msg":"trace[1626313472] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1898; }","duration":"274.084457ms","start":"2026-09-09T06:16:14.342024Z","end":"2026-09-09T06:16:14.616109Z","steps":["trace[1626313472] 'read index received' (duration: 273.830157ms)","trace[1626313472] 'applied index is now lower than readState.Index' (duration: 252.93µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:14.616213Z","caller":"traceutil/trace.go:171","msg":"trace[2011991303] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"302.814594ms","start":"2026-09-09T06:16:14.313356Z","end":"2026-09-09T06:16:14.616171Z","steps":["trace[2011991303] 'process raft request' (duration: 302.531852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:14.616467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.418992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-09-09T06:16:14.616546Z","caller":"traceutil/trace.go:171","msg":"trace[607730993] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1838; }","duration":"274.582729ms","start":"2026-09-09T06:16:14.341948Z","end":"2026-09-09T06:16:14.616531Z","steps":["trace[607730993] 'agreement among raft nodes before linearized reading' (duration: 274.340139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:14.616987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.368155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9033"} {"level":"info","ts":"2026-09-09T06:16:14.61704Z","caller":"traceutil/trace.go:171","msg":"trace[183722760] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1838; }","duration":"159.504131ms","start":"2026-09-09T06:16:14.457522Z","end":"2026-09-09T06:16:14.617026Z","steps":["trace[183722760] 'agreement among raft nodes before linearized reading' (duration: 158.953638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:14.617083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:16:14.313321Z","time spent":"303.317205ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:16:14.742766Z","caller":"traceutil/trace.go:171","msg":"trace[59117682] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"120.538603ms","start":"2026-09-09T06:16:14.622188Z","end":"2026-09-09T06:16:14.742727Z","steps":["trace[59117682] 'process raft request' (duration: 86.976158ms)","trace[59117682] 'compare' (duration: 33.321615ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:14.788142Z","caller":"traceutil/trace.go:171","msg":"trace[1341117839] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"161.061945ms","start":"2026-09-09T06:16:14.627049Z","end":"2026-09-09T06:16:14.788111Z","steps":["trace[1341117839] 'process raft request' (duration: 160.988352ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:14.788164Z","caller":"traceutil/trace.go:171","msg":"trace[787998629] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"164.617861ms","start":"2026-09-09T06:16:14.623493Z","end":"2026-09-09T06:16:14.788111Z","steps":["trace[787998629] 'process raft request' (duration: 164.366351ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:14.924285Z","caller":"traceutil/trace.go:171","msg":"trace[1841733336] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"129.477412ms","start":"2026-09-09T06:16:14.794773Z","end":"2026-09-09T06:16:14.92425Z","steps":["trace[1841733336] 'process raft request' (duration: 94.072321ms)","trace[1841733336] 'compare' (duration: 35.044546ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:14.924373Z","caller":"traceutil/trace.go:171","msg":"trace[1824939858] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1903; }","duration":"129.276334ms","start":"2026-09-09T06:16:14.795061Z","end":"2026-09-09T06:16:14.924338Z","steps":["trace[1824939858] 'read index received' (duration: 11.468843ms)","trace[1824939858] 'applied index is now lower than readState.Index' (duration: 117.806271ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:14.924456Z","caller":"traceutil/trace.go:171","msg":"trace[774365773] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"128.846526ms","start":"2026-09-09T06:16:14.795602Z","end":"2026-09-09T06:16:14.924448Z","steps":["trace[774365773] 'process raft request' (duration: 128.616385ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:14.924718Z","caller":"traceutil/trace.go:171","msg":"trace[1296353645] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"128.930709ms","start":"2026-09-09T06:16:14.795778Z","end":"2026-09-09T06:16:14.924708Z","steps":["trace[1296353645] 'process raft request' (duration: 128.502092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:14.924854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.125768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-09-09T06:16:14.92481Z","caller":"traceutil/trace.go:171","msg":"trace[515558436] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"129.539765ms","start":"2026-09-09T06:16:14.795215Z","end":"2026-09-09T06:16:14.924754Z","steps":["trace[515558436] 'process raft request' (duration: 128.881247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:14.924848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.849397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:9350"} {"level":"info","ts":"2026-09-09T06:16:14.924923Z","caller":"traceutil/trace.go:171","msg":"trace[37653052] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1846; }","duration":"129.972162ms","start":"2026-09-09T06:16:14.794934Z","end":"2026-09-09T06:16:14.924906Z","steps":["trace[37653052] 'agreement among raft nodes before linearized reading' (duration: 129.703991ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:14.924884Z","caller":"traceutil/trace.go:171","msg":"trace[1243929329] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1846; }","duration":"113.180519ms","start":"2026-09-09T06:16:14.811696Z","end":"2026-09-09T06:16:14.924876Z","steps":["trace[1243929329] 'agreement among raft nodes before linearized reading' (duration: 113.103716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:16:47.759693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.2127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:16:47.760795Z","caller":"traceutil/trace.go:171","msg":"trace[1055389520] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"181.840419ms","start":"2026-09-09T06:16:47.57893Z","end":"2026-09-09T06:16:47.76077Z","steps":["trace[1055389520] 'compare' (duration: 172.782771ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:16:48.00376Z","caller":"traceutil/trace.go:171","msg":"trace[1086980980] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"237.292983ms","start":"2026-09-09T06:16:47.766427Z","end":"2026-09-09T06:16:48.00372Z","steps":["trace[1086980980] 'process raft request' (duration: 139.112657ms)","trace[1086980980] 'compare' (duration: 97.988918ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:48.129583Z","caller":"traceutil/trace.go:171","msg":"trace[1678240384] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"115.649752ms","start":"2026-09-09T06:16:48.013873Z","end":"2026-09-09T06:16:48.129522Z","steps":["trace[1678240384] 'process raft request' (duration: 91.358177ms)","trace[1678240384] 'compare' (duration: 24.111597ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:16:48.319662Z","caller":"traceutil/trace.go:171","msg":"trace[2111900642] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"107.397968ms","start":"2026-09-09T06:16:48.212229Z","end":"2026-09-09T06:16:48.319627Z","steps":["trace[2111900642] 'read index received' (duration: 47.73279ms)","trace[2111900642] 'applied index is now lower than readState.Index' (duration: 59.663898ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:16:48.320121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.86319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T06:16:48.320166Z","caller":"traceutil/trace.go:171","msg":"trace[440414641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2120; }","duration":"107.940844ms","start":"2026-09-09T06:16:48.212212Z","end":"2026-09-09T06:16:48.320153Z","steps":["trace[440414641] 'agreement among raft nodes before linearized reading' (duration: 107.543816ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:17:41.905746Z","caller":"traceutil/trace.go:171","msg":"trace[1904120953] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"144.477801ms","start":"2026-09-09T06:17:41.76125Z","end":"2026-09-09T06:17:41.905728Z","steps":["trace[1904120953] 'process raft request' (duration: 144.356964ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:18:08.270773Z","caller":"traceutil/trace.go:171","msg":"trace[139262659] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"236.937809ms","start":"2026-09-09T06:18:08.033719Z","end":"2026-09-09T06:18:08.270657Z","steps":["trace[139262659] 'process raft request' (duration: 236.480087ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:18:08.401391Z","caller":"traceutil/trace.go:171","msg":"trace[2025528017] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"126.227135ms","start":"2026-09-09T06:18:08.275118Z","end":"2026-09-09T06:18:08.401345Z","steps":["trace[2025528017] 'process raft request' (duration: 78.868439ms)","trace[2025528017] 'compare' (duration: 47.029959ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:18:08.588981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.740913ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-09T06:18:08.589817Z","caller":"traceutil/trace.go:171","msg":"trace[2124383541] linearizableReadLoop","detail":"{readStateIndex:2884; appliedIndex:2882; }","duration":"280.986354ms","start":"2026-09-09T06:18:08.30875Z","end":"2026-09-09T06:18:08.589736Z","steps":["trace[2124383541] 'read index received' (duration: 45.202361ms)","trace[2124383541] 'applied index is now lower than readState.Index' (duration: 235.781082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:18:08.590092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.301269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-09-09T06:18:08.590161Z","caller":"traceutil/trace.go:171","msg":"trace[2088082445] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2772; }","duration":"281.441356ms","start":"2026-09-09T06:18:08.308701Z","end":"2026-09-09T06:18:08.590142Z","steps":["trace[2088082445] 'agreement among raft nodes before linearized reading' (duration: 281.211015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:18:08.590233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.827828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:18:08.590388Z","caller":"traceutil/trace.go:171","msg":"trace[1793268565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2772; }","duration":"224.999966ms","start":"2026-09-09T06:18:08.365367Z","end":"2026-09-09T06:18:08.590367Z","steps":["trace[1793268565] 'agreement among raft nodes before linearized reading' (duration: 224.734682ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:45.426222Z","caller":"traceutil/trace.go:171","msg":"trace[1733115017] linearizableReadLoop","detail":"{readStateIndex:4715; appliedIndex:4714; }","duration":"353.817995ms","start":"2026-09-09T06:20:45.07237Z","end":"2026-09-09T06:20:45.426188Z","steps":["trace[1733115017] 'read index received' (duration: 353.441884ms)","trace[1733115017] 'applied index is now lower than readState.Index' (duration: 374.441µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:45.426327Z","caller":"traceutil/trace.go:171","msg":"trace[574754703] transaction","detail":"{read_only:false; response_revision:4514; number_of_response:1; }","duration":"370.55508ms","start":"2026-09-09T06:20:45.055732Z","end":"2026-09-09T06:20:45.426287Z","steps":["trace[574754703] 'process raft request' (duration: 370.22747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:45.426522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:45.055697Z","time spent":"370.721665ms","remote":"127.0.0.1:43812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:20:45.426586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.723347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-09T06:20:45.426621Z","caller":"traceutil/trace.go:171","msg":"trace[77647311] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4514; }","duration":"283.841361ms","start":"2026-09-09T06:20:45.142768Z","end":"2026-09-09T06:20:45.426609Z","steps":["trace[77647311] 'agreement among raft nodes before linearized reading' (duration: 283.629685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:45.426646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.244828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-zzjml\" ","response":"range_response_count:1 size:5141"} {"level":"info","ts":"2026-09-09T06:20:45.426745Z","caller":"traceutil/trace.go:171","msg":"trace[2041912319] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-zzjml; range_end:; response_count:1; response_revision:4514; }","duration":"354.410983ms","start":"2026-09-09T06:20:45.072309Z","end":"2026-09-09T06:20:45.42672Z","steps":["trace[2041912319] 'agreement among raft nodes before linearized reading' (duration: 354.046642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:45.426789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:45.072277Z","time spent":"354.500556ms","remote":"127.0.0.1:43812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":5164,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-zzjml\" "} {"level":"warn","ts":"2026-09-09T06:20:45.426955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.554775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-09T06:20:45.426919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.015937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T06:20:45.427124Z","caller":"traceutil/trace.go:171","msg":"trace[848084846] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:4514; }","duration":"283.193872ms","start":"2026-09-09T06:20:45.143834Z","end":"2026-09-09T06:20:45.427028Z","steps":["trace[848084846] 'agreement among raft nodes before linearized reading' (duration: 282.980686ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:45.426988Z","caller":"traceutil/trace.go:171","msg":"trace[85950947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4514; }","duration":"143.607526ms","start":"2026-09-09T06:20:45.283372Z","end":"2026-09-09T06:20:45.42698Z","steps":["trace[85950947] 'agreement among raft nodes before linearized reading' (duration: 143.430931ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:45.427672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.474429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T06:20:45.427684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.855048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-09-09T06:20:45.427945Z","caller":"traceutil/trace.go:171","msg":"trace[557934568] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4514; }","duration":"157.135036ms","start":"2026-09-09T06:20:45.270802Z","end":"2026-09-09T06:20:45.427937Z","steps":["trace[557934568] 'agreement among raft nodes before linearized reading' (duration: 155.706304ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:45.427958Z","caller":"traceutil/trace.go:171","msg":"trace[2051898141] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4514; }","duration":"208.687094ms","start":"2026-09-09T06:20:45.219245Z","end":"2026-09-09T06:20:45.427932Z","steps":["trace[2051898141] 'agreement among raft nodes before linearized reading' (duration: 207.322354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:45.429469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.250028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2360980"} {"level":"info","ts":"2026-09-09T06:20:45.429644Z","caller":"traceutil/trace.go:171","msg":"trace[1385253268] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4514; }","duration":"163.425233ms","start":"2026-09-09T06:20:45.266208Z","end":"2026-09-09T06:20:45.429633Z","steps":["trace[1385253268] 'agreement among raft nodes before linearized reading' (duration: 160.381063ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:20:46.274962Z","caller":"traceutil/trace.go:171","msg":"trace[1389860388] transaction","detail":"{read_only:false; response_revision:4539; number_of_response:1; }","duration":"163.66792ms","start":"2026-09-09T06:20:46.111255Z","end":"2026-09-09T06:20:46.274923Z","steps":["trace[1389860388] 'process raft request' (duration: 163.489145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:48.898403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.38274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T06:20:48.898501Z","caller":"traceutil/trace.go:171","msg":"trace[1690981385] linearizableReadLoop","detail":"{readStateIndex:4819; appliedIndex:4818; }","duration":"157.563025ms","start":"2026-09-09T06:20:48.740923Z","end":"2026-09-09T06:20:48.898486Z","steps":["trace[1690981385] 'read index received' (duration: 13.894746ms)","trace[1690981385] 'applied index is now lower than readState.Index' (duration: 143.667149ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:20:48.89858Z","caller":"traceutil/trace.go:171","msg":"trace[1856973745] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"304.247134ms","start":"2026-09-09T06:20:48.594311Z","end":"2026-09-09T06:20:48.898558Z","steps":["trace[1856973745] 'process raft request' (duration: 160.622277ms)","trace[1856973745] 'compare' (duration: 143.234755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:20:48.898685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.770761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T06:20:48.898711Z","caller":"traceutil/trace.go:171","msg":"trace[1590023426] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4616; }","duration":"157.798952ms","start":"2026-09-09T06:20:48.740903Z","end":"2026-09-09T06:20:48.898702Z","steps":["trace[1590023426] 'agreement among raft nodes before linearized reading' (duration: 157.672848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:48.898945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.474393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-47b59346-47a3-453e-8e33-e3fccf960dc1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-09T06:20:48.89897Z","caller":"traceutil/trace.go:171","msg":"trace[1893106017] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-47b59346-47a3-453e-8e33-e3fccf960dc1; range_end:; response_count:1; response_revision:4616; }","duration":"105.538955ms","start":"2026-09-09T06:20:48.793422Z","end":"2026-09-09T06:20:48.898961Z","steps":["trace[1893106017] 'agreement among raft nodes before linearized reading' (duration: 105.443572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:20:48.899361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:20:48.594253Z","time spent":"304.382898ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:21:03.160753Z","caller":"traceutil/trace.go:171","msg":"trace[708552137] transaction","detail":"{read_only:false; response_revision:4810; number_of_response:1; }","duration":"220.383359ms","start":"2026-09-09T06:21:02.940329Z","end":"2026-09-09T06:21:03.160713Z","steps":["trace[708552137] 'process raft request' (duration: 173.487326ms)","trace[708552137] 'compare' (duration: 46.667986ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:21:03.510603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.716655ms","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-09-09T06:21:03.511048Z","caller":"traceutil/trace.go:171","msg":"trace[753208471] transaction","detail":"{read_only:false; response_revision:4813; number_of_response:1; }","duration":"109.311281ms","start":"2026-09-09T06:21:03.401681Z","end":"2026-09-09T06:21:03.510992Z","steps":["trace[753208471] 'process raft request' (duration: 109.169676ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:03.511104Z","caller":"traceutil/trace.go:171","msg":"trace[1900521743] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4810; }","duration":"105.231562ms","start":"2026-09-09T06:21:03.405844Z","end":"2026-09-09T06:21:03.511076Z","steps":["trace[1900521743] 'range keys from in-memory index tree' (duration: 104.693224ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:03.51149Z","caller":"traceutil/trace.go:171","msg":"trace[1984940052] transaction","detail":"{read_only:false; response_revision:4811; number_of_response:1; }","duration":"533.548547ms","start":"2026-09-09T06:21:02.977924Z","end":"2026-09-09T06:21:03.511472Z","steps":["trace[1984940052] 'process raft request' (duration: 481.954104ms)","trace[1984940052] 'compare' (duration: 50.676534ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:21:03.511751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:02.977891Z","time spent":"533.757174ms","remote":"127.0.0.1:43658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T06:21:03.511535Z","caller":"traceutil/trace.go:171","msg":"trace[92474940] transaction","detail":"{read_only:false; response_revision:4812; number_of_response:1; }","duration":"500.726001ms","start":"2026-09-09T06:21:03.010776Z","end":"2026-09-09T06:21:03.511502Z","steps":["trace[92474940] 'process raft request' (duration: 500.015598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:03.511912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.968176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-09T06:21:03.511979Z","caller":"traceutil/trace.go:171","msg":"trace[52741339] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4813; }","duration":"427.11609ms","start":"2026-09-09T06:21:03.084844Z","end":"2026-09-09T06:21:03.51196Z","steps":["trace[52741339] 'agreement among raft nodes before linearized reading' (duration: 426.78685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:03.512046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:03.084819Z","time spent":"427.215214ms","remote":"127.0.0.1:43710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-09-09T06:21:03.511534Z","caller":"traceutil/trace.go:171","msg":"trace[118605382] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5022; }","duration":"426.586324ms","start":"2026-09-09T06:21:03.084899Z","end":"2026-09-09T06:21:03.511485Z","steps":["trace[118605382] 'read index received' (duration: 28.901395ms)","trace[118605382] 'applied index is now lower than readState.Index' (duration: 397.678498ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T06:21:03.512244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:03.010759Z","time spent":"501.217896ms","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T06:21:03.512969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.635216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-09-09T06:21:03.513159Z","caller":"traceutil/trace.go:171","msg":"trace[153233867] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4813; }","duration":"420.865123ms","start":"2026-09-09T06:21:03.092277Z","end":"2026-09-09T06:21:03.513142Z","steps":["trace[153233867] 'agreement among raft nodes before linearized reading' (duration: 420.556403ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:03.513197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:03.092253Z","time spent":"420.933955ms","remote":"127.0.0.1:43812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10539,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-09T06:21:03.513467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.310544ms","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:5"} {"level":"info","ts":"2026-09-09T06:21:03.513514Z","caller":"traceutil/trace.go:171","msg":"trace[1103146410] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:4813; }","duration":"353.394777ms","start":"2026-09-09T06:21:03.160101Z","end":"2026-09-09T06:21:03.513496Z","steps":["trace[1103146410] 'agreement among raft nodes before linearized reading' (duration: 353.315245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:03.513549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:03.160079Z","time spent":"353.462418ms","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-09-09T06:21:03.51384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.162593ms","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-09-09T06:21:03.513936Z","caller":"traceutil/trace.go:171","msg":"trace[1874022841] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4813; }","duration":"385.292817ms","start":"2026-09-09T06:21:03.128625Z","end":"2026-09-09T06:21:03.513918Z","steps":["trace[1874022841] 'agreement among raft nodes before linearized reading' (duration: 385.010517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:03.514008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T06:21:03.128602Z","time spent":"385.39023ms","remote":"127.0.0.1:43772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-09T06:21:03.757453Z","caller":"traceutil/trace.go:171","msg":"trace[1411737693] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"180.308037ms","start":"2026-09-09T06:21:03.577086Z","end":"2026-09-09T06:21:03.757394Z","steps":["trace[1411737693] 'process raft request' (duration: 150.085831ms)","trace[1411737693] 'compare' (duration: 29.903326ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:21:03.757531Z","caller":"traceutil/trace.go:171","msg":"trace[1779062012] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"179.286656ms","start":"2026-09-09T06:21:03.57821Z","end":"2026-09-09T06:21:03.757496Z","steps":["trace[1779062012] 'process raft request' (duration: 179.071258ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:03.758061Z","caller":"traceutil/trace.go:171","msg":"trace[1887967928] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5029; }","duration":"122.532449ms","start":"2026-09-09T06:21:03.635499Z","end":"2026-09-09T06:21:03.758032Z","steps":["trace[1887967928] 'read index received' (duration: 91.586239ms)","trace[1887967928] 'applied index is now lower than readState.Index' (duration: 30.94511ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T06:21:03.758117Z","caller":"traceutil/trace.go:171","msg":"trace[48212043] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"179.597235ms","start":"2026-09-09T06:21:03.578504Z","end":"2026-09-09T06:21:03.758101Z","steps":["trace[48212043] 'process raft request' (duration: 178.916244ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:03.758132Z","caller":"traceutil/trace.go:171","msg":"trace[1549717105] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"179.208493ms","start":"2026-09-09T06:21:03.578889Z","end":"2026-09-09T06:21:03.758098Z","steps":["trace[1549717105] 'process raft request' (duration: 178.890693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T06:21:03.758572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.058406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-09T06:21:03.758629Z","caller":"traceutil/trace.go:171","msg":"trace[536446398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4821; }","duration":"123.19168ms","start":"2026-09-09T06:21:03.635422Z","end":"2026-09-09T06:21:03.758614Z","steps":["trace[536446398] 'agreement among raft nodes before linearized reading' (duration: 122.969613ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:21:03.760052Z","caller":"traceutil/trace.go:171","msg":"trace[36396420] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"179.054708ms","start":"2026-09-09T06:21:03.579201Z","end":"2026-09-09T06:21:03.758256Z","steps":["trace[36396420] 'process raft request' (duration: 178.749469ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T06:23:49.477345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3122} {"level":"info","ts":"2026-09-09T06:23:49.589602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3122,"took":"110.287732ms","hash":2199776332,"current-db-size-bytes":57835520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30949376,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T06:23:49.589667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2199776332,"revision":3122,"compact-revision":-1}