{"level":"warn","ts":"2026-09-18T02:20:35.021448Z","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-18T02:20:35.021547Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.213: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.213:2380","--initial-cluster=instance=https://162.253.55.213:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.213:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.213: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-18T02:20:35.021631Z","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-18T02:20:35.021642Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-09-18T02:20:35.021659Z","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-18T02:20:35.022313Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"]} {"level":"info","ts":"2026-09-18T02:20:35.022382Z","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.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.213: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-18T02:20:35.029748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.137189ms"} {"level":"info","ts":"2026-09-18T02:20:35.042497Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"43ce62d0a10d51da","cluster-id":"25e0c2268a6b42c6"} {"level":"info","ts":"2026-09-18T02:20:35.042586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T02:20:35.04261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 0"} {"level":"info","ts":"2026-09-18T02:20:35.042623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 43ce62d0a10d51da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T02:20:35.04263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became follower at term 1"} {"level":"info","ts":"2026-09-18T02:20:35.042664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"warn","ts":"2026-09-18T02:20:35.055827Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T02:20:35.057429Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T02:20:35.058892Z","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-18T02:20:35.06114Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"43ce62d0a10d51da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T02:20:35.06129Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"43ce62d0a10d51da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T02:20:35.061356Z","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-18T02:20:35.061411Z","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-18T02:20:35.06142Z","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-18T02:20:35.063229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da switched to configuration voters=(4885951293938291162)"} {"level":"info","ts":"2026-09-18T02:20:35.063697Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","added-peer-id":"43ce62d0a10d51da","added-peer-peer-urls":["https://162.253.55.213:2380"]} {"level":"info","ts":"2026-09-18T02:20:35.065597Z","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-18T02:20:35.06576Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"43ce62d0a10d51da","initial-advertise-peer-urls":["https://162.253.55.213:2380"],"listen-peer-urls":["https://162.253.55.213:2380"],"advertise-client-urls":["https://162.253.55.213:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.213:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T02:20:35.065781Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T02:20:35.065762Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-09-18T02:20:35.065826Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.213:2380"} {"level":"info","ts":"2026-09-18T02:20:36.043423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T02:20:36.043484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T02:20:36.043538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgPreVoteResp from 43ce62d0a10d51da at term 1"} {"level":"info","ts":"2026-09-18T02:20:36.04355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became candidate at term 2"} {"level":"info","ts":"2026-09-18T02:20:36.043557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da received MsgVoteResp from 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-09-18T02:20:36.043567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"43ce62d0a10d51da became leader at term 2"} {"level":"info","ts":"2026-09-18T02:20:36.043579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 43ce62d0a10d51da elected leader 43ce62d0a10d51da at term 2"} {"level":"info","ts":"2026-09-18T02:20:36.045371Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:20:36.046685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:20:36.04668Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"43ce62d0a10d51da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.213:2379]}","request-path":"/0/members/43ce62d0a10d51da/attributes","cluster-id":"25e0c2268a6b42c6","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T02:20:36.046801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T02:20:36.046959Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T02:20:36.046994Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T02:20:36.047156Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25e0c2268a6b42c6","local-member-id":"43ce62d0a10d51da","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:20:36.047239Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:20:36.047261Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T02:20:36.048407Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.213:2379"} {"level":"info","ts":"2026-09-18T02:20:36.049965Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T02:20:51.874733Z","caller":"traceutil/trace.go:171","msg":"trace[39103833] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"150.88744ms","start":"2026-09-18T02:20:51.723815Z","end":"2026-09-18T02:20:51.874703Z","steps":["trace[39103833] 'process raft request' (duration: 150.691641ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:20:58.116315Z","caller":"traceutil/trace.go:171","msg":"trace[2058386819] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:402; }","duration":"208.414059ms","start":"2026-09-18T02:20:57.907877Z","end":"2026-09-18T02:20:58.116291Z","steps":["trace[2058386819] 'read index received' (duration: 208.256502ms)","trace[2058386819] 'applied index is now lower than readState.Index' (duration: 156.237µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:20:58.116463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.561035ms","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-18T02:20:58.116534Z","caller":"traceutil/trace.go:171","msg":"trace[680220813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"208.660239ms","start":"2026-09-18T02:20:57.907865Z","end":"2026-09-18T02:20:58.116525Z","steps":["trace[680220813] 'agreement among raft nodes before linearized reading' (duration: 208.516063ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:20:58.116486Z","caller":"traceutil/trace.go:171","msg":"trace[616026417] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"215.613709ms","start":"2026-09-18T02:20:57.900825Z","end":"2026-09-18T02:20:58.116439Z","steps":["trace[616026417] 'process raft request' (duration: 215.331257ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:20.69808Z","caller":"traceutil/trace.go:171","msg":"trace[233930908] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"141.399921ms","start":"2026-09-18T02:21:20.55665Z","end":"2026-09-18T02:21:20.69805Z","steps":["trace[233930908] 'process raft request' (duration: 125.557131ms)","trace[233930908] 'compare' (duration: 15.476897ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:20.699641Z","caller":"traceutil/trace.go:171","msg":"trace[875914461] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"122.876446ms","start":"2026-09-18T02:21:20.576753Z","end":"2026-09-18T02:21:20.69963Z","steps":["trace[875914461] 'process raft request' (duration: 122.7116ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:20.699706Z","caller":"traceutil/trace.go:171","msg":"trace[2005138747] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"122.065917ms","start":"2026-09-18T02:21:20.577634Z","end":"2026-09-18T02:21:20.6997Z","steps":["trace[2005138747] 'process raft request' (duration: 121.945473ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:20.699936Z","caller":"traceutil/trace.go:171","msg":"trace[435498145] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"121.910171ms","start":"2026-09-18T02:21:20.577992Z","end":"2026-09-18T02:21:20.699902Z","steps":["trace[435498145] 'process raft request' (duration: 121.641742ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:20.69993Z","caller":"traceutil/trace.go:171","msg":"trace[161743587] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"118.715778ms","start":"2026-09-18T02:21:20.581185Z","end":"2026-09-18T02:21:20.699901Z","steps":["trace[161743587] 'process raft request' (duration: 118.49314ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.654547Z","caller":"traceutil/trace.go:171","msg":"trace[1977138396] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"239.585455ms","start":"2026-09-18T02:21:22.414828Z","end":"2026-09-18T02:21:22.654413Z","steps":["trace[1977138396] 'process raft request' (duration: 172.942463ms)","trace[1977138396] 'compare' (duration: 66.447245ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:22.667278Z","caller":"traceutil/trace.go:171","msg":"trace[1039291091] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"250.833356ms","start":"2026-09-18T02:21:22.416422Z","end":"2026-09-18T02:21:22.667256Z","steps":["trace[1039291091] 'process raft request' (duration: 250.6594ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.667462Z","caller":"traceutil/trace.go:171","msg":"trace[1342253581] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"249.425988ms","start":"2026-09-18T02:21:22.418027Z","end":"2026-09-18T02:21:22.667453Z","steps":["trace[1342253581] 'process raft request' (duration: 249.164519ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.667557Z","caller":"traceutil/trace.go:171","msg":"trace[782427017] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"240.382414ms","start":"2026-09-18T02:21:22.427167Z","end":"2026-09-18T02:21:22.667549Z","steps":["trace[782427017] 'process raft request' (duration: 240.121585ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.667622Z","caller":"traceutil/trace.go:171","msg":"trace[1411545950] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"244.973333ms","start":"2026-09-18T02:21:22.422641Z","end":"2026-09-18T02:21:22.667614Z","steps":["trace[1411545950] 'process raft request' (duration: 244.585839ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.66814Z","caller":"traceutil/trace.go:171","msg":"trace[835107820] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"176.33072ms","start":"2026-09-18T02:21:22.491785Z","end":"2026-09-18T02:21:22.668116Z","steps":["trace[835107820] 'process raft request' (duration: 175.843263ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.668247Z","caller":"traceutil/trace.go:171","msg":"trace[937330414] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"229.761985ms","start":"2026-09-18T02:21:22.438468Z","end":"2026-09-18T02:21:22.66823Z","steps":["trace[937330414] 'process raft request' (duration: 229.02776ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.668331Z","caller":"traceutil/trace.go:171","msg":"trace[330035087] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"212.62954ms","start":"2026-09-18T02:21:22.455692Z","end":"2026-09-18T02:21:22.668322Z","steps":["trace[330035087] 'process raft request' (duration: 211.843313ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.668663Z","caller":"traceutil/trace.go:171","msg":"trace[1220346662] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"190.396999ms","start":"2026-09-18T02:21:22.478206Z","end":"2026-09-18T02:21:22.668603Z","steps":["trace[1220346662] 'process raft request' (duration: 189.375194ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.668796Z","caller":"traceutil/trace.go:171","msg":"trace[1337288428] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"231.218786ms","start":"2026-09-18T02:21:22.436384Z","end":"2026-09-18T02:21:22.667603Z","steps":["trace[1337288428] 'process raft request' (duration: 231.073681ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:22.669288Z","caller":"traceutil/trace.go:171","msg":"trace[799228157] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"235.451772ms","start":"2026-09-18T02:21:22.433817Z","end":"2026-09-18T02:21:22.669269Z","steps":["trace[799228157] 'process raft request' (duration: 233.606408ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:23.204891Z","caller":"traceutil/trace.go:171","msg":"trace[1068334577] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"115.970587ms","start":"2026-09-18T02:21:23.088898Z","end":"2026-09-18T02:21:23.204869Z","steps":["trace[1068334577] 'process raft request' (duration: 41.209896ms)","trace[1068334577] 'compare' (duration: 74.663757ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:25.045342Z","caller":"traceutil/trace.go:171","msg":"trace[1539095419] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"182.611199ms","start":"2026-09-18T02:21:24.862661Z","end":"2026-09-18T02:21:25.045272Z","steps":["trace[1539095419] 'process raft request' (duration: 182.423373ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:21:31.983479Z","caller":"traceutil/trace.go:171","msg":"trace[512756117] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"102.521621ms","start":"2026-09-18T02:21:31.880932Z","end":"2026-09-18T02:21:31.983453Z","steps":["trace[512756117] 'process raft request' (duration: 102.334175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:32.763162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.626919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:21:32.763287Z","caller":"traceutil/trace.go:171","msg":"trace[998380764] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"318.411347ms","start":"2026-09-18T02:21:32.444861Z","end":"2026-09-18T02:21:32.763272Z","steps":["trace[998380764] 'read index received' (duration: 59.953142ms)","trace[998380764] 'applied index is now lower than readState.Index' (duration: 258.457035ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:21:32.763344Z","caller":"traceutil/trace.go:171","msg":"trace[907084323] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"715.877311ms","start":"2026-09-18T02:21:32.047442Z","end":"2026-09-18T02:21:32.76332Z","steps":["trace[907084323] 'process raft request' (duration: 457.507768ms)","trace[907084323] 'compare' (duration: 257.510316ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:21:32.763459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.593563ms","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-18T02:21:32.763496Z","caller":"traceutil/trace.go:171","msg":"trace[1472222483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"318.633154ms","start":"2026-09-18T02:21:32.444854Z","end":"2026-09-18T02:21:32.763487Z","steps":["trace[1472222483] 'agreement among raft nodes before linearized reading' (duration: 318.49407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:32.763486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:32.047409Z","time spent":"716.023965ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:21:32.763694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.700106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T02:21:32.763731Z","caller":"traceutil/trace.go:171","msg":"trace[2058895850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"306.772708ms","start":"2026-09-18T02:21:32.45695Z","end":"2026-09-18T02:21:32.763722Z","steps":["trace[2058895850] 'agreement among raft nodes before linearized reading' (duration: 306.617193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:32.763756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:32.456933Z","time spent":"306.816799ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T02:21:32.763536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:32.44479Z","time spent":"318.737867ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T02:21:49.796887Z","caller":"traceutil/trace.go:171","msg":"trace[1421892879] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"573.233111ms","start":"2026-09-18T02:21:49.223617Z","end":"2026-09-18T02:21:49.79685Z","steps":["trace[1421892879] 'process raft request' (duration: 573.006175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:49.797114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:49.223602Z","time spent":"573.393865ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:21:49.801047Z","caller":"traceutil/trace.go:171","msg":"trace[35857601] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"290.39029ms","start":"2026-09-18T02:21:49.510627Z","end":"2026-09-18T02:21:49.801017Z","steps":["trace[35857601] 'process raft request' (duration: 290.263156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:21:49.801043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:21:49.224213Z","time spent":"576.818119ms","remote":"127.0.0.1:41296","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-18T02:22:03.992257Z","caller":"traceutil/trace.go:171","msg":"trace[1884399026] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"101.449738ms","start":"2026-09-18T02:22:03.890768Z","end":"2026-09-18T02:22:03.992217Z","steps":["trace[1884399026] 'process raft request' (duration: 101.217443ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:22:04.249151Z","caller":"traceutil/trace.go:171","msg":"trace[1927575275] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"138.869088ms","start":"2026-09-18T02:22:04.110216Z","end":"2026-09-18T02:22:04.249085Z","steps":["trace[1927575275] 'process raft request' (duration: 120.276467ms)","trace[1927575275] 'compare' (duration: 18.354364ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:09.661037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.26028ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-09-18T02:23:09.661118Z","caller":"traceutil/trace.go:171","msg":"trace[1296276900] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1933; }","duration":"357.748981ms","start":"2026-09-18T02:23:09.303357Z","end":"2026-09-18T02:23:09.661106Z","steps":["trace[1296276900] 'read index received' (duration: 209.327628ms)","trace[1296276900] 'applied index is now lower than readState.Index' (duration: 148.420273ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:09.661129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.260722Z","time spent":"400.403135ms","remote":"127.0.0.1:41296","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-18T02:23:09.661253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.812856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52c93706-d4c1-4407-bd5b-df616ce207c7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-18T02:23:09.661267Z","caller":"traceutil/trace.go:171","msg":"trace[2133665422] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-52c93706-d4c1-4407-bd5b-df616ce207c7; range_end:; response_count:1; response_revision:1872; }","duration":"311.877867ms","start":"2026-09-18T02:23:09.349385Z","end":"2026-09-18T02:23:09.661263Z","steps":["trace[2133665422] 'agreement among raft nodes before linearized reading' (duration: 311.796225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:09.661285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.349355Z","time spent":"311.925828ms","remote":"127.0.0.1:41442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52c93706-d4c1-4407-bd5b-df616ce207c7\" "} {"level":"warn","ts":"2026-09-18T02:23:09.661308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.929194ms","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-18T02:23:09.661354Z","caller":"traceutil/trace.go:171","msg":"trace[2103681698] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1872; }","duration":"358.038016ms","start":"2026-09-18T02:23:09.303307Z","end":"2026-09-18T02:23:09.661345Z","steps":["trace[2103681698] 'agreement among raft nodes before linearized reading' (duration: 357.849463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:09.661421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.355561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T02:23:09.66144Z","caller":"traceutil/trace.go:171","msg":"trace[1336547978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1872; }","duration":"246.409992ms","start":"2026-09-18T02:23:09.415023Z","end":"2026-09-18T02:23:09.661433Z","steps":["trace[1336547978] 'agreement among raft nodes before linearized reading' (duration: 246.358991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:09.661465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.303277Z","time spent":"358.151268ms","remote":"127.0.0.1:41362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":681,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T02:23:09.661576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.37275ms","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-18T02:23:09.661592Z","caller":"traceutil/trace.go:171","msg":"trace[1579756943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1872; }","duration":"300.42817ms","start":"2026-09-18T02:23:09.36116Z","end":"2026-09-18T02:23:09.661588Z","steps":["trace[1579756943] 'agreement among raft nodes before linearized reading' (duration: 300.376439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:09.661604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.361138Z","time spent":"300.463172ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T02:23:09.661658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.096709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52c93706-d4c1-4407-bd5b-df616ce207c7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-18T02:23:09.663004Z","caller":"traceutil/trace.go:171","msg":"trace[1304407915] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-52c93706-d4c1-4407-bd5b-df616ce207c7; range_end:; response_count:1; response_revision:1872; }","duration":"235.302773ms","start":"2026-09-18T02:23:09.426453Z","end":"2026-09-18T02:23:09.661756Z","steps":["trace[1304407915] 'agreement among raft nodes before linearized reading' (duration: 234.905136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:10.37767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.356806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:23:10.37805Z","caller":"traceutil/trace.go:171","msg":"trace[1836185583] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1935; }","duration":"658.169229ms","start":"2026-09-18T02:23:09.719852Z","end":"2026-09-18T02:23:10.378021Z","steps":["trace[1836185583] 'read index received' (duration: 411.310314ms)","trace[1836185583] 'applied index is now lower than readState.Index' (duration: 246.857195ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:10.378341Z","caller":"traceutil/trace.go:171","msg":"trace[776011385] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"710.63022ms","start":"2026-09-18T02:23:09.667691Z","end":"2026-09-18T02:23:10.378321Z","steps":["trace[776011385] 'process raft request' (duration: 710.144822ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:10.378111Z","caller":"traceutil/trace.go:171","msg":"trace[124456096] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"710.619121ms","start":"2026-09-18T02:23:09.667326Z","end":"2026-09-18T02:23:10.377945Z","steps":["trace[124456096] 'process raft request' (duration: 463.769576ms)","trace[124456096] 'compare' (duration: 246.118361ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:10.378448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.667664Z","time spent":"710.734052ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:10.378686Z","caller":"traceutil/trace.go:171","msg":"trace[727394112] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"700.325495ms","start":"2026-09-18T02:23:09.678317Z","end":"2026-09-18T02:23:10.378642Z","steps":["trace[727394112] 'process raft request' (duration: 699.612983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:10.378794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.461596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:566"} {"level":"info","ts":"2026-09-18T02:23:10.378858Z","caller":"traceutil/trace.go:171","msg":"trace[706950742] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1876; }","duration":"711.542638ms","start":"2026-09-18T02:23:09.667294Z","end":"2026-09-18T02:23:10.378837Z","steps":["trace[706950742] 'agreement among raft nodes before linearized reading' (duration: 711.356944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:10.378904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.667266Z","time spent":"711.627779ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":588,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-18T02:23:10.37904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.678287Z","time spent":"700.493069ms","remote":"127.0.0.1:41442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:23:10.379229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.667303Z","time spent":"711.204231ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:23:10.740143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.686943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:23:10.740313Z","caller":"traceutil/trace.go:171","msg":"trace[230296353] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"354.739485ms","start":"2026-09-18T02:23:10.385559Z","end":"2026-09-18T02:23:10.740298Z","steps":["trace[230296353] 'process raft request' (duration: 354.666754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:10.740366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.385528Z","time spent":"354.812837ms","remote":"127.0.0.1:41362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:10.740575Z","caller":"traceutil/trace.go:171","msg":"trace[828232883] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"1.01384007s","start":"2026-09-18T02:23:09.726723Z","end":"2026-09-18T02:23:10.740563Z","steps":["trace[828232883] 'process raft request' (duration: 856.674289ms)","trace[828232883] 'compare' (duration: 156.57715ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:10.740619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.726701Z","time spent":"1.013893191s","remote":"127.0.0.1:41362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:10.740629Z","caller":"traceutil/trace.go:171","msg":"trace[320586271] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"362.489123ms","start":"2026-09-18T02:23:10.378101Z","end":"2026-09-18T02:23:10.74059Z","steps":["trace[320586271] 'read index received' (duration: 205.308172ms)","trace[320586271] 'applied index is now lower than readState.Index' (duration: 157.177051ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:10.740759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018152377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-18T02:23:10.740788Z","caller":"traceutil/trace.go:171","msg":"trace[1909564890] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1878; }","duration":"1.018226808s","start":"2026-09-18T02:23:09.722549Z","end":"2026-09-18T02:23:10.740775Z","steps":["trace[1909564890] 'agreement among raft nodes before linearized reading' (duration: 1.018175847s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:10.740807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:09.722484Z","time spent":"1.0183186s","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-09-18T02:23:10.740887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.901732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-09-18T02:23:10.740943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.208197ms","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-09-18T02:23:10.740951Z","caller":"traceutil/trace.go:171","msg":"trace[2000783619] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1878; }","duration":"698.032984ms","start":"2026-09-18T02:23:10.0429Z","end":"2026-09-18T02:23:10.740933Z","steps":["trace[2000783619] 'agreement among raft nodes before linearized reading' (duration: 697.7908ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:10.740998Z","caller":"traceutil/trace.go:171","msg":"trace[335839223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1878; }","duration":"334.33927ms","start":"2026-09-18T02:23:10.406642Z","end":"2026-09-18T02:23:10.740981Z","steps":["trace[335839223] 'agreement among raft nodes before linearized reading' (duration: 334.119986ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:10.741158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.406621Z","time spent":"334.521114ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T02:23:10.741332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.579493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-18T02:23:10.741358Z","caller":"traceutil/trace.go:171","msg":"trace[809476732] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1878; }","duration":"160.639023ms","start":"2026-09-18T02:23:10.580709Z","end":"2026-09-18T02:23:10.741348Z","steps":["trace[809476732] 'agreement among raft nodes before linearized reading' (duration: 160.556641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:10.741001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.042871Z","time spent":"698.122176ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-18T02:23:11.300758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.222444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:23:11.300943Z","caller":"traceutil/trace.go:171","msg":"trace[1153166502] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"554.219755ms","start":"2026-09-18T02:23:10.7467Z","end":"2026-09-18T02:23:11.300919Z","steps":["trace[1153166502] 'process raft request' (duration: 240.811747ms)","trace[1153166502] 'compare' (duration: 313.045522ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:11.300993Z","caller":"traceutil/trace.go:171","msg":"trace[316958575] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"423.291213ms","start":"2026-09-18T02:23:10.877687Z","end":"2026-09-18T02:23:11.300978Z","steps":["trace[316958575] 'process raft request' (duration: 423.256983ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:11.301062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.877661Z","time spent":"423.359784ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:23:11.301063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.746681Z","time spent":"554.320057ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:11.301117Z","caller":"traceutil/trace.go:171","msg":"trace[353522249] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"553.289228ms","start":"2026-09-18T02:23:10.747789Z","end":"2026-09-18T02:23:11.301078Z","steps":["trace[353522249] 'process raft request' (duration: 553.109555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:11.301209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.747782Z","time spent":"553.387ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:11.301245Z","caller":"traceutil/trace.go:171","msg":"trace[1200079989] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"554.45301ms","start":"2026-09-18T02:23:10.746775Z","end":"2026-09-18T02:23:11.301228Z","steps":["trace[1200079989] 'process raft request' (duration: 554.043653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:11.301297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.291626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-09-18T02:23:11.301304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.74677Z","time spent":"554.50702ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:11.301322Z","caller":"traceutil/trace.go:171","msg":"trace[66090278] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1885; }","duration":"554.349616ms","start":"2026-09-18T02:23:10.746967Z","end":"2026-09-18T02:23:11.301316Z","steps":["trace[66090278] 'agreement among raft nodes before linearized reading' (duration: 554.295915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:11.301345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.746949Z","time spent":"554.390797ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-09-18T02:23:11.301231Z","caller":"traceutil/trace.go:171","msg":"trace[1831659333] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"552.378881ms","start":"2026-09-18T02:23:10.748843Z","end":"2026-09-18T02:23:11.301222Z","steps":["trace[1831659333] 'process raft request' (duration: 552.071596ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:11.301372Z","caller":"traceutil/trace.go:171","msg":"trace[1486291771] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"554.342378ms","start":"2026-09-18T02:23:10.747015Z","end":"2026-09-18T02:23:11.301357Z","steps":["trace[1486291771] 'process raft request' (duration: 553.865049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:11.301433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.747006Z","time spent":"554.400279ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:23:11.301432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.748836Z","time spent":"552.566186ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:11.301252Z","caller":"traceutil/trace.go:171","msg":"trace[1425665259] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1940; }","duration":"554.240255ms","start":"2026-09-18T02:23:10.747005Z","end":"2026-09-18T02:23:11.301246Z","steps":["trace[1425665259] 'read index received' (duration: 28.444939ms)","trace[1425665259] 'applied index is now lower than readState.Index' (duration: 525.794436ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:11.301567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.627836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:23:11.301594Z","caller":"traceutil/trace.go:171","msg":"trace[1392993084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1885; }","duration":"467.682127ms","start":"2026-09-18T02:23:10.833904Z","end":"2026-09-18T02:23:11.301587Z","steps":["trace[1392993084] 'agreement among raft nodes before linearized reading' (duration: 467.639666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:11.301613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:10.833887Z","time spent":"467.721177ms","remote":"127.0.0.1:36896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T02:23:11.301701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.043462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:23:11.301736Z","caller":"traceutil/trace.go:171","msg":"trace[1007931579] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1885; }","duration":"216.159074ms","start":"2026-09-18T02:23:11.085567Z","end":"2026-09-18T02:23:11.301726Z","steps":["trace[1007931579] 'agreement among raft nodes before linearized reading' (duration: 216.036042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:17.12316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.919655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:23:17.123236Z","caller":"traceutil/trace.go:171","msg":"trace[1656299796] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1950; }","duration":"109.014747ms","start":"2026-09-18T02:23:17.014204Z","end":"2026-09-18T02:23:17.123219Z","steps":["trace[1656299796] 'count revisions from in-memory index tree' (duration: 108.804264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:19.693585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.388932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:23:19.693683Z","caller":"traceutil/trace.go:171","msg":"trace[1155213771] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1979; }","duration":"224.520934ms","start":"2026-09-18T02:23:19.469137Z","end":"2026-09-18T02:23:19.693658Z","steps":["trace[1155213771] 'count revisions from in-memory index tree' (duration: 224.328341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:19.694106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.135029ms","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":"warn","ts":"2026-09-18T02:23:19.694129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.911294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-09-18T02:23:19.694175Z","caller":"traceutil/trace.go:171","msg":"trace[113585219] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1979; }","duration":"212.231911ms","start":"2026-09-18T02:23:19.481925Z","end":"2026-09-18T02:23:19.694157Z","steps":["trace[113585219] 'range keys from in-memory index tree' (duration: 212.019328ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:19.694195Z","caller":"traceutil/trace.go:171","msg":"trace[1839690616] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1979; }","duration":"216.017886ms","start":"2026-09-18T02:23:19.47816Z","end":"2026-09-18T02:23:19.694178Z","steps":["trace[1839690616] 'range keys from in-memory index tree' (duration: 215.769443ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:43.212305Z","caller":"traceutil/trace.go:171","msg":"trace[567634761] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"132.04881ms","start":"2026-09-18T02:23:43.08023Z","end":"2026-09-18T02:23:43.212279Z","steps":["trace[567634761] 'process raft request' (duration: 131.921948ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:44.775216Z","caller":"traceutil/trace.go:171","msg":"trace[754891976] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"126.255582ms","start":"2026-09-18T02:23:44.648937Z","end":"2026-09-18T02:23:44.775193Z","steps":["trace[754891976] 'process raft request' (duration: 126.202661ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:44.775268Z","caller":"traceutil/trace.go:171","msg":"trace[129949191] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"138.502822ms","start":"2026-09-18T02:23:44.636733Z","end":"2026-09-18T02:23:44.775236Z","steps":["trace[129949191] 'process raft request' (duration: 130.98936ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:48.808947Z","caller":"traceutil/trace.go:171","msg":"trace[943222829] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2264; }","duration":"125.220308ms","start":"2026-09-18T02:23:48.683696Z","end":"2026-09-18T02:23:48.808917Z","steps":["trace[943222829] 'read index received' (duration: 125.022255ms)","trace[943222829] 'applied index is now lower than readState.Index' (duration: 196.823µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:48.809112Z","caller":"traceutil/trace.go:171","msg":"trace[280540372] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"242.302803ms","start":"2026-09-18T02:23:48.566794Z","end":"2026-09-18T02:23:48.809096Z","steps":["trace[280540372] 'process raft request' (duration: 241.925178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:48.809347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.635094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T02:23:48.809387Z","caller":"traceutil/trace.go:171","msg":"trace[1564749761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2187; }","duration":"125.725545ms","start":"2026-09-18T02:23:48.683646Z","end":"2026-09-18T02:23:48.809372Z","steps":["trace[1564749761] 'agreement among raft nodes before linearized reading' (duration: 125.583572ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:49.200307Z","caller":"traceutil/trace.go:171","msg":"trace[215606543] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2265; }","duration":"379.370357ms","start":"2026-09-18T02:23:48.82092Z","end":"2026-09-18T02:23:49.20029Z","steps":["trace[215606543] 'read index received' (duration: 329.503221ms)","trace[215606543] 'applied index is now lower than readState.Index' (duration: 49.866506ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:49.200397Z","caller":"traceutil/trace.go:171","msg":"trace[480451757] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"386.327291ms","start":"2026-09-18T02:23:48.814062Z","end":"2026-09-18T02:23:49.20039Z","steps":["trace[480451757] 'process raft request' (duration: 386.195889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:49.200466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:48.814036Z","time spent":"386.377841ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:49.200327Z","caller":"traceutil/trace.go:171","msg":"trace[925285370] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"387.093603ms","start":"2026-09-18T02:23:48.813214Z","end":"2026-09-18T02:23:49.200307Z","steps":["trace[925285370] 'process raft request' (duration: 337.206367ms)","trace[925285370] 'compare' (duration: 49.594211ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:49.200741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.819074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-09-18T02:23:49.201029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:48.813172Z","time spent":"387.755793ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:23:49.201091Z","caller":"traceutil/trace.go:171","msg":"trace[2009033269] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"380.15913ms","start":"2026-09-18T02:23:48.820882Z","end":"2026-09-18T02:23:49.201041Z","steps":["trace[2009033269] 'agreement among raft nodes before linearized reading' (duration: 379.784094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:49.201301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:23:48.820865Z","time spent":"380.379373ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-18T02:23:49.200773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.477901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-09-18T02:23:49.201466Z","caller":"traceutil/trace.go:171","msg":"trace[590381428] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"154.168091ms","start":"2026-09-18T02:23:49.047232Z","end":"2026-09-18T02:23:49.2014Z","steps":["trace[590381428] 'agreement among raft nodes before linearized reading' (duration: 153.270557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:49.447413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.504054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:23:49.447667Z","caller":"traceutil/trace.go:171","msg":"trace[180814050] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"168.596354ms","start":"2026-09-18T02:23:49.279029Z","end":"2026-09-18T02:23:49.447626Z","steps":["trace[180814050] 'process raft request' (duration: 168.475702ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:23:49.447717Z","caller":"traceutil/trace.go:171","msg":"trace[816614168] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"206.524557ms","start":"2026-09-18T02:23:49.241173Z","end":"2026-09-18T02:23:49.447698Z","steps":["trace[816614168] 'process raft request' (duration: 66.665317ms)","trace[816614168] 'compare' (duration: 139.165329ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:23:49.447703Z","caller":"traceutil/trace.go:171","msg":"trace[1741848308] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2268; }","duration":"159.835393ms","start":"2026-09-18T02:23:49.287837Z","end":"2026-09-18T02:23:49.447672Z","steps":["trace[1741848308] 'read index received' (duration: 20.012933ms)","trace[1741848308] 'applied index is now lower than readState.Index' (duration: 139.81883ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:23:49.448047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.174599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-09-18T02:23:49.44809Z","caller":"traceutil/trace.go:171","msg":"trace[1161177644] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2192; }","duration":"160.263761ms","start":"2026-09-18T02:23:49.287815Z","end":"2026-09-18T02:23:49.448078Z","steps":["trace[1161177644] 'agreement among raft nodes before linearized reading' (duration: 159.957475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:23:59.580368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.141461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:23:59.580463Z","caller":"traceutil/trace.go:171","msg":"trace[920829862] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:2256; }","duration":"119.312283ms","start":"2026-09-18T02:23:59.461126Z","end":"2026-09-18T02:23:59.580438Z","steps":["trace[920829862] 'range keys from in-memory index tree' (duration: 119.027069ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:00.548977Z","caller":"traceutil/trace.go:171","msg":"trace[309741007] transaction","detail":"{read_only:false; response_revision:2266; number_of_response:1; }","duration":"102.864559ms","start":"2026-09-18T02:24:00.446088Z","end":"2026-09-18T02:24:00.548953Z","steps":["trace[309741007] 'process raft request' (duration: 62.815564ms)","trace[309741007] 'compare' (duration: 39.627599ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:31.883564Z","caller":"traceutil/trace.go:171","msg":"trace[225801797] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"128.47946ms","start":"2026-09-18T02:24:31.755063Z","end":"2026-09-18T02:24:31.883543Z","steps":["trace[225801797] 'process raft request' (duration: 98.645248ms)","trace[225801797] 'compare' (duration: 29.69388ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:32.240092Z","caller":"traceutil/trace.go:171","msg":"trace[723240753] linearizableReadLoop","detail":"{readStateIndex:2587; appliedIndex:2586; }","duration":"123.829458ms","start":"2026-09-18T02:24:32.116229Z","end":"2026-09-18T02:24:32.240059Z","steps":["trace[723240753] 'read index received' (duration: 70.449598ms)","trace[723240753] 'applied index is now lower than readState.Index' (duration: 53.37854ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:32.240259Z","caller":"traceutil/trace.go:171","msg":"trace[952527391] transaction","detail":"{read_only:false; response_revision:2493; number_of_response:1; }","duration":"135.795968ms","start":"2026-09-18T02:24:32.10437Z","end":"2026-09-18T02:24:32.240166Z","steps":["trace[952527391] 'process raft request' (duration: 82.412619ms)","trace[952527391] 'compare' (duration: 53.131265ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:24:32.240505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.274384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-09-18T02:24:32.240593Z","caller":"traceutil/trace.go:171","msg":"trace[423110720] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2493; }","duration":"124.432656ms","start":"2026-09-18T02:24:32.116138Z","end":"2026-09-18T02:24:32.240571Z","steps":["trace[423110720] 'agreement among raft nodes before linearized reading' (duration: 124.061021ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:51.890868Z","caller":"traceutil/trace.go:171","msg":"trace[1790211358] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"119.746621ms","start":"2026-09-18T02:24:51.771099Z","end":"2026-09-18T02:24:51.890846Z","steps":["trace[1790211358] 'process raft request' (duration: 119.300898ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:24:57.525372Z","caller":"traceutil/trace.go:171","msg":"trace[366126701] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2751; }","duration":"171.326564ms","start":"2026-09-18T02:24:57.35403Z","end":"2026-09-18T02:24:57.525357Z","steps":["trace[366126701] 'process raft request' (duration: 79.970491ms)","trace[366126701] 'compare' (duration: 91.180804ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:57.715937Z","caller":"traceutil/trace.go:171","msg":"trace[1499412708] linearizableReadLoop","detail":"{readStateIndex:2864; appliedIndex:2861; }","duration":"116.963216ms","start":"2026-09-18T02:24:57.598952Z","end":"2026-09-18T02:24:57.715915Z","steps":["trace[1499412708] 'read index received' (duration: 6.108287ms)","trace[1499412708] 'applied index is now lower than readState.Index' (duration: 110.853809ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:57.716088Z","caller":"traceutil/trace.go:171","msg":"trace[819755624] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"135.233667ms","start":"2026-09-18T02:24:57.580833Z","end":"2026-09-18T02:24:57.716066Z","steps":["trace[819755624] 'process raft request' (duration: 65.466226ms)","trace[819755624] 'compare' (duration: 69.169329ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:24:57.716421Z","caller":"traceutil/trace.go:171","msg":"trace[900160219] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"128.688626ms","start":"2026-09-18T02:24:57.587695Z","end":"2026-09-18T02:24:57.716384Z","steps":["trace[900160219] 'process raft request' (duration: 128.153228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:24:57.716671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.702225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-09-18T02:24:57.716713Z","caller":"traceutil/trace.go:171","msg":"trace[486263518] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2757; }","duration":"117.795889ms","start":"2026-09-18T02:24:57.598907Z","end":"2026-09-18T02:24:57.716703Z","steps":["trace[486263518] 'agreement among raft nodes before linearized reading' (duration: 117.652062ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:26.249401Z","caller":"traceutil/trace.go:171","msg":"trace[1528065862] linearizableReadLoop","detail":"{readStateIndex:3056; appliedIndex:3055; }","duration":"124.309006ms","start":"2026-09-18T02:25:26.125075Z","end":"2026-09-18T02:25:26.249384Z","steps":["trace[1528065862] 'read index received' (duration: 124.267714ms)","trace[1528065862] 'applied index is now lower than readState.Index' (duration: 40.472µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:25:26.24957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.492074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:551"} {"level":"info","ts":"2026-09-18T02:25:26.249601Z","caller":"traceutil/trace.go:171","msg":"trace[848251609] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2938; }","duration":"124.611089ms","start":"2026-09-18T02:25:26.124979Z","end":"2026-09-18T02:25:26.24959Z","steps":["trace[848251609] 'agreement among raft nodes before linearized reading' (duration: 124.495714ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:25:26.24951Z","caller":"traceutil/trace.go:171","msg":"trace[1615638338] transaction","detail":"{read_only:false; response_revision:2938; number_of_response:1; }","duration":"124.917983ms","start":"2026-09-18T02:25:26.124557Z","end":"2026-09-18T02:25:26.249475Z","steps":["trace[1615638338] 'process raft request' (duration: 124.718284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:25:26.263293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.251965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T02:25:26.263371Z","caller":"traceutil/trace.go:171","msg":"trace[2140356868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2939; }","duration":"128.365549ms","start":"2026-09-18T02:25:26.134989Z","end":"2026-09-18T02:25:26.263355Z","steps":["trace[2140356868] 'agreement among raft nodes before linearized reading' (duration: 128.16312ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:27:55.273327Z","caller":"traceutil/trace.go:171","msg":"trace[2134845332] transaction","detail":"{read_only:false; response_revision:4426; number_of_response:1; }","duration":"112.147355ms","start":"2026-09-18T02:27:55.161038Z","end":"2026-09-18T02:27:55.273186Z","steps":["trace[2134845332] 'process raft request' (duration: 110.726247ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:27:55.276998Z","caller":"traceutil/trace.go:171","msg":"trace[304390151] transaction","detail":"{read_only:false; response_revision:4427; number_of_response:1; }","duration":"115.744533ms","start":"2026-09-18T02:27:55.161232Z","end":"2026-09-18T02:27:55.276976Z","steps":["trace[304390151] 'process raft request' (duration: 115.586298ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:27:55.277332Z","caller":"traceutil/trace.go:171","msg":"trace[2006285665] transaction","detail":"{read_only:false; response_revision:4428; number_of_response:1; }","duration":"102.197747ms","start":"2026-09-18T02:27:55.175101Z","end":"2026-09-18T02:27:55.277298Z","steps":["trace[2006285665] 'process raft request' (duration: 101.830097ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:27:55.412866Z","caller":"traceutil/trace.go:171","msg":"trace[1398780199] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"125.919887ms","start":"2026-09-18T02:27:55.286918Z","end":"2026-09-18T02:27:55.412838Z","steps":["trace[1398780199] 'process raft request' (duration: 92.919067ms)","trace[1398780199] 'compare' (duration: 32.819805ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:27:55.413144Z","caller":"traceutil/trace.go:171","msg":"trace[1523941874] transaction","detail":"{read_only:false; response_revision:4432; number_of_response:1; }","duration":"119.827093ms","start":"2026-09-18T02:27:55.293306Z","end":"2026-09-18T02:27:55.413133Z","steps":["trace[1523941874] 'process raft request' (duration: 119.483894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:27:58.892691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.35541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f5bc2ae-3e73-4864-9b0b-c5fe2e61ee2e\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-09-18T02:27:58.892758Z","caller":"traceutil/trace.go:171","msg":"trace[1915061655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f5bc2ae-3e73-4864-9b0b-c5fe2e61ee2e; range_end:; response_count:1; response_revision:4574; }","duration":"109.463203ms","start":"2026-09-18T02:27:58.783282Z","end":"2026-09-18T02:27:58.892745Z","steps":["trace[1915061655] 'range keys from in-memory index tree' (duration: 109.210696ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:05.091368Z","caller":"traceutil/trace.go:171","msg":"trace[1864844455] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"112.824976ms","start":"2026-09-18T02:28:04.978521Z","end":"2026-09-18T02:28:05.091345Z","steps":["trace[1864844455] 'process raft request' (duration: 112.669202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:12.57235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.404869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:28:12.572444Z","caller":"traceutil/trace.go:171","msg":"trace[552556090] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:4826; }","duration":"178.551273ms","start":"2026-09-18T02:28:12.393873Z","end":"2026-09-18T02:28:12.572425Z","steps":["trace[552556090] 'count revisions from in-memory index tree' (duration: 178.270397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:12.572657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.928897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-18T02:28:12.572735Z","caller":"traceutil/trace.go:171","msg":"trace[510658461] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4826; }","duration":"156.04527ms","start":"2026-09-18T02:28:12.416672Z","end":"2026-09-18T02:28:12.572718Z","steps":["trace[510658461] 'range keys from in-memory index tree' (duration: 155.760283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:12.57314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.089657ms","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-18T02:28:12.573258Z","caller":"traceutil/trace.go:171","msg":"trace[425306816] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4826; }","duration":"209.258041ms","start":"2026-09-18T02:28:12.363979Z","end":"2026-09-18T02:28:12.573237Z","steps":["trace[425306816] 'range keys from in-memory index tree' (duration: 208.207364ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:12.863095Z","caller":"traceutil/trace.go:171","msg":"trace[369767243] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"172.945799ms","start":"2026-09-18T02:28:12.690113Z","end":"2026-09-18T02:28:12.863059Z","steps":["trace[369767243] 'process raft request' (duration: 172.806346ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:13.291472Z","caller":"traceutil/trace.go:171","msg":"trace[1349100225] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"322.857904ms","start":"2026-09-18T02:28:12.968592Z","end":"2026-09-18T02:28:13.29145Z","steps":["trace[1349100225] 'process raft request' (duration: 322.644208ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:13.291571Z","caller":"traceutil/trace.go:171","msg":"trace[341855219] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"322.307748ms","start":"2026-09-18T02:28:12.969244Z","end":"2026-09-18T02:28:13.291552Z","steps":["trace[341855219] 'process raft request' (duration: 322.090223ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.291628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:12.968585Z","time spent":"322.960406ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:28:13.291686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:12.969052Z","time spent":"322.575516ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:28:13.29174Z","caller":"traceutil/trace.go:171","msg":"trace[1585207492] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"318.720346ms","start":"2026-09-18T02:28:12.973006Z","end":"2026-09-18T02:28:13.291727Z","steps":["trace[1585207492] 'process raft request' (duration: 318.386277ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:13.291896Z","caller":"traceutil/trace.go:171","msg":"trace[1349199196] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"323.438498ms","start":"2026-09-18T02:28:12.968438Z","end":"2026-09-18T02:28:13.291876Z","steps":["trace[1349199196] 'process raft request' (duration: 242.997176ms)","trace[1349199196] 'compare' (duration: 79.60072ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:28:13.291936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:12.972993Z","time spent":"318.792627ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:28:13.291471Z","caller":"traceutil/trace.go:171","msg":"trace[946236228] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5045; }","duration":"297.441944ms","start":"2026-09-18T02:28:12.994004Z","end":"2026-09-18T02:28:13.291446Z","steps":["trace[946236228] 'read index received' (duration: 34.010502ms)","trace[946236228] 'applied index is now lower than readState.Index' (duration: 263.430082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:28:13.291691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.67553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-09-18T02:28:13.291989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:12.968417Z","time spent":"323.52978ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:28:13.292576Z","caller":"traceutil/trace.go:171","msg":"trace[1230148344] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4835; }","duration":"298.169752ms","start":"2026-09-18T02:28:12.993973Z","end":"2026-09-18T02:28:13.292142Z","steps":["trace[1230148344] 'agreement among raft nodes before linearized reading' (duration: 297.547046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.34955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.128104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efd7c4de-10d1-49ad-9759-10543d159a67\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-09-18T02:28:13.349613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.678241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-18T02:28:13.349644Z","caller":"traceutil/trace.go:171","msg":"trace[478926273] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efd7c4de-10d1-49ad-9759-10543d159a67; range_end:; response_count:1; response_revision:4837; }","duration":"124.267328ms","start":"2026-09-18T02:28:13.225355Z","end":"2026-09-18T02:28:13.349622Z","steps":["trace[478926273] 'agreement among raft nodes before linearized reading' (duration: 124.027822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.34964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.147058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T02:28:13.349703Z","caller":"traceutil/trace.go:171","msg":"trace[2057386802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4837; }","duration":"116.349273ms","start":"2026-09-18T02:28:13.233336Z","end":"2026-09-18T02:28:13.349685Z","steps":["trace[2057386802] 'agreement among raft nodes before linearized reading' (duration: 116.045665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.349984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.029613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-09-18T02:28:13.349996Z","caller":"traceutil/trace.go:171","msg":"trace[1306167512] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"317.414279ms","start":"2026-09-18T02:28:13.032555Z","end":"2026-09-18T02:28:13.34997Z","steps":["trace[1306167512] 'process raft request' (duration: 316.522377ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:13.350048Z","caller":"traceutil/trace.go:171","msg":"trace[1289705772] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4837; }","duration":"265.124677ms","start":"2026-09-18T02:28:13.084909Z","end":"2026-09-18T02:28:13.350033Z","steps":["trace[1289705772] 'agreement among raft nodes before linearized reading' (duration: 264.773417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.350128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:13.03254Z","time spent":"317.518312ms","remote":"127.0.0.1:47312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:28:13.34996Z","caller":"traceutil/trace.go:171","msg":"trace[223821911] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"237.91177ms","start":"2026-09-18T02:28:13.112004Z","end":"2026-09-18T02:28:13.349915Z","steps":["trace[223821911] 'process raft request' (duration: 237.243813ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.350308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.476759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:28:13.350393Z","caller":"traceutil/trace.go:171","msg":"trace[192238448] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4837; }","duration":"286.603672ms","start":"2026-09-18T02:28:13.063753Z","end":"2026-09-18T02:28:13.350356Z","steps":["trace[192238448] 'agreement among raft nodes before linearized reading' (duration: 286.470328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.349996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.455406ms","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-18T02:28:13.350502Z","caller":"traceutil/trace.go:171","msg":"trace[115422063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4837; }","duration":"102.012112ms","start":"2026-09-18T02:28:13.248475Z","end":"2026-09-18T02:28:13.350487Z","steps":["trace[115422063] 'agreement among raft nodes before linearized reading' (duration: 101.430466ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:13.349661Z","caller":"traceutil/trace.go:171","msg":"trace[1482500490] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4837; }","duration":"350.757603ms","start":"2026-09-18T02:28:12.998889Z","end":"2026-09-18T02:28:13.349646Z","steps":["trace[1482500490] 'agreement among raft nodes before linearized reading' (duration: 350.62607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.350662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:12.998872Z","time spent":"351.719188ms","remote":"127.0.0.1:41346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1843,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-09-18T02:28:13.593768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.255551ms","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-18T02:28:13.593892Z","caller":"traceutil/trace.go:171","msg":"trace[902115138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4839; }","duration":"189.426206ms","start":"2026-09-18T02:28:13.404442Z","end":"2026-09-18T02:28:13.593868Z","steps":["trace[902115138] 'range keys from in-memory index tree' (duration: 189.065546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:13.593779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.743769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-09-18T02:28:13.593999Z","caller":"traceutil/trace.go:171","msg":"trace[314233952] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4839; }","duration":"201.010605ms","start":"2026-09-18T02:28:13.392958Z","end":"2026-09-18T02:28:13.593969Z","steps":["trace[314233952] 'range keys from in-memory index tree' (duration: 200.537784ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:13.603632Z","caller":"traceutil/trace.go:171","msg":"trace[706358535] transaction","detail":"{read_only:false; response_revision:4840; number_of_response:1; }","duration":"178.208975ms","start":"2026-09-18T02:28:13.425406Z","end":"2026-09-18T02:28:13.603615Z","steps":["trace[706358535] 'process raft request' (duration: 178.078712ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:23.958331Z","caller":"traceutil/trace.go:171","msg":"trace[413421036] linearizableReadLoop","detail":"{readStateIndex:5218; appliedIndex:5217; }","duration":"120.698011ms","start":"2026-09-18T02:28:23.837606Z","end":"2026-09-18T02:28:23.958304Z","steps":["trace[413421036] 'read index received' (duration: 120.472255ms)","trace[413421036] 'applied index is now lower than readState.Index' (duration: 224.185µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:28:23.958479Z","caller":"traceutil/trace.go:171","msg":"trace[809787991] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"226.877172ms","start":"2026-09-18T02:28:23.731588Z","end":"2026-09-18T02:28:23.958465Z","steps":["trace[809787991] 'process raft request' (duration: 226.472172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:23.958697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.9339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:28:23.958773Z","caller":"traceutil/trace.go:171","msg":"trace[452263765] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4996; }","duration":"120.059994ms","start":"2026-09-18T02:28:23.838694Z","end":"2026-09-18T02:28:23.958754Z","steps":["trace[452263765] 'agreement among raft nodes before linearized reading' (duration: 119.90234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:23.958776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.156552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:28:23.958878Z","caller":"traceutil/trace.go:171","msg":"trace[901835757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4996; }","duration":"121.252154ms","start":"2026-09-18T02:28:23.837573Z","end":"2026-09-18T02:28:23.958825Z","steps":["trace[901835757] 'agreement among raft nodes before linearized reading' (duration: 121.114391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:23.959156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.82051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-09-18T02:28:23.959221Z","caller":"traceutil/trace.go:171","msg":"trace[2060752019] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4996; }","duration":"106.975314ms","start":"2026-09-18T02:28:23.852231Z","end":"2026-09-18T02:28:23.959206Z","steps":["trace[2060752019] 'agreement among raft nodes before linearized reading' (duration: 106.682196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:24.401432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.354852ms","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-18T02:28:24.401579Z","caller":"traceutil/trace.go:171","msg":"trace[1076877751] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4996; }","duration":"402.541586ms","start":"2026-09-18T02:28:23.99902Z","end":"2026-09-18T02:28:24.401562Z","steps":["trace[1076877751] 'range keys from in-memory index tree' (duration: 402.136736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:24.401656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:23.998985Z","time spent":"402.648389ms","remote":"127.0.0.1:41442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10538,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-18T02:28:24.401674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.877219ms","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-18T02:28:24.402061Z","caller":"traceutil/trace.go:171","msg":"trace[1291787235] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4996; }","duration":"400.266188ms","start":"2026-09-18T02:28:24.001742Z","end":"2026-09-18T02:28:24.402008Z","steps":["trace[1291787235] 'range keys from in-memory index tree' (duration: 399.656534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:24.402163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:24.001729Z","time spent":"400.417573ms","remote":"127.0.0.1:41416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-18T02:28:24.460873Z","caller":"traceutil/trace.go:171","msg":"trace[746077108] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"436.847244ms","start":"2026-09-18T02:28:24.024001Z","end":"2026-09-18T02:28:24.460848Z","steps":["trace[746077108] 'process raft request' (duration: 436.429854ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:24.46101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:24.023977Z","time spent":"436.963887ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:28:24.835077Z","caller":"traceutil/trace.go:171","msg":"trace[1150783559] linearizableReadLoop","detail":"{readStateIndex:5229; appliedIndex:5228; }","duration":"218.999111ms","start":"2026-09-18T02:28:24.616049Z","end":"2026-09-18T02:28:24.835048Z","steps":["trace[1150783559] 'read index received' (duration: 218.838487ms)","trace[1150783559] 'applied index is now lower than readState.Index' (duration: 159.594µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:28:24.835373Z","caller":"traceutil/trace.go:171","msg":"trace[2121327115] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"242.694131ms","start":"2026-09-18T02:28:24.592649Z","end":"2026-09-18T02:28:24.835343Z","steps":["trace[2121327115] 'process raft request' (duration: 242.199008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:24.835684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.772323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-09-18T02:28:24.835756Z","caller":"traceutil/trace.go:171","msg":"trace[1675840778] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5006; }","duration":"242.886426ms","start":"2026-09-18T02:28:24.592853Z","end":"2026-09-18T02:28:24.83574Z","steps":["trace[1675840778] 'agreement among raft nodes before linearized reading' (duration: 242.328872ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:24.93666Z","caller":"traceutil/trace.go:171","msg":"trace[561711242] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"313.513213ms","start":"2026-09-18T02:28:24.623119Z","end":"2026-09-18T02:28:24.936632Z","steps":["trace[561711242] 'process raft request' (duration: 313.186005ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:24.936816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:24.623059Z","time spent":"313.666957ms","remote":"127.0.0.1:41426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:28:24.973595Z","caller":"traceutil/trace.go:171","msg":"trace[1117327691] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"350.173891ms","start":"2026-09-18T02:28:24.623395Z","end":"2026-09-18T02:28:24.973569Z","steps":["trace[1117327691] 'process raft request' (duration: 350.000916ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:24.973656Z","caller":"traceutil/trace.go:171","msg":"trace[1973277446] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"350.389766ms","start":"2026-09-18T02:28:24.623242Z","end":"2026-09-18T02:28:24.973632Z","steps":["trace[1973277446] 'process raft request' (duration: 313.37799ms)","trace[1973277446] 'compare' (duration: 36.611286ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:28:24.973745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:24.623387Z","time spent":"350.279983ms","remote":"127.0.0.1:41534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1563,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:28:24.973818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:24.623215Z","time spent":"350.520119ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:28:24.973643Z","caller":"traceutil/trace.go:171","msg":"trace[208583545] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"346.943829ms","start":"2026-09-18T02:28:24.626669Z","end":"2026-09-18T02:28:24.973612Z","steps":["trace[208583545] 'process raft request' (duration: 346.793165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:28:24.97505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:28:24.626636Z","time spent":"348.361355ms","remote":"127.0.0.1:41754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:28:24.97465Z","caller":"traceutil/trace.go:171","msg":"trace[1749663474] linearizableReadLoop","detail":"{readStateIndex:5235; appliedIndex:5229; }","duration":"107.176543ms","start":"2026-09-18T02:28:24.867454Z","end":"2026-09-18T02:28:24.974631Z","steps":["trace[1749663474] 'read index received' (duration: 44.23133ms)","trace[1749663474] 'applied index is now lower than readState.Index' (duration: 62.943733ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:28:24.97481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.348557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-09-18T02:28:24.975329Z","caller":"traceutil/trace.go:171","msg":"trace[243821041] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5011; }","duration":"107.899899ms","start":"2026-09-18T02:28:24.867415Z","end":"2026-09-18T02:28:24.975315Z","steps":["trace[243821041] 'agreement among raft nodes before linearized reading' (duration: 107.270984ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:28:24.974886Z","caller":"traceutil/trace.go:171","msg":"trace[1001146079] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"130.104073ms","start":"2026-09-18T02:28:24.844764Z","end":"2026-09-18T02:28:24.974868Z","steps":["trace[1001146079] 'process raft request' (duration: 128.759108ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:30:36.079242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3006} {"level":"info","ts":"2026-09-18T02:30:36.173201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3006,"took":"92.153653ms","hash":1880651631,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":30711808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-18T02:30:36.173266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1880651631,"revision":3006,"compact-revision":-1} {"level":"info","ts":"2026-09-18T02:31:35.417566Z","caller":"traceutil/trace.go:171","msg":"trace[1240546786] linearizableReadLoop","detail":"{readStateIndex:6924; appliedIndex:6923; }","duration":"119.350962ms","start":"2026-09-18T02:31:35.298183Z","end":"2026-09-18T02:31:35.417534Z","steps":["trace[1240546786] 'read index received' (duration: 87.10906ms)","trace[1240546786] 'applied index is now lower than readState.Index' (duration: 32.240832ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:35.41771Z","caller":"traceutil/trace.go:171","msg":"trace[1608759931] transaction","detail":"{read_only:false; response_revision:6618; number_of_response:1; }","duration":"142.98234ms","start":"2026-09-18T02:31:35.274706Z","end":"2026-09-18T02:31:35.417688Z","steps":["trace[1608759931] 'process raft request' (duration: 110.688477ms)","trace[1608759931] 'compare' (duration: 32.018017ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:35.418541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.593016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-09-18T02:31:35.418624Z","caller":"traceutil/trace.go:171","msg":"trace[646333124] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6618; }","duration":"120.460424ms","start":"2026-09-18T02:31:35.298128Z","end":"2026-09-18T02:31:35.418588Z","steps":["trace[646333124] 'agreement among raft nodes before linearized reading' (duration: 119.527365ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:40.538164Z","caller":"traceutil/trace.go:171","msg":"trace[658549061] transaction","detail":"{read_only:false; number_of_response:0; response_revision:6645; }","duration":"171.586107ms","start":"2026-09-18T02:31:40.366559Z","end":"2026-09-18T02:31:40.538145Z","steps":["trace[658549061] 'process raft request' (duration: 144.918159ms)","trace[658549061] 'compare' (duration: 26.552106ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:40.907403Z","caller":"traceutil/trace.go:171","msg":"trace[1103291867] transaction","detail":"{read_only:false; response_revision:6647; number_of_response:1; }","duration":"359.392282ms","start":"2026-09-18T02:31:40.547983Z","end":"2026-09-18T02:31:40.907375Z","steps":["trace[1103291867] 'process raft request' (duration: 359.188598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:40.908046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:40.547958Z","time spent":"359.517304ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:31:40.92394Z","caller":"traceutil/trace.go:171","msg":"trace[1247546192] linearizableReadLoop","detail":"{readStateIndex:6956; appliedIndex:6954; }","duration":"184.316334ms","start":"2026-09-18T02:31:40.739599Z","end":"2026-09-18T02:31:40.923916Z","steps":["trace[1247546192] 'read index received' (duration: 167.617228ms)","trace[1247546192] 'applied index is now lower than readState.Index' (duration: 16.698226ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:40.924159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.538269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:31:40.924223Z","caller":"traceutil/trace.go:171","msg":"trace[1181776355] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:6648; }","duration":"184.65513ms","start":"2026-09-18T02:31:40.739552Z","end":"2026-09-18T02:31:40.924207Z","steps":["trace[1181776355] 'agreement among raft nodes before linearized reading' (duration: 184.548008ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:40.924394Z","caller":"traceutil/trace.go:171","msg":"trace[788370066] transaction","detail":"{read_only:false; response_revision:6648; number_of_response:1; }","duration":"286.655325ms","start":"2026-09-18T02:31:40.6377Z","end":"2026-09-18T02:31:40.924355Z","steps":["trace[788370066] 'process raft request' (duration: 286.091064ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:41.297294Z","caller":"traceutil/trace.go:171","msg":"trace[1304483319] transaction","detail":"{read_only:false; response_revision:6651; number_of_response:1; }","duration":"107.23789ms","start":"2026-09-18T02:31:41.190013Z","end":"2026-09-18T02:31:41.29725Z","steps":["trace[1304483319] 'process raft request' (duration: 107.087957ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:41.356228Z","caller":"traceutil/trace.go:171","msg":"trace[1040617522] linearizableReadLoop","detail":"{readStateIndex:6960; appliedIndex:6958; }","duration":"127.060149ms","start":"2026-09-18T02:31:41.229154Z","end":"2026-09-18T02:31:41.356214Z","steps":["trace[1040617522] 'read index received' (duration: 67.958879ms)","trace[1040617522] 'applied index is now lower than readState.Index' (duration: 59.1006ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:41.356972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.804234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:31:41.3572Z","caller":"traceutil/trace.go:171","msg":"trace[504736339] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:6652; }","duration":"128.074989ms","start":"2026-09-18T02:31:41.229113Z","end":"2026-09-18T02:31:41.357188Z","steps":["trace[504736339] 'agreement among raft nodes before linearized reading' (duration: 127.820814ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:41.357356Z","caller":"traceutil/trace.go:171","msg":"trace[1166776264] transaction","detail":"{read_only:false; response_revision:6652; number_of_response:1; }","duration":"161.887381ms","start":"2026-09-18T02:31:41.195442Z","end":"2026-09-18T02:31:41.357329Z","steps":["trace[1166776264] 'process raft request' (duration: 160.676686ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:41.736725Z","caller":"traceutil/trace.go:171","msg":"trace[1980173788] transaction","detail":"{read_only:false; response_revision:6655; number_of_response:1; }","duration":"105.396473ms","start":"2026-09-18T02:31:41.6313Z","end":"2026-09-18T02:31:41.736696Z","steps":["trace[1980173788] 'process raft request' (duration: 104.898033ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:42.336425Z","caller":"traceutil/trace.go:171","msg":"trace[547342192] transaction","detail":"{read_only:false; response_revision:6658; number_of_response:1; }","duration":"101.199236ms","start":"2026-09-18T02:31:42.235203Z","end":"2026-09-18T02:31:42.336402Z","steps":["trace[547342192] 'process raft request' (duration: 101.037963ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:43.51015Z","caller":"traceutil/trace.go:171","msg":"trace[505255931] linearizableReadLoop","detail":"{readStateIndex:6975; appliedIndex:6973; }","duration":"149.662641ms","start":"2026-09-18T02:31:43.36047Z","end":"2026-09-18T02:31:43.510133Z","steps":["trace[505255931] 'read index received' (duration: 13.151455ms)","trace[505255931] 'applied index is now lower than readState.Index' (duration: 136.510506ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:43.510209Z","caller":"traceutil/trace.go:171","msg":"trace[641758548] transaction","detail":"{read_only:false; response_revision:6666; number_of_response:1; }","duration":"150.322174ms","start":"2026-09-18T02:31:43.359855Z","end":"2026-09-18T02:31:43.510177Z","steps":["trace[641758548] 'process raft request' (duration: 150.15304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:43.510366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.876275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-18T02:31:43.510414Z","caller":"traceutil/trace.go:171","msg":"trace[48578685] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6666; }","duration":"149.964727ms","start":"2026-09-18T02:31:43.360439Z","end":"2026-09-18T02:31:43.510404Z","steps":["trace[48578685] 'agreement among raft nodes before linearized reading' (duration: 149.766633ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:43.715312Z","caller":"traceutil/trace.go:171","msg":"trace[2132140263] transaction","detail":"{read_only:false; response_revision:6678; number_of_response:1; }","duration":"102.826509ms","start":"2026-09-18T02:31:43.612461Z","end":"2026-09-18T02:31:43.715287Z","steps":["trace[2132140263] 'process raft request' (duration: 102.768558ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:43.715383Z","caller":"traceutil/trace.go:171","msg":"trace[239671041] transaction","detail":"{read_only:false; response_revision:6677; number_of_response:1; }","duration":"105.376221ms","start":"2026-09-18T02:31:43.610001Z","end":"2026-09-18T02:31:43.715377Z","steps":["trace[239671041] 'process raft request' (duration: 105.186297ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:43.715346Z","caller":"traceutil/trace.go:171","msg":"trace[327352332] transaction","detail":"{read_only:false; response_revision:6676; number_of_response:1; }","duration":"105.86396ms","start":"2026-09-18T02:31:43.609464Z","end":"2026-09-18T02:31:43.715328Z","steps":["trace[327352332] 'process raft request' (duration: 105.604074ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:43.793033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.926155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-18T02:31:43.796319Z","caller":"traceutil/trace.go:171","msg":"trace[1451773273] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:6680; }","duration":"140.249071ms","start":"2026-09-18T02:31:43.656051Z","end":"2026-09-18T02:31:43.7963Z","steps":["trace[1451773273] 'agreement among raft nodes before linearized reading' (duration: 136.830473ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.101809Z","caller":"traceutil/trace.go:171","msg":"trace[1899296044] transaction","detail":"{read_only:false; response_revision:6692; number_of_response:1; }","duration":"184.592722ms","start":"2026-09-18T02:31:43.917184Z","end":"2026-09-18T02:31:44.101777Z","steps":["trace[1899296044] 'process raft request' (duration: 170.155071ms)","trace[1899296044] 'compare' (duration: 14.156825ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:44.101931Z","caller":"traceutil/trace.go:171","msg":"trace[203466666] linearizableReadLoop","detail":"{readStateIndex:7002; appliedIndex:6999; }","duration":"182.031741ms","start":"2026-09-18T02:31:43.919874Z","end":"2026-09-18T02:31:44.101906Z","steps":["trace[203466666] 'read index received' (duration: 33.679337ms)","trace[203466666] 'applied index is now lower than readState.Index' (duration: 148.350924ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:44.101957Z","caller":"traceutil/trace.go:171","msg":"trace[1201983975] transaction","detail":"{read_only:false; response_revision:6693; number_of_response:1; }","duration":"182.782526ms","start":"2026-09-18T02:31:43.919152Z","end":"2026-09-18T02:31:44.101935Z","steps":["trace[1201983975] 'process raft request' (duration: 182.566892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:44.102203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.299096ms","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-09-18T02:31:44.102322Z","caller":"traceutil/trace.go:171","msg":"trace[2089753769] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:6693; }","duration":"182.440298ms","start":"2026-09-18T02:31:43.91986Z","end":"2026-09-18T02:31:44.1023Z","steps":["trace[2089753769] 'agreement among raft nodes before linearized reading' (duration: 182.184163ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.301146Z","caller":"traceutil/trace.go:171","msg":"trace[1551308762] linearizableReadLoop","detail":"{readStateIndex:7004; appliedIndex:7002; }","duration":"194.375439ms","start":"2026-09-18T02:31:44.106754Z","end":"2026-09-18T02:31:44.30113Z","steps":["trace[1551308762] 'read index received' (duration: 74.503688ms)","trace[1551308762] 'applied index is now lower than readState.Index' (duration: 119.871121ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:44.301527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.757506ms","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-09-18T02:31:44.301561Z","caller":"traceutil/trace.go:171","msg":"trace[1181156310] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:6695; }","duration":"194.821497ms","start":"2026-09-18T02:31:44.106729Z","end":"2026-09-18T02:31:44.301551Z","steps":["trace[1181156310] 'agreement among raft nodes before linearized reading' (duration: 194.47439ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.301769Z","caller":"traceutil/trace.go:171","msg":"trace[1233818341] transaction","detail":"{read_only:false; response_revision:6695; number_of_response:1; }","duration":"195.391768ms","start":"2026-09-18T02:31:44.105815Z","end":"2026-09-18T02:31:44.301207Z","steps":["trace[1233818341] 'process raft request' (duration: 194.900259ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.311982Z","caller":"traceutil/trace.go:171","msg":"trace[503378805] transaction","detail":"{read_only:false; response_revision:6696; number_of_response:1; }","duration":"126.31594ms","start":"2026-09-18T02:31:44.185632Z","end":"2026-09-18T02:31:44.311947Z","steps":["trace[503378805] 'process raft request' (duration: 126.148007ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.312018Z","caller":"traceutil/trace.go:171","msg":"trace[185688070] transaction","detail":"{read_only:false; response_revision:6697; number_of_response:1; }","duration":"126.138466ms","start":"2026-09-18T02:31:44.185868Z","end":"2026-09-18T02:31:44.312007Z","steps":["trace[185688070] 'process raft request' (duration: 125.987723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:44.312278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.988829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-09-18T02:31:44.312372Z","caller":"traceutil/trace.go:171","msg":"trace[99442444] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:6697; }","duration":"179.103502ms","start":"2026-09-18T02:31:44.133242Z","end":"2026-09-18T02:31:44.312346Z","steps":["trace[99442444] 'agreement among raft nodes before linearized reading' (duration: 178.815816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:44.528897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.038873ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-18T02:31:44.529212Z","caller":"traceutil/trace.go:171","msg":"trace[1505157338] transaction","detail":"{read_only:false; response_revision:6703; number_of_response:1; }","duration":"130.307789ms","start":"2026-09-18T02:31:44.398886Z","end":"2026-09-18T02:31:44.529194Z","steps":["trace[1505157338] 'process raft request' (duration: 130.172087ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.529306Z","caller":"traceutil/trace.go:171","msg":"trace[1392308143] linearizableReadLoop","detail":"{readStateIndex:7012; appliedIndex:7011; }","duration":"137.629307ms","start":"2026-09-18T02:31:44.391624Z","end":"2026-09-18T02:31:44.529253Z","steps":["trace[1392308143] 'read index received' (duration: 15.255177ms)","trace[1392308143] 'applied index is now lower than readState.Index' (duration: 122.37204ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:44.529597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.527646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-09-18T02:31:44.529694Z","caller":"traceutil/trace.go:171","msg":"trace[609024511] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:6703; }","duration":"153.648819ms","start":"2026-09-18T02:31:44.376033Z","end":"2026-09-18T02:31:44.529682Z","steps":["trace[609024511] 'agreement among raft nodes before linearized reading' (duration: 153.434995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:44.529597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.456752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-18T02:31:44.529825Z","caller":"traceutil/trace.go:171","msg":"trace[1650298265] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:6703; }","duration":"132.697987ms","start":"2026-09-18T02:31:44.397097Z","end":"2026-09-18T02:31:44.529795Z","steps":["trace[1650298265] 'agreement among raft nodes before linearized reading' (duration: 132.403131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:44.52963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.641386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T02:31:44.529707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.288368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:31:44.530084Z","caller":"traceutil/trace.go:171","msg":"trace[813017828] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:6703; }","duration":"117.715398ms","start":"2026-09-18T02:31:44.412359Z","end":"2026-09-18T02:31:44.530075Z","steps":["trace[813017828] 'agreement among raft nodes before linearized reading' (duration: 117.251398ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.530028Z","caller":"traceutil/trace.go:171","msg":"trace[190795668] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-init; range_end:; response_count:0; response_revision:6703; }","duration":"124.980233ms","start":"2026-09-18T02:31:44.404945Z","end":"2026-09-18T02:31:44.529925Z","steps":["trace[190795668] 'agreement among raft nodes before linearized reading' (duration: 124.668127ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.66231Z","caller":"traceutil/trace.go:171","msg":"trace[170637996] linearizableReadLoop","detail":"{readStateIndex:7014; appliedIndex:7013; }","duration":"127.177147ms","start":"2026-09-18T02:31:44.535077Z","end":"2026-09-18T02:31:44.662254Z","steps":["trace[170637996] 'read index received' (duration: 33.538284ms)","trace[170637996] 'applied index is now lower than readState.Index' (duration: 93.637943ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:44.66236Z","caller":"traceutil/trace.go:171","msg":"trace[1471050310] transaction","detail":"{read_only:false; response_revision:6704; number_of_response:1; }","duration":"127.549654ms","start":"2026-09-18T02:31:44.534777Z","end":"2026-09-18T02:31:44.662327Z","steps":["trace[1471050310] 'process raft request' (duration: 33.83137ms)","trace[1471050310] 'compare' (duration: 93.50678ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:44.662601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.500503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-18T02:31:44.662664Z","caller":"traceutil/trace.go:171","msg":"trace[1037498125] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:6704; }","duration":"127.596005ms","start":"2026-09-18T02:31:44.535053Z","end":"2026-09-18T02:31:44.662649Z","steps":["trace[1037498125] 'agreement among raft nodes before linearized reading' (duration: 127.34262ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.690421Z","caller":"traceutil/trace.go:171","msg":"trace[1835282604] transaction","detail":"{read_only:false; response_revision:6705; number_of_response:1; }","duration":"154.246371ms","start":"2026-09-18T02:31:44.536142Z","end":"2026-09-18T02:31:44.690388Z","steps":["trace[1835282604] 'process raft request' (duration: 153.771852ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.690851Z","caller":"traceutil/trace.go:171","msg":"trace[411518161] transaction","detail":"{read_only:false; response_revision:6706; number_of_response:1; }","duration":"152.148589ms","start":"2026-09-18T02:31:44.538686Z","end":"2026-09-18T02:31:44.690834Z","steps":["trace[411518161] 'process raft request' (duration: 151.325563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:44.691033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.291482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-18T02:31:44.691105Z","caller":"traceutil/trace.go:171","msg":"trace[1956661386] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:6707; }","duration":"152.394514ms","start":"2026-09-18T02:31:44.538701Z","end":"2026-09-18T02:31:44.691095Z","steps":["trace[1956661386] 'agreement among raft nodes before linearized reading' (duration: 152.146338ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.691362Z","caller":"traceutil/trace.go:171","msg":"trace[1912520025] transaction","detail":"{read_only:false; response_revision:6707; number_of_response:1; }","duration":"128.161687ms","start":"2026-09-18T02:31:44.56319Z","end":"2026-09-18T02:31:44.691352Z","steps":["trace[1912520025] 'process raft request' (duration: 126.889891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:44.932192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.849025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T02:31:44.932315Z","caller":"traceutil/trace.go:171","msg":"trace[297695976] linearizableReadLoop","detail":"{readStateIndex:7022; appliedIndex:7021; }","duration":"187.396948ms","start":"2026-09-18T02:31:44.744902Z","end":"2026-09-18T02:31:44.932299Z","steps":["trace[297695976] 'read index received' (duration: 25.183677ms)","trace[297695976] 'applied index is now lower than readState.Index' (duration: 162.211181ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:44.932384Z","caller":"traceutil/trace.go:171","msg":"trace[1255082454] transaction","detail":"{read_only:false; response_revision:6712; number_of_response:1; }","duration":"197.875008ms","start":"2026-09-18T02:31:44.734496Z","end":"2026-09-18T02:31:44.932371Z","steps":["trace[1255082454] 'process raft request' (duration: 35.580834ms)","trace[1255082454] 'compare' (duration: 161.64968ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:44.932756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.672724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-77dc8646f4-b6wgq\" ","response":"range_response_count:1 size:8088"} {"level":"warn","ts":"2026-09-18T02:31:44.932779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.510551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-09-18T02:31:44.932825Z","caller":"traceutil/trace.go:171","msg":"trace[769720456] range","detail":"{range_begin:/registry/pods/openstack/horizon-77dc8646f4-b6wgq; range_end:; response_count:1; response_revision:6712; }","duration":"199.778476ms","start":"2026-09-18T02:31:44.733031Z","end":"2026-09-18T02:31:44.932809Z","steps":["trace[769720456] 'agreement among raft nodes before linearized reading' (duration: 199.566222ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.932838Z","caller":"traceutil/trace.go:171","msg":"trace[111126626] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:6712; }","duration":"199.627293ms","start":"2026-09-18T02:31:44.733192Z","end":"2026-09-18T02:31:44.932819Z","steps":["trace[111126626] 'agreement among raft nodes before linearized reading' (duration: 199.404169ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.965435Z","caller":"traceutil/trace.go:171","msg":"trace[320831439] transaction","detail":"{read_only:false; response_revision:6716; number_of_response:1; }","duration":"213.94153ms","start":"2026-09-18T02:31:44.751455Z","end":"2026-09-18T02:31:44.965396Z","steps":["trace[320831439] 'process raft request' (duration: 213.870979ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.965491Z","caller":"traceutil/trace.go:171","msg":"trace[218788374] transaction","detail":"{read_only:false; response_revision:6714; number_of_response:1; }","duration":"218.76285ms","start":"2026-09-18T02:31:44.746704Z","end":"2026-09-18T02:31:44.965466Z","steps":["trace[218788374] 'process raft request' (duration: 218.516014ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.965595Z","caller":"traceutil/trace.go:171","msg":"trace[1416578621] transaction","detail":"{read_only:false; response_revision:6715; number_of_response:1; }","duration":"218.818271ms","start":"2026-09-18T02:31:44.746762Z","end":"2026-09-18T02:31:44.96558Z","steps":["trace[1416578621] 'process raft request' (duration: 218.533514ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:44.965341Z","caller":"traceutil/trace.go:171","msg":"trace[24659252] transaction","detail":"{read_only:false; response_revision:6713; number_of_response:1; }","duration":"218.901641ms","start":"2026-09-18T02:31:44.746405Z","end":"2026-09-18T02:31:44.965306Z","steps":["trace[24659252] 'process raft request' (duration: 218.699948ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.076409Z","caller":"traceutil/trace.go:171","msg":"trace[1476314628] linearizableReadLoop","detail":"{readStateIndex:7027; appliedIndex:7022; }","duration":"144.037976ms","start":"2026-09-18T02:31:44.932338Z","end":"2026-09-18T02:31:45.076376Z","steps":["trace[1476314628] 'read index received' (duration: 32.739768ms)","trace[1476314628] 'applied index is now lower than readState.Index' (duration: 111.296548ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:45.076449Z","caller":"traceutil/trace.go:171","msg":"trace[1365467711] transaction","detail":"{read_only:false; response_revision:6718; number_of_response:1; }","duration":"131.334841ms","start":"2026-09-18T02:31:44.945087Z","end":"2026-09-18T02:31:45.076422Z","steps":["trace[1365467711] 'process raft request' (duration: 131.185788ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.076483Z","caller":"traceutil/trace.go:171","msg":"trace[722613535] transaction","detail":"{read_only:false; response_revision:6717; number_of_response:1; }","duration":"145.873552ms","start":"2026-09-18T02:31:44.930584Z","end":"2026-09-18T02:31:45.076457Z","steps":["trace[722613535] 'process raft request' (duration: 112.722716ms)","trace[722613535] 'compare' (duration: 32.81524ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:45.076675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.390521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/horizon\" ","response":"range_response_count:1 size:517"} {"level":"info","ts":"2026-09-18T02:31:45.07674Z","caller":"traceutil/trace.go:171","msg":"trace[1047001709] range","detail":"{range_begin:/registry/serviceaccounts/openstack/horizon; range_end:; response_count:1; response_revision:6718; }","duration":"222.527673ms","start":"2026-09-18T02:31:44.854198Z","end":"2026-09-18T02:31:45.076725Z","steps":["trace[1047001709] 'agreement among raft nodes before linearized reading' (duration: 222.284279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:45.076904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.237658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-09-18T02:31:45.076964Z","caller":"traceutil/trace.go:171","msg":"trace[1899253738] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:6718; }","duration":"322.32488ms","start":"2026-09-18T02:31:44.754621Z","end":"2026-09-18T02:31:45.076946Z","steps":["trace[1899253738] 'agreement among raft nodes before linearized reading' (duration: 322.102855ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:45.077057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:44.754609Z","time spent":"322.431042ms","remote":"127.0.0.1:34794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4748,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-09-18T02:31:45.077368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.587895ms","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-09-18T02:31:45.07748Z","caller":"traceutil/trace.go:171","msg":"trace[1531977213] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:6718; }","duration":"329.73247ms","start":"2026-09-18T02:31:44.747729Z","end":"2026-09-18T02:31:45.077461Z","steps":["trace[1531977213] 'agreement among raft nodes before linearized reading' (duration: 329.149917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:45.077561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:44.74772Z","time spent":"329.824161ms","remote":"127.0.0.1:41346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":718,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" "} {"level":"info","ts":"2026-09-18T02:31:45.147778Z","caller":"traceutil/trace.go:171","msg":"trace[1193744595] transaction","detail":"{read_only:false; response_revision:6722; number_of_response:1; }","duration":"172.989307ms","start":"2026-09-18T02:31:44.97477Z","end":"2026-09-18T02:31:45.147759Z","steps":["trace[1193744595] 'process raft request' (duration: 172.933656ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.147837Z","caller":"traceutil/trace.go:171","msg":"trace[568020641] transaction","detail":"{read_only:false; response_revision:6719; number_of_response:1; }","duration":"175.398476ms","start":"2026-09-18T02:31:44.972407Z","end":"2026-09-18T02:31:45.147805Z","steps":["trace[568020641] 'process raft request' (duration: 175.137651ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.147865Z","caller":"traceutil/trace.go:171","msg":"trace[421272921] transaction","detail":"{read_only:false; response_revision:6721; number_of_response:1; }","duration":"174.233122ms","start":"2026-09-18T02:31:44.973598Z","end":"2026-09-18T02:31:45.147831Z","steps":["trace[421272921] 'process raft request' (duration: 174.083769ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.148081Z","caller":"traceutil/trace.go:171","msg":"trace[939541868] transaction","detail":"{read_only:false; response_revision:6723; number_of_response:1; }","duration":"172.974977ms","start":"2026-09-18T02:31:44.97509Z","end":"2026-09-18T02:31:45.148065Z","steps":["trace[939541868] 'process raft request' (duration: 172.64303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:45.148261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.20827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/horizon\" ","response":"range_response_count:1 size:8919"} {"level":"warn","ts":"2026-09-18T02:31:45.148289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.534159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T02:31:45.148303Z","caller":"traceutil/trace.go:171","msg":"trace[637161838] range","detail":"{range_begin:/registry/deployments/openstack/horizon; range_end:; response_count:1; response_revision:6724; }","duration":"167.293541ms","start":"2026-09-18T02:31:44.980998Z","end":"2026-09-18T02:31:45.148292Z","steps":["trace[637161838] 'agreement among raft nodes before linearized reading' (duration: 167.140108ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.148319Z","caller":"traceutil/trace.go:171","msg":"trace[506439885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6724; }","duration":"187.609171ms","start":"2026-09-18T02:31:44.9607Z","end":"2026-09-18T02:31:45.148309Z","steps":["trace[506439885] 'agreement among raft nodes before linearized reading' (duration: 187.483438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:45.148486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.601592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-18T02:31:45.148163Z","caller":"traceutil/trace.go:171","msg":"trace[731007466] transaction","detail":"{read_only:false; response_revision:6720; number_of_response:1; }","duration":"174.987606ms","start":"2026-09-18T02:31:44.973152Z","end":"2026-09-18T02:31:45.14814Z","steps":["trace[731007466] 'process raft request' (duration: 174.478436ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.148541Z","caller":"traceutil/trace.go:171","msg":"trace[228187678] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:6724; }","duration":"204.664503ms","start":"2026-09-18T02:31:44.943862Z","end":"2026-09-18T02:31:45.148526Z","steps":["trace[228187678] 'agreement among raft nodes before linearized reading' (duration: 204.52109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:45.149146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.257096ms","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-09-18T02:31:45.149189Z","caller":"traceutil/trace.go:171","msg":"trace[626748144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6724; }","duration":"101.331277ms","start":"2026-09-18T02:31:45.047847Z","end":"2026-09-18T02:31:45.149178Z","steps":["trace[626748144] 'agreement among raft nodes before linearized reading' (duration: 101.231225ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.148492Z","caller":"traceutil/trace.go:171","msg":"trace[2043401191] transaction","detail":"{read_only:false; response_revision:6724; number_of_response:1; }","duration":"170.852144ms","start":"2026-09-18T02:31:44.977632Z","end":"2026-09-18T02:31:45.148485Z","steps":["trace[2043401191] 'process raft request' (duration: 170.319814ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.369057Z","caller":"traceutil/trace.go:171","msg":"trace[2090771067] transaction","detail":"{read_only:false; response_revision:6738; number_of_response:1; }","duration":"100.557231ms","start":"2026-09-18T02:31:45.268482Z","end":"2026-09-18T02:31:45.369039Z","steps":["trace[2090771067] 'process raft request' (duration: 100.395928ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.369568Z","caller":"traceutil/trace.go:171","msg":"trace[2113214433] linearizableReadLoop","detail":"{readStateIndex:7048; appliedIndex:7048; }","duration":"100.859987ms","start":"2026-09-18T02:31:45.268697Z","end":"2026-09-18T02:31:45.369557Z","steps":["trace[2113214433] 'read index received' (duration: 100.855617ms)","trace[2113214433] 'applied index is now lower than readState.Index' (duration: 3.53µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:45.369693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.982919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-18T02:31:45.369721Z","caller":"traceutil/trace.go:171","msg":"trace[1192330370] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:6738; }","duration":"101.02093ms","start":"2026-09-18T02:31:45.268691Z","end":"2026-09-18T02:31:45.369712Z","steps":["trace[1192330370] 'agreement among raft nodes before linearized reading' (duration: 100.919018ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:45.406304Z","caller":"traceutil/trace.go:171","msg":"trace[712935617] transaction","detail":"{read_only:false; response_revision:6739; number_of_response:1; }","duration":"137.526263ms","start":"2026-09-18T02:31:45.268732Z","end":"2026-09-18T02:31:45.406259Z","steps":["trace[712935617] 'process raft request' (duration: 136.974852ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:46.008444Z","caller":"traceutil/trace.go:171","msg":"trace[673797675] linearizableReadLoop","detail":"{readStateIndex:7055; appliedIndex:7054; }","duration":"315.597002ms","start":"2026-09-18T02:31:45.692821Z","end":"2026-09-18T02:31:46.008418Z","steps":["trace[673797675] 'read index received' (duration: 273.704111ms)","trace[673797675] 'applied index is now lower than readState.Index' (duration: 41.891251ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:46.008869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.029681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-09-18T02:31:46.008673Z","caller":"traceutil/trace.go:171","msg":"trace[256771937] transaction","detail":"{read_only:false; response_revision:6745; number_of_response:1; }","duration":"319.090722ms","start":"2026-09-18T02:31:45.689552Z","end":"2026-09-18T02:31:46.008642Z","steps":["trace[256771937] 'process raft request' (duration: 276.990146ms)","trace[256771937] 'compare' (duration: 41.469583ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:46.009149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:45.689519Z","time spent":"319.556451ms","remote":"127.0.0.1:47312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2351,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T02:31:46.008935Z","caller":"traceutil/trace.go:171","msg":"trace[252444481] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:6745; }","duration":"316.126184ms","start":"2026-09-18T02:31:45.692796Z","end":"2026-09-18T02:31:46.008922Z","steps":["trace[252444481] 'agreement among raft nodes before linearized reading' (duration: 315.92924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:46.009667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:45.692785Z","time spent":"316.810117ms","remote":"127.0.0.1:41346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1364,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"warn","ts":"2026-09-18T02:31:46.008888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.073728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:31:46.009813Z","caller":"traceutil/trace.go:171","msg":"trace[2050244807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6745; }","duration":"174.040437ms","start":"2026-09-18T02:31:45.835748Z","end":"2026-09-18T02:31:46.009788Z","steps":["trace[2050244807] 'agreement among raft nodes before linearized reading' (duration: 173.062898ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:46.192283Z","caller":"traceutil/trace.go:171","msg":"trace[983054574] transaction","detail":"{read_only:false; response_revision:6749; number_of_response:1; }","duration":"115.070911ms","start":"2026-09-18T02:31:46.077188Z","end":"2026-09-18T02:31:46.192259Z","steps":["trace[983054574] 'process raft request' (duration: 76.73311ms)","trace[983054574] 'compare' (duration: 37.710678ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:46.251351Z","caller":"traceutil/trace.go:171","msg":"trace[417433921] transaction","detail":"{read_only:false; number_of_response:0; response_revision:6749; }","duration":"146.543484ms","start":"2026-09-18T02:31:46.104789Z","end":"2026-09-18T02:31:46.251333Z","steps":["trace[417433921] 'process raft request' (duration: 146.458972ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:46.372309Z","caller":"traceutil/trace.go:171","msg":"trace[2090888483] linearizableReadLoop","detail":"{readStateIndex:7061; appliedIndex:7059; }","duration":"180.227079ms","start":"2026-09-18T02:31:46.192059Z","end":"2026-09-18T02:31:46.372286Z","steps":["trace[2090888483] 'read index received' (duration: 59.140647ms)","trace[2090888483] 'applied index is now lower than readState.Index' (duration: 121.085432ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:46.37241Z","caller":"traceutil/trace.go:171","msg":"trace[573806535] transaction","detail":"{read_only:false; response_revision:6751; number_of_response:1; }","duration":"129.698724ms","start":"2026-09-18T02:31:46.242692Z","end":"2026-09-18T02:31:46.372391Z","steps":["trace[573806535] 'process raft request' (duration: 129.51012ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:46.372412Z","caller":"traceutil/trace.go:171","msg":"trace[1366714417] transaction","detail":"{read_only:false; response_revision:6750; number_of_response:1; }","duration":"196.252242ms","start":"2026-09-18T02:31:46.176135Z","end":"2026-09-18T02:31:46.372387Z","steps":["trace[1366714417] 'process raft request' (duration: 135.998532ms)","trace[1366714417] 'compare' (duration: 59.658658ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:46.372523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.615458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:31:46.372601Z","caller":"traceutil/trace.go:171","msg":"trace[2127961158] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:6751; }","duration":"188.750012ms","start":"2026-09-18T02:31:46.183834Z","end":"2026-09-18T02:31:46.372584Z","steps":["trace[2127961158] 'agreement among raft nodes before linearized reading' (duration: 188.569287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:46.372671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.799318ms","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-18T02:31:46.3727Z","caller":"traceutil/trace.go:171","msg":"trace[1944147263] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6751; }","duration":"261.86618ms","start":"2026-09-18T02:31:46.110825Z","end":"2026-09-18T02:31:46.372691Z","steps":["trace[1944147263] 'agreement among raft nodes before linearized reading' (duration: 261.741977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:46.449099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.160389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-09-18T02:31:46.449148Z","caller":"traceutil/trace.go:171","msg":"trace[1048448513] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:6752; }","duration":"196.243541ms","start":"2026-09-18T02:31:46.252891Z","end":"2026-09-18T02:31:46.449135Z","steps":["trace[1048448513] 'agreement among raft nodes before linearized reading' (duration: 196.074808ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:46.672439Z","caller":"traceutil/trace.go:171","msg":"trace[2058751720] transaction","detail":"{read_only:false; response_revision:6756; number_of_response:1; }","duration":"213.094331ms","start":"2026-09-18T02:31:46.459317Z","end":"2026-09-18T02:31:46.672411Z","steps":["trace[2058751720] 'process raft request' (duration: 213.008249ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:46.672495Z","caller":"traceutil/trace.go:171","msg":"trace[1681364385] transaction","detail":"{read_only:false; response_revision:6755; number_of_response:1; }","duration":"214.865886ms","start":"2026-09-18T02:31:46.457561Z","end":"2026-09-18T02:31:46.672427Z","steps":["trace[1681364385] 'process raft request' (duration: 199.584979ms)","trace[1681364385] 'compare' (duration: 15.018071ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:48.608113Z","caller":"traceutil/trace.go:171","msg":"trace[18330338] transaction","detail":"{read_only:false; response_revision:6773; number_of_response:1; }","duration":"148.915997ms","start":"2026-09-18T02:31:48.459164Z","end":"2026-09-18T02:31:48.60808Z","steps":["trace[18330338] 'process raft request' (duration: 135.631561ms)","trace[18330338] 'compare' (duration: 13.018661ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:48.654438Z","caller":"traceutil/trace.go:171","msg":"trace[372014216] transaction","detail":"{read_only:false; response_revision:6774; number_of_response:1; }","duration":"128.56486ms","start":"2026-09-18T02:31:48.525843Z","end":"2026-09-18T02:31:48.654408Z","steps":["trace[372014216] 'process raft request' (duration: 128.313255ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:48.654627Z","caller":"traceutil/trace.go:171","msg":"trace[1469132114] transaction","detail":"{read_only:false; response_revision:6775; number_of_response:1; }","duration":"128.706732ms","start":"2026-09-18T02:31:48.525886Z","end":"2026-09-18T02:31:48.654593Z","steps":["trace[1469132114] 'process raft request' (duration: 128.462968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:48.955789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.827463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:31:48.955859Z","caller":"traceutil/trace.go:171","msg":"trace[1754329180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6780; }","duration":"118.932126ms","start":"2026-09-18T02:31:48.836908Z","end":"2026-09-18T02:31:48.95584Z","steps":["trace[1754329180] 'agreement among raft nodes before linearized reading' (duration: 60.478763ms)","trace[1754329180] 'range keys from in-memory index tree' (duration: 58.35702ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:31:51.735029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.288621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T02:31:51.735127Z","caller":"traceutil/trace.go:171","msg":"trace[1997009932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6806; }","duration":"467.428454ms","start":"2026-09-18T02:31:51.267676Z","end":"2026-09-18T02:31:51.735105Z","steps":["trace[1997009932] 'range keys from in-memory index tree' (duration: 467.130448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:51.735125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.349028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-18T02:31:51.735207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:51.267658Z","time spent":"467.533606ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-18T02:31:51.735253Z","caller":"traceutil/trace.go:171","msg":"trace[912517618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6806; }","duration":"320.519921ms","start":"2026-09-18T02:31:51.414708Z","end":"2026-09-18T02:31:51.735228Z","steps":["trace[912517618] 'range keys from in-memory index tree' (duration: 320.241435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:51.735301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:51.414694Z","time spent":"320.596352ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T02:31:51.735487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.218659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-18T02:31:51.735509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.919759ms","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-09-18T02:31:51.735551Z","caller":"traceutil/trace.go:171","msg":"trace[1670493421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6806; }","duration":"112.32713ms","start":"2026-09-18T02:31:51.623208Z","end":"2026-09-18T02:31:51.735535Z","steps":["trace[1670493421] 'range keys from in-memory index tree' (duration: 112.086455ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:51.735564Z","caller":"traceutil/trace.go:171","msg":"trace[513109060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6806; }","duration":"321.058191ms","start":"2026-09-18T02:31:51.414487Z","end":"2026-09-18T02:31:51.735545Z","steps":["trace[513109060] 'range keys from in-memory index tree' (duration: 320.775826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:51.735596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:31:51.414466Z","time spent":"321.122763ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-18T02:31:53.58544Z","caller":"traceutil/trace.go:171","msg":"trace[1118551486] transaction","detail":"{read_only:false; response_revision:6825; number_of_response:1; }","duration":"108.954461ms","start":"2026-09-18T02:31:53.476461Z","end":"2026-09-18T02:31:53.585415Z","steps":["trace[1118551486] 'process raft request' (duration: 108.750147ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:53.985324Z","caller":"traceutil/trace.go:171","msg":"trace[1550110008] transaction","detail":"{read_only:false; response_revision:6827; number_of_response:1; }","duration":"191.827629ms","start":"2026-09-18T02:31:53.793477Z","end":"2026-09-18T02:31:53.985305Z","steps":["trace[1550110008] 'process raft request' (duration: 191.678476ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:53.985642Z","caller":"traceutil/trace.go:171","msg":"trace[810210978] transaction","detail":"{read_only:false; response_revision:6828; number_of_response:1; }","duration":"191.779097ms","start":"2026-09-18T02:31:53.793854Z","end":"2026-09-18T02:31:53.985633Z","steps":["trace[810210978] 'process raft request' (duration: 191.41562ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:53.985875Z","caller":"traceutil/trace.go:171","msg":"trace[123539186] linearizableReadLoop","detail":"{readStateIndex:7143; appliedIndex:7139; }","duration":"148.022412ms","start":"2026-09-18T02:31:53.837816Z","end":"2026-09-18T02:31:53.985838Z","steps":["trace[123539186] 'read index received' (duration: 147.246586ms)","trace[123539186] 'applied index is now lower than readState.Index' (duration: 774.746µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:53.985896Z","caller":"traceutil/trace.go:171","msg":"trace[486644315] transaction","detail":"{read_only:false; response_revision:6830; number_of_response:1; }","duration":"190.827139ms","start":"2026-09-18T02:31:53.795035Z","end":"2026-09-18T02:31:53.985862Z","steps":["trace[486644315] 'process raft request' (duration: 190.721307ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:53.985948Z","caller":"traceutil/trace.go:171","msg":"trace[482564492] transaction","detail":"{read_only:false; response_revision:6829; number_of_response:1; }","duration":"191.671246ms","start":"2026-09-18T02:31:53.794248Z","end":"2026-09-18T02:31:53.98592Z","steps":["trace[482564492] 'process raft request' (duration: 191.34273ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:31:53.986012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.149995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:31:53.986167Z","caller":"traceutil/trace.go:171","msg":"trace[1177689645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6830; }","duration":"148.301688ms","start":"2026-09-18T02:31:53.837737Z","end":"2026-09-18T02:31:53.986039Z","steps":["trace[1177689645] 'agreement among raft nodes before linearized reading' (duration: 148.196736ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:54.850423Z","caller":"traceutil/trace.go:171","msg":"trace[1441269819] transaction","detail":"{read_only:false; response_revision:6833; number_of_response:1; }","duration":"254.092642ms","start":"2026-09-18T02:31:54.596301Z","end":"2026-09-18T02:31:54.850393Z","steps":["trace[1441269819] 'process raft request' (duration: 253.869567ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:54.874823Z","caller":"traceutil/trace.go:171","msg":"trace[831310365] transaction","detail":"{read_only:false; response_revision:6834; number_of_response:1; }","duration":"203.97507ms","start":"2026-09-18T02:31:54.670817Z","end":"2026-09-18T02:31:54.874792Z","steps":["trace[831310365] 'process raft request' (duration: 203.796407ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:31:55.615389Z","caller":"traceutil/trace.go:171","msg":"trace[1843759235] transaction","detail":"{read_only:false; response_revision:6839; number_of_response:1; }","duration":"102.160473ms","start":"2026-09-18T02:31:55.513194Z","end":"2026-09-18T02:31:55.615355Z","steps":["trace[1843759235] 'process raft request' (duration: 84.435918ms)","trace[1843759235] 'compare' (duration: 17.187504ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:55.985171Z","caller":"traceutil/trace.go:171","msg":"trace[1760128886] transaction","detail":"{read_only:false; response_revision:6841; number_of_response:1; }","duration":"124.134493ms","start":"2026-09-18T02:31:55.860992Z","end":"2026-09-18T02:31:55.985126Z","steps":["trace[1760128886] 'process raft request' (duration: 113.188284ms)","trace[1760128886] 'compare' (duration: 10.589982ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:31:57.652879Z","caller":"traceutil/trace.go:171","msg":"trace[2070026504] transaction","detail":"{read_only:false; response_revision:6856; number_of_response:1; }","duration":"117.467657ms","start":"2026-09-18T02:31:57.535385Z","end":"2026-09-18T02:31:57.652853Z","steps":["trace[2070026504] 'process raft request' (duration: 88.179492ms)","trace[2070026504] 'compare' (duration: 29.169883ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:32:10.415376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.243901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T02:32:10.415446Z","caller":"traceutil/trace.go:171","msg":"trace[691850125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6956; }","duration":"162.362494ms","start":"2026-09-18T02:32:10.253063Z","end":"2026-09-18T02:32:10.415426Z","steps":["trace[691850125] 'range keys from in-memory index tree' (duration: 162.13791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.415444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.514925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-18T02:32:10.41565Z","caller":"traceutil/trace.go:171","msg":"trace[1944555326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6956; }","duration":"169.782371ms","start":"2026-09-18T02:32:10.245844Z","end":"2026-09-18T02:32:10.415626Z","steps":["trace[1944555326] 'range keys from in-memory index tree' (duration: 169.267681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.41536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.578389ms","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-18T02:32:10.415735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.862689ms","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-09-18T02:32:10.415771Z","caller":"traceutil/trace.go:171","msg":"trace[1842821631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6956; }","duration":"225.051708ms","start":"2026-09-18T02:32:10.1907Z","end":"2026-09-18T02:32:10.415752Z","steps":["trace[1842821631] 'range keys from in-memory index tree' (duration: 224.384034ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:10.415815Z","caller":"traceutil/trace.go:171","msg":"trace[140842118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6956; }","duration":"407.989691ms","start":"2026-09-18T02:32:10.007805Z","end":"2026-09-18T02:32:10.415795Z","steps":["trace[140842118] 'range keys from in-memory index tree' (duration: 407.710145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.415848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:32:10.007793Z","time spent":"408.049843ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T02:32:10.415877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.292807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-18T02:32:10.415908Z","caller":"traceutil/trace.go:171","msg":"trace[322587399] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6956; }","duration":"197.351068ms","start":"2026-09-18T02:32:10.218549Z","end":"2026-09-18T02:32:10.4159Z","steps":["trace[322587399] 'range keys from in-memory index tree' (duration: 197.218406ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.416325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.18352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4837"} {"level":"info","ts":"2026-09-18T02:32:10.416592Z","caller":"traceutil/trace.go:171","msg":"trace[2023272193] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6956; }","duration":"163.449986ms","start":"2026-09-18T02:32:10.253074Z","end":"2026-09-18T02:32:10.416524Z","steps":["trace[2023272193] 'range keys from in-memory index tree' (duration: 162.249591ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:10.680038Z","caller":"traceutil/trace.go:171","msg":"trace[1309132048] transaction","detail":"{read_only:false; response_revision:6957; number_of_response:1; }","duration":"260.248867ms","start":"2026-09-18T02:32:10.419758Z","end":"2026-09-18T02:32:10.680007Z","steps":["trace[1309132048] 'process raft request' (duration: 260.058913ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:10.835743Z","caller":"traceutil/trace.go:171","msg":"trace[1927274420] transaction","detail":"{read_only:false; response_revision:6959; number_of_response:1; }","duration":"414.570082ms","start":"2026-09-18T02:32:10.421152Z","end":"2026-09-18T02:32:10.835722Z","steps":["trace[1927274420] 'process raft request' (duration: 414.479339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.835885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:32:10.421114Z","time spent":"414.693183ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:32:10.836004Z","caller":"traceutil/trace.go:171","msg":"trace[1151246410] linearizableReadLoop","detail":"{readStateIndex:7282; appliedIndex:7277; }","duration":"229.001327ms","start":"2026-09-18T02:32:10.606984Z","end":"2026-09-18T02:32:10.835985Z","steps":["trace[1151246410] 'read index received' (duration: 72.979349ms)","trace[1151246410] 'applied index is now lower than readState.Index' (duration: 156.021098ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:10.836112Z","caller":"traceutil/trace.go:171","msg":"trace[60391778] transaction","detail":"{read_only:false; response_revision:6961; number_of_response:1; }","duration":"414.131412ms","start":"2026-09-18T02:32:10.42197Z","end":"2026-09-18T02:32:10.836102Z","steps":["trace[60391778] 'process raft request' (duration: 413.862087ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:10.836135Z","caller":"traceutil/trace.go:171","msg":"trace[1838732070] transaction","detail":"{read_only:false; response_revision:6958; number_of_response:1; }","duration":"415.368636ms","start":"2026-09-18T02:32:10.420755Z","end":"2026-09-18T02:32:10.836123Z","steps":["trace[1838732070] 'process raft request' (duration: 414.772774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.836191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:32:10.420743Z","time spent":"415.422098ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:32:10.836201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:32:10.421925Z","time spent":"414.213534ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T02:32:10.836086Z","caller":"traceutil/trace.go:171","msg":"trace[1105942925] transaction","detail":"{read_only:false; response_revision:6960; number_of_response:1; }","duration":"414.271645ms","start":"2026-09-18T02:32:10.421787Z","end":"2026-09-18T02:32:10.836059Z","steps":["trace[1105942925] 'process raft request' (duration: 413.880957ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.83639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.417534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-db-sync-zqvgn\" ","response":"range_response_count:1 size:6521"} {"level":"info","ts":"2026-09-18T02:32:10.836427Z","caller":"traceutil/trace.go:171","msg":"trace[2133576756] range","detail":"{range_begin:/registry/pods/openstack/horizon-db-sync-zqvgn; range_end:; response_count:1; response_revision:6961; }","duration":"229.514297ms","start":"2026-09-18T02:32:10.606901Z","end":"2026-09-18T02:32:10.836416Z","steps":["trace[2133576756] 'agreement among raft nodes before linearized reading' (duration: 229.386595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:10.836426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T02:32:10.421778Z","time spent":"414.558261ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T02:32:10.836592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.576602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:32:10.836624Z","caller":"traceutil/trace.go:171","msg":"trace[1041705326] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:6961; }","duration":"132.642354ms","start":"2026-09-18T02:32:10.703974Z","end":"2026-09-18T02:32:10.836616Z","steps":["trace[1041705326] 'agreement among raft nodes before linearized reading' (duration: 132.568722ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:15.207355Z","caller":"traceutil/trace.go:171","msg":"trace[1797039404] transaction","detail":"{read_only:false; response_revision:6989; number_of_response:1; }","duration":"240.483615ms","start":"2026-09-18T02:32:14.966846Z","end":"2026-09-18T02:32:15.207329Z","steps":["trace[1797039404] 'process raft request' (duration: 238.035216ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:15.207522Z","caller":"traceutil/trace.go:171","msg":"trace[469331363] linearizableReadLoop","detail":"{readStateIndex:7313; appliedIndex:7311; }","duration":"207.719315ms","start":"2026-09-18T02:32:14.999772Z","end":"2026-09-18T02:32:15.207492Z","steps":["trace[469331363] 'read index received' (duration: 205.141214ms)","trace[469331363] 'applied index is now lower than readState.Index' (duration: 2.575261ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:15.207585Z","caller":"traceutil/trace.go:171","msg":"trace[1646638873] transaction","detail":"{read_only:false; response_revision:6990; number_of_response:1; }","duration":"240.521275ms","start":"2026-09-18T02:32:14.967031Z","end":"2026-09-18T02:32:15.207553Z","steps":["trace[1646638873] 'process raft request' (duration: 240.22673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:15.207779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.99509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4837"} {"level":"info","ts":"2026-09-18T02:32:15.207831Z","caller":"traceutil/trace.go:171","msg":"trace[734963450] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6990; }","duration":"208.088802ms","start":"2026-09-18T02:32:14.999726Z","end":"2026-09-18T02:32:15.207815Z","steps":["trace[734963450] 'agreement among raft nodes before linearized reading' (duration: 207.880348ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:15.221287Z","caller":"traceutil/trace.go:171","msg":"trace[654565232] transaction","detail":"{read_only:false; response_revision:6991; number_of_response:1; }","duration":"171.0962ms","start":"2026-09-18T02:32:15.050146Z","end":"2026-09-18T02:32:15.221242Z","steps":["trace[654565232] 'process raft request' (duration: 170.876616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:15.221299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.995416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4837"} {"level":"info","ts":"2026-09-18T02:32:15.221437Z","caller":"traceutil/trace.go:171","msg":"trace[229650872] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6991; }","duration":"128.17673ms","start":"2026-09-18T02:32:15.093239Z","end":"2026-09-18T02:32:15.221416Z","steps":["trace[229650872] 'agreement among raft nodes before linearized reading' (duration: 127.918985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:15.221531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.468007ms","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-18T02:32:15.221611Z","caller":"traceutil/trace.go:171","msg":"trace[2010869880] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:6991; }","duration":"116.566359ms","start":"2026-09-18T02:32:15.105026Z","end":"2026-09-18T02:32:15.221592Z","steps":["trace[2010869880] 'agreement among raft nodes before linearized reading' (duration: 116.307854ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:26.994215Z","caller":"traceutil/trace.go:171","msg":"trace[2003405655] transaction","detail":"{read_only:false; response_revision:7117; number_of_response:1; }","duration":"161.079217ms","start":"2026-09-18T02:32:26.833117Z","end":"2026-09-18T02:32:26.994196Z","steps":["trace[2003405655] 'process raft request' (duration: 160.576928ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:27.235593Z","caller":"traceutil/trace.go:171","msg":"trace[339679131] linearizableReadLoop","detail":"{readStateIndex:7448; appliedIndex:7447; }","duration":"148.927737ms","start":"2026-09-18T02:32:27.086644Z","end":"2026-09-18T02:32:27.235572Z","steps":["trace[339679131] 'read index received' (duration: 66.977391ms)","trace[339679131] 'applied index is now lower than readState.Index' (duration: 81.949066ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:32:27.236158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.509668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-18T02:32:27.236255Z","caller":"traceutil/trace.go:171","msg":"trace[1303626225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7118; }","duration":"149.656271ms","start":"2026-09-18T02:32:27.086589Z","end":"2026-09-18T02:32:27.236246Z","steps":["trace[1303626225] 'agreement among raft nodes before linearized reading' (duration: 149.118041ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:27.235661Z","caller":"traceutil/trace.go:171","msg":"trace[1399463528] transaction","detail":"{read_only:false; response_revision:7118; number_of_response:1; }","duration":"236.402821ms","start":"2026-09-18T02:32:26.999237Z","end":"2026-09-18T02:32:27.23564Z","steps":["trace[1399463528] 'process raft request' (duration: 154.425245ms)","trace[1399463528] 'compare' (duration: 81.733091ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:27.450332Z","caller":"traceutil/trace.go:171","msg":"trace[671666364] transaction","detail":"{read_only:false; response_revision:7120; number_of_response:1; }","duration":"109.708613ms","start":"2026-09-18T02:32:27.3406Z","end":"2026-09-18T02:32:27.450308Z","steps":["trace[671666364] 'process raft request' (duration: 62.223507ms)","trace[671666364] 'compare' (duration: 47.214161ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:27.65806Z","caller":"traceutil/trace.go:171","msg":"trace[1794317419] transaction","detail":"{read_only:false; response_revision:7122; number_of_response:1; }","duration":"202.046333ms","start":"2026-09-18T02:32:27.455994Z","end":"2026-09-18T02:32:27.65804Z","steps":["trace[1794317419] 'process raft request' (duration: 201.87366ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:27.658157Z","caller":"traceutil/trace.go:171","msg":"trace[512704934] linearizableReadLoop","detail":"{readStateIndex:7453; appliedIndex:7450; }","duration":"197.248919ms","start":"2026-09-18T02:32:27.460897Z","end":"2026-09-18T02:32:27.658146Z","steps":["trace[512704934] 'read index received' (duration: 64.329128ms)","trace[512704934] 'applied index is now lower than readState.Index' (duration: 132.919141ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:27.658242Z","caller":"traceutil/trace.go:171","msg":"trace[1584241764] transaction","detail":"{read_only:false; response_revision:7123; number_of_response:1; }","duration":"200.221567ms","start":"2026-09-18T02:32:27.458014Z","end":"2026-09-18T02:32:27.658235Z","steps":["trace[1584241764] 'process raft request' (duration: 199.999262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:27.658392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.371701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T02:32:27.65842Z","caller":"traceutil/trace.go:171","msg":"trace[1748616180] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:7123; }","duration":"164.442763ms","start":"2026-09-18T02:32:27.493966Z","end":"2026-09-18T02:32:27.658409Z","steps":["trace[1748616180] 'agreement among raft nodes before linearized reading' (duration: 164.359981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:27.65854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.639877ms","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-18T02:32:27.658576Z","caller":"traceutil/trace.go:171","msg":"trace[1720194604] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7123; }","duration":"197.697728ms","start":"2026-09-18T02:32:27.460867Z","end":"2026-09-18T02:32:27.658565Z","steps":["trace[1720194604] 'agreement among raft nodes before linearized reading' (duration: 197.594996ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:29.634044Z","caller":"traceutil/trace.go:171","msg":"trace[107423057] transaction","detail":"{read_only:false; response_revision:7137; number_of_response:1; }","duration":"103.06378ms","start":"2026-09-18T02:32:29.530959Z","end":"2026-09-18T02:32:29.634023Z","steps":["trace[107423057] 'process raft request' (duration: 95.021091ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:29.773989Z","caller":"traceutil/trace.go:171","msg":"trace[2033481820] transaction","detail":"{read_only:false; response_revision:7139; number_of_response:1; }","duration":"107.435017ms","start":"2026-09-18T02:32:29.666508Z","end":"2026-09-18T02:32:29.773943Z","steps":["trace[2033481820] 'process raft request' (duration: 107.259382ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:29.774146Z","caller":"traceutil/trace.go:171","msg":"trace[1572108577] transaction","detail":"{read_only:false; response_revision:7138; number_of_response:1; }","duration":"109.692641ms","start":"2026-09-18T02:32:29.664294Z","end":"2026-09-18T02:32:29.773987Z","steps":["trace[1572108577] 'process raft request' (duration: 96.948799ms)","trace[1572108577] 'compare' (duration: 12.361714ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:29.91288Z","caller":"traceutil/trace.go:171","msg":"trace[668688278] transaction","detail":"{read_only:false; response_revision:7140; number_of_response:1; }","duration":"128.078542ms","start":"2026-09-18T02:32:29.783805Z","end":"2026-09-18T02:32:29.911883Z","steps":["trace[668688278] 'process raft request' (duration: 127.811197ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:30.820055Z","caller":"traceutil/trace.go:171","msg":"trace[1397021423] transaction","detail":"{read_only:false; response_revision:7144; number_of_response:1; }","duration":"231.049699ms","start":"2026-09-18T02:32:30.588984Z","end":"2026-09-18T02:32:30.820034Z","steps":["trace[1397021423] 'process raft request' (duration: 230.925607ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:32.015418Z","caller":"traceutil/trace.go:171","msg":"trace[1959343207] linearizableReadLoop","detail":"{readStateIndex:7483; appliedIndex:7481; }","duration":"179.039363ms","start":"2026-09-18T02:32:31.836354Z","end":"2026-09-18T02:32:32.015394Z","steps":["trace[1959343207] 'read index received' (duration: 6.231032ms)","trace[1959343207] 'applied index is now lower than readState.Index' (duration: 172.807381ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T02:32:32.015647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.267678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T02:32:32.015768Z","caller":"traceutil/trace.go:171","msg":"trace[547963980] transaction","detail":"{read_only:false; response_revision:7151; number_of_response:1; }","duration":"232.926926ms","start":"2026-09-18T02:32:31.782816Z","end":"2026-09-18T02:32:32.015743Z","steps":["trace[547963980] 'process raft request' (duration: 197.07998ms)","trace[547963980] 'compare' (duration: 35.119941ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:32.015776Z","caller":"traceutil/trace.go:171","msg":"trace[1493607633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7151; }","duration":"179.429273ms","start":"2026-09-18T02:32:31.83632Z","end":"2026-09-18T02:32:32.01575Z","steps":["trace[1493607633] 'agreement among raft nodes before linearized reading' (duration: 179.244729ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:37.435331Z","caller":"traceutil/trace.go:171","msg":"trace[2024607238] transaction","detail":"{read_only:false; response_revision:7184; number_of_response:1; }","duration":"120.788592ms","start":"2026-09-18T02:32:37.314521Z","end":"2026-09-18T02:32:37.435309Z","steps":["trace[2024607238] 'process raft request' (duration: 120.66887ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:44.189468Z","caller":"traceutil/trace.go:171","msg":"trace[867957781] transaction","detail":"{read_only:false; response_revision:7228; number_of_response:1; }","duration":"115.647225ms","start":"2026-09-18T02:32:44.073791Z","end":"2026-09-18T02:32:44.189438Z","steps":["trace[867957781] 'process raft request' (duration: 115.42764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T02:32:44.189612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.447221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-18T02:32:44.189658Z","caller":"traceutil/trace.go:171","msg":"trace[306734818] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7228; }","duration":"114.532513ms","start":"2026-09-18T02:32:44.075106Z","end":"2026-09-18T02:32:44.189639Z","steps":["trace[306734818] 'agreement among raft nodes before linearized reading' (duration: 114.34473ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:32:44.189372Z","caller":"traceutil/trace.go:171","msg":"trace[991987933] linearizableReadLoop","detail":"{readStateIndex:7564; appliedIndex:7563; }","duration":"114.216737ms","start":"2026-09-18T02:32:44.075135Z","end":"2026-09-18T02:32:44.189352Z","steps":["trace[991987933] 'read index received' (duration: 113.999553ms)","trace[991987933] 'applied index is now lower than readState.Index' (duration: 216.504µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:44.399595Z","caller":"traceutil/trace.go:171","msg":"trace[54558834] transaction","detail":"{read_only:false; response_revision:7229; number_of_response:1; }","duration":"203.027108ms","start":"2026-09-18T02:32:44.196518Z","end":"2026-09-18T02:32:44.399545Z","steps":["trace[54558834] 'process raft request' (duration: 180.429285ms)","trace[54558834] 'compare' (duration: 22.484241ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T02:32:44.402022Z","caller":"traceutil/trace.go:171","msg":"trace[199240878] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7229; }","duration":"202.745933ms","start":"2026-09-18T02:32:44.199259Z","end":"2026-09-18T02:32:44.402005Z","steps":["trace[199240878] 'process raft request' (duration: 202.6207ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T02:35:36.087531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6155} {"level":"info","ts":"2026-09-18T02:35:36.198192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6155,"took":"109.588602ms","hash":1359470416,"current-db-size-bytes":57319424,"current-db-size":"57 MB","current-db-size-in-use-bytes":18866176,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T02:35:36.198245Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1359470416,"revision":6155,"compact-revision":3006}