{"level":"warn","ts":"2026-09-23T03:18:43.250378Z","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-23T03:18:43.250507Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.112: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.112:2380","--initial-cluster=instance=https://162.253.55.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.112: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-23T03:18:43.250612Z","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-23T03:18:43.25063Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-23T03:18:43.250659Z","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-23T03:18:43.251791Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"]} {"level":"info","ts":"2026-09-23T03:18:43.251899Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.112: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-23T03:18:43.256344Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.091988ms"} {"level":"info","ts":"2026-09-23T03:18:43.265641Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1b80133015df7bbe","cluster-id":"96c35284d1622e20"} {"level":"info","ts":"2026-09-23T03:18:43.265809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=()"} {"level":"info","ts":"2026-09-23T03:18:43.26586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 0"} {"level":"info","ts":"2026-09-23T03:18:43.265877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1b80133015df7bbe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-23T03:18:43.265897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became follower at term 1"} {"level":"info","ts":"2026-09-23T03:18:43.265981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"warn","ts":"2026-09-23T03:18:43.273794Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-23T03:18:43.276101Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-23T03:18:43.277873Z","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-23T03:18:43.283256Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1b80133015df7bbe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-23T03:18:43.28368Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1b80133015df7bbe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-23T03:18:43.2838Z","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-23T03:18:43.283884Z","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-23T03:18:43.283896Z","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-23T03:18:43.28604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe switched to configuration voters=(1981604933289343934)"} {"level":"info","ts":"2026-09-23T03:18:43.286248Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","added-peer-id":"1b80133015df7bbe","added-peer-peer-urls":["https://162.253.55.112:2380"]} {"level":"info","ts":"2026-09-23T03:18:43.289708Z","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-23T03:18:43.290114Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1b80133015df7bbe","initial-advertise-peer-urls":["https://162.253.55.112:2380"],"listen-peer-urls":["https://162.253.55.112:2380"],"advertise-client-urls":["https://162.253.55.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-23T03:18:43.290191Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-23T03:18:43.289867Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-23T03:18:43.290361Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.112:2380"} {"level":"info","ts":"2026-09-23T03:18:43.866467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe is starting a new election at term 1"} {"level":"info","ts":"2026-09-23T03:18:43.86658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became pre-candidate at term 1"} {"level":"info","ts":"2026-09-23T03:18:43.866622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgPreVoteResp from 1b80133015df7bbe at term 1"} {"level":"info","ts":"2026-09-23T03:18:43.866644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became candidate at term 2"} {"level":"info","ts":"2026-09-23T03:18:43.866656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe received MsgVoteResp from 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-23T03:18:43.866678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1b80133015df7bbe became leader at term 2"} {"level":"info","ts":"2026-09-23T03:18:43.866698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1b80133015df7bbe elected leader 1b80133015df7bbe at term 2"} {"level":"info","ts":"2026-09-23T03:18:43.868404Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1b80133015df7bbe","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.112:2379]}","request-path":"/0/members/1b80133015df7bbe/attributes","cluster-id":"96c35284d1622e20","publish-timeout":"7s"} {"level":"info","ts":"2026-09-23T03:18:43.868458Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T03:18:43.868686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-23T03:18:43.868999Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-23T03:18:43.869024Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-23T03:18:43.871947Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-23T03:18:43.87217Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:43.873854Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"96c35284d1622e20","local-member-id":"1b80133015df7bbe","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:43.896736Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:43.896887Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-23T03:18:43.925831Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.112:2379"} {"level":"info","ts":"2026-09-23T03:18:59.145973Z","caller":"traceutil/trace.go:171","msg":"trace[198438060] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"127.780598ms","start":"2026-09-23T03:18:59.018162Z","end":"2026-09-23T03:18:59.145943Z","steps":["trace[198438060] 'read index received' (duration: 127.516922ms)","trace[198438060] 'applied index is now lower than readState.Index' (duration: 262.736µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:18:59.14606Z","caller":"traceutil/trace.go:171","msg":"trace[405463792] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"143.379169ms","start":"2026-09-23T03:18:59.002655Z","end":"2026-09-23T03:18:59.146034Z","steps":["trace[405463792] 'process raft request' (duration: 143.137683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:18:59.146208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.028544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-09-23T03:18:59.146332Z","caller":"traceutil/trace.go:171","msg":"trace[1710930351] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:403; }","duration":"128.204138ms","start":"2026-09-23T03:18:59.018109Z","end":"2026-09-23T03:18:59.146313Z","steps":["trace[1710930351] 'agreement among raft nodes before linearized reading' (duration: 128.013483ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:19:09.90724Z","caller":"traceutil/trace.go:171","msg":"trace[1651254270] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"124.766598ms","start":"2026-09-23T03:19:09.782421Z","end":"2026-09-23T03:19:09.907187Z","steps":["trace[1651254270] 'process raft request' (duration: 124.634714ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:19:32.446595Z","caller":"traceutil/trace.go:171","msg":"trace[1892327890] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"180.687338ms","start":"2026-09-23T03:19:32.265875Z","end":"2026-09-23T03:19:32.446562Z","steps":["trace[1892327890] 'process raft request' (duration: 180.504833ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:19:32.677533Z","caller":"traceutil/trace.go:171","msg":"trace[1891505484] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"113.653393ms","start":"2026-09-23T03:19:32.563849Z","end":"2026-09-23T03:19:32.677503Z","steps":["trace[1891505484] 'read index received' (duration: 112.764682ms)","trace[1891505484] 'applied index is now lower than readState.Index' (duration: 887.221µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:19:32.677593Z","caller":"traceutil/trace.go:171","msg":"trace[23228273] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"294.544256ms","start":"2026-09-23T03:19:32.383021Z","end":"2026-09-23T03:19:32.677565Z","steps":["trace[23228273] 'process raft request' (duration: 293.949692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:19:32.677737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.866808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:19:32.677861Z","caller":"traceutil/trace.go:171","msg":"trace[1358757765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"114.031972ms","start":"2026-09-23T03:19:32.563802Z","end":"2026-09-23T03:19:32.677833Z","steps":["trace[1358757765] 'agreement among raft nodes before linearized reading' (duration: 113.830948ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:19:48.741288Z","caller":"traceutil/trace.go:171","msg":"trace[24503911] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"109.33528ms","start":"2026-09-23T03:19:48.631891Z","end":"2026-09-23T03:19:48.741227Z","steps":["trace[24503911] 'process raft request' (duration: 109.210507ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:06.464708Z","caller":"traceutil/trace.go:171","msg":"trace[1290105651] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"279.640088ms","start":"2026-09-23T03:21:06.185023Z","end":"2026-09-23T03:21:06.464663Z","steps":["trace[1290105651] 'process raft request' (duration: 279.382592ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:06.905898Z","caller":"traceutil/trace.go:171","msg":"trace[1124037738] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1965; }","duration":"163.374201ms","start":"2026-09-23T03:21:06.742498Z","end":"2026-09-23T03:21:06.905872Z","steps":["trace[1124037738] 'read index received' (duration: 15.013241ms)","trace[1124037738] 'applied index is now lower than readState.Index' (duration: 148.35992ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:21:06.905972Z","caller":"traceutil/trace.go:171","msg":"trace[180850548] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"243.300924ms","start":"2026-09-23T03:21:06.662641Z","end":"2026-09-23T03:21:06.905942Z","steps":["trace[180850548] 'process raft request' (duration: 234.679676ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:21:06.906112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.587296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f63edb51-fb6a-4329-9403-343e65cedba6\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-23T03:21:06.906155Z","caller":"traceutil/trace.go:171","msg":"trace[380306702] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f63edb51-fb6a-4329-9403-343e65cedba6; range_end:; response_count:1; response_revision:1892; }","duration":"163.682928ms","start":"2026-09-23T03:21:06.74246Z","end":"2026-09-23T03:21:06.906143Z","steps":["trace[380306702] 'agreement among raft nodes before linearized reading' (duration: 163.511114ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:19.15468Z","caller":"traceutil/trace.go:171","msg":"trace[362653334] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2072; }","duration":"115.820095ms","start":"2026-09-23T03:21:19.038833Z","end":"2026-09-23T03:21:19.154653Z","steps":["trace[362653334] 'read index received' (duration: 115.659951ms)","trace[362653334] 'applied index is now lower than readState.Index' (duration: 158.844µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:21:19.154747Z","caller":"traceutil/trace.go:171","msg":"trace[537042508] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"192.33643ms","start":"2026-09-23T03:21:18.962364Z","end":"2026-09-23T03:21:19.154701Z","steps":["trace[537042508] 'process raft request' (duration: 192.129805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:21:19.154912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.070381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-23T03:21:19.154957Z","caller":"traceutil/trace.go:171","msg":"trace[1405506186] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1994; }","duration":"116.181124ms","start":"2026-09-23T03:21:19.038762Z","end":"2026-09-23T03:21:19.154943Z","steps":["trace[1405506186] 'agreement among raft nodes before linearized reading' (duration: 116.02113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:21:51.486209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.979429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T03:21:51.486293Z","caller":"traceutil/trace.go:171","msg":"trace[2006049172] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"100.800256ms","start":"2026-09-23T03:21:51.38548Z","end":"2026-09-23T03:21:51.48628Z","steps":["trace[2006049172] 'read index received' (duration: 51.701µs)","trace[2006049172] 'applied index is now lower than readState.Index' (duration: 100.747755ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:21:51.486342Z","caller":"traceutil/trace.go:171","msg":"trace[1265793390] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"116.918332ms","start":"2026-09-23T03:21:51.369412Z","end":"2026-09-23T03:21:51.48633Z","steps":["trace[1265793390] 'process raft request' (duration: 13.409501ms)","trace[1265793390] 'compare' (duration: 102.790454ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:21:51.486393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.924029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-23T03:21:51.486414Z","caller":"traceutil/trace.go:171","msg":"trace[1876134960] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2186; }","duration":"100.9733ms","start":"2026-09-23T03:21:51.385433Z","end":"2026-09-23T03:21:51.486406Z","steps":["trace[1876134960] 'agreement among raft nodes before linearized reading' (duration: 100.879108ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:51.67958Z","caller":"traceutil/trace.go:171","msg":"trace[252918044] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"143.289285ms","start":"2026-09-23T03:21:51.536259Z","end":"2026-09-23T03:21:51.679548Z","steps":["trace[252918044] 'process raft request' (duration: 103.216874ms)","trace[252918044] 'compare' (duration: 39.770454ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:21:57.793236Z","caller":"traceutil/trace.go:171","msg":"trace[473794486] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2307; }","duration":"261.300303ms","start":"2026-09-23T03:21:57.5319Z","end":"2026-09-23T03:21:57.793201Z","steps":["trace[473794486] 'read index received' (duration: 243.814614ms)","trace[473794486] 'applied index is now lower than readState.Index' (duration: 17.484289ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:21:57.79337Z","caller":"traceutil/trace.go:171","msg":"trace[1660788376] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"282.205904ms","start":"2026-09-23T03:21:57.511101Z","end":"2026-09-23T03:21:57.793307Z","steps":["trace[1660788376] 'process raft request' (duration: 265.32554ms)","trace[1660788376] 'compare' (duration: 16.359722ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:21:57.793523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.60643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-23T03:21:57.793574Z","caller":"traceutil/trace.go:171","msg":"trace[42650851] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2220; }","duration":"261.704883ms","start":"2026-09-23T03:21:57.531855Z","end":"2026-09-23T03:21:57.79356Z","steps":["trace[42650851] 'agreement among raft nodes before linearized reading' (duration: 261.481088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:21:57.807372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.300064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:21:57.80744Z","caller":"traceutil/trace.go:171","msg":"trace[795201019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2221; }","duration":"241.428546ms","start":"2026-09-23T03:21:57.565994Z","end":"2026-09-23T03:21:57.807423Z","steps":["trace[795201019] 'agreement among raft nodes before linearized reading' (duration: 241.288983ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:21:57.807692Z","caller":"traceutil/trace.go:171","msg":"trace[134052046] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"103.140482ms","start":"2026-09-23T03:21:57.704497Z","end":"2026-09-23T03:21:57.807637Z","steps":["trace[134052046] 'process raft request' (duration: 102.688502ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:23:22.702532Z","caller":"traceutil/trace.go:171","msg":"trace[275175105] linearizableReadLoop","detail":"{readStateIndex:2985; appliedIndex:2984; }","duration":"118.599448ms","start":"2026-09-23T03:23:22.583907Z","end":"2026-09-23T03:23:22.702507Z","steps":["trace[275175105] 'read index received' (duration: 118.456854ms)","trace[275175105] 'applied index is now lower than readState.Index' (duration: 141.674µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:23:22.702589Z","caller":"traceutil/trace.go:171","msg":"trace[238322168] transaction","detail":"{read_only:false; response_revision:2872; number_of_response:1; }","duration":"119.171012ms","start":"2026-09-23T03:23:22.583395Z","end":"2026-09-23T03:23:22.702566Z","steps":["trace[238322168] 'process raft request' (duration: 118.957567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:23:22.7028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.876445ms","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-23T03:23:22.702883Z","caller":"traceutil/trace.go:171","msg":"trace[632461234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2872; }","duration":"118.999057ms","start":"2026-09-23T03:23:22.583866Z","end":"2026-09-23T03:23:22.702865Z","steps":["trace[632461234] 'agreement among raft nodes before linearized reading' (duration: 118.742511ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:26:40.621388Z","caller":"traceutil/trace.go:171","msg":"trace[1090991944] transaction","detail":"{read_only:false; response_revision:4114; number_of_response:1; }","duration":"132.392921ms","start":"2026-09-23T03:26:40.488958Z","end":"2026-09-23T03:26:40.621351Z","steps":["trace[1090991944] 'process raft request' (duration: 132.194146ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:26:40.658979Z","caller":"traceutil/trace.go:171","msg":"trace[1001470890] transaction","detail":"{read_only:false; response_revision:4115; number_of_response:1; }","duration":"106.290203ms","start":"2026-09-23T03:26:40.552651Z","end":"2026-09-23T03:26:40.658942Z","steps":["trace[1001470890] 'process raft request' (duration: 105.902104ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:13.638823Z","caller":"traceutil/trace.go:171","msg":"trace[704904020] linearizableReadLoop","detail":"{readStateIndex:5540; appliedIndex:5539; }","duration":"162.072124ms","start":"2026-09-23T03:28:13.476728Z","end":"2026-09-23T03:28:13.6388Z","steps":["trace[704904020] 'read index received' (duration: 92.465171ms)","trace[704904020] 'applied index is now lower than readState.Index' (duration: 69.606033ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:13.63898Z","caller":"traceutil/trace.go:171","msg":"trace[50362548] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"118.786714ms","start":"2026-09-23T03:28:13.520161Z","end":"2026-09-23T03:28:13.638948Z","steps":["trace[50362548] 'process raft request' (duration: 118.59585ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:13.639017Z","caller":"traceutil/trace.go:171","msg":"trace[2041374864] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"208.779096ms","start":"2026-09-23T03:28:13.430205Z","end":"2026-09-23T03:28:13.638984Z","steps":["trace[2041374864] 'process raft request' (duration: 138.924498ms)","trace[2041374864] 'compare' (duration: 69.210423ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:28:13.639327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.587917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787144"} {"level":"info","ts":"2026-09-23T03:28:13.639361Z","caller":"traceutil/trace.go:171","msg":"trace[492643259] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5337; }","duration":"162.692099ms","start":"2026-09-23T03:28:13.476658Z","end":"2026-09-23T03:28:13.639351Z","steps":["trace[492643259] 'agreement among raft nodes before linearized reading' (duration: 162.238908ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:13.977665Z","caller":"traceutil/trace.go:171","msg":"trace[1832380162] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"183.883137ms","start":"2026-09-23T03:28:13.793757Z","end":"2026-09-23T03:28:13.97764Z","steps":["trace[1832380162] 'process raft request' (duration: 183.729744ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:27.491792Z","caller":"traceutil/trace.go:171","msg":"trace[1348955499] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"202.264359ms","start":"2026-09-23T03:28:27.289488Z","end":"2026-09-23T03:28:27.491752Z","steps":["trace[1348955499] 'process raft request' (duration: 202.005383ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:27.640348Z","caller":"traceutil/trace.go:171","msg":"trace[1166414968] linearizableReadLoop","detail":"{readStateIndex:5796; appliedIndex:5794; }","duration":"111.047969ms","start":"2026-09-23T03:28:27.529271Z","end":"2026-09-23T03:28:27.640319Z","steps":["trace[1166414968] 'read index received' (duration: 7.524742ms)","trace[1166414968] 'applied index is now lower than readState.Index' (duration: 103.521807ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:27.640462Z","caller":"traceutil/trace.go:171","msg":"trace[16014107] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"112.133384ms","start":"2026-09-23T03:28:27.528255Z","end":"2026-09-23T03:28:27.640388Z","steps":["trace[16014107] 'process raft request' (duration: 34.513559ms)","trace[16014107] 'compare' (duration: 77.40736ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:28:27.640552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.270924ms","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-23T03:28:27.640658Z","caller":"traceutil/trace.go:171","msg":"trace[1519273487] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5584; }","duration":"111.421457ms","start":"2026-09-23T03:28:27.529217Z","end":"2026-09-23T03:28:27.640638Z","steps":["trace[1519273487] 'agreement among raft nodes before linearized reading' (duration: 111.224193ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:28.18341Z","caller":"traceutil/trace.go:171","msg":"trace[910270696] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"491.54623ms","start":"2026-09-23T03:28:27.691834Z","end":"2026-09-23T03:28:28.18338Z","steps":["trace[910270696] 'process raft request' (duration: 491.355455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:28.183581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:28:27.691808Z","time spent":"491.690423ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T03:28:28.184222Z","caller":"traceutil/trace.go:171","msg":"trace[2119606087] linearizableReadLoop","detail":"{readStateIndex:5798; appliedIndex:5796; }","duration":"457.353888ms","start":"2026-09-23T03:28:27.72684Z","end":"2026-09-23T03:28:28.184194Z","steps":["trace[2119606087] 'read index received' (duration: 456.414946ms)","trace[2119606087] 'applied index is now lower than readState.Index' (duration: 937.482µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:28.184268Z","caller":"traceutil/trace.go:171","msg":"trace[627507338] transaction","detail":"{read_only:false; response_revision:5586; number_of_response:1; }","duration":"460.835352ms","start":"2026-09-23T03:28:27.723406Z","end":"2026-09-23T03:28:28.184241Z","steps":["trace[627507338] 'process raft request' (duration: 460.623756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:28.184406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:28:27.723371Z","time spent":"460.978435ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T03:28:28.184489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.633855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-23T03:28:28.184503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.819609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-23T03:28:28.184511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.058143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-23T03:28:28.18455Z","caller":"traceutil/trace.go:171","msg":"trace[294748498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5586; }","duration":"457.752368ms","start":"2026-09-23T03:28:27.72678Z","end":"2026-09-23T03:28:28.184533Z","steps":["trace[294748498] 'agreement among raft nodes before linearized reading' (duration: 457.558433ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:28.184598Z","caller":"traceutil/trace.go:171","msg":"trace[1481314868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5586; }","duration":"142.155655ms","start":"2026-09-23T03:28:28.042399Z","end":"2026-09-23T03:28:28.184554Z","steps":["trace[1481314868] 'agreement among raft nodes before linearized reading' (duration: 141.983681ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:28.184596Z","caller":"traceutil/trace.go:171","msg":"trace[1833284851] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5586; }","duration":"370.922472ms","start":"2026-09-23T03:28:27.813624Z","end":"2026-09-23T03:28:28.184546Z","steps":["trace[1833284851] 'agreement among raft nodes before linearized reading' (duration: 370.8369ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:28.184636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:28:27.726755Z","time spent":"457.867581ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-23T03:28:28.184424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.46659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-23T03:28:28.18471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:28:27.813594Z","time spent":"371.072895ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-23T03:28:28.184733Z","caller":"traceutil/trace.go:171","msg":"trace[1572086487] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5586; }","duration":"422.809718ms","start":"2026-09-23T03:28:27.7619Z","end":"2026-09-23T03:28:28.18471Z","steps":["trace[1572086487] 'agreement among raft nodes before linearized reading' (duration: 422.47245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:28.184797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:28:27.761872Z","time spent":"422.90674ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-23T03:28:38.37598Z","caller":"traceutil/trace.go:171","msg":"trace[639272108] transaction","detail":"{read_only:false; response_revision:5792; number_of_response:1; }","duration":"128.110535ms","start":"2026-09-23T03:28:38.247838Z","end":"2026-09-23T03:28:38.375949Z","steps":["trace[639272108] 'process raft request' (duration: 98.94044ms)","trace[639272108] 'compare' (duration: 28.676254ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:38.391398Z","caller":"traceutil/trace.go:171","msg":"trace[1778697764] linearizableReadLoop","detail":"{readStateIndex:6009; appliedIndex:6007; }","duration":"117.427091ms","start":"2026-09-23T03:28:38.273947Z","end":"2026-09-23T03:28:38.391375Z","steps":["trace[1778697764] 'read index received' (duration: 72.837178ms)","trace[1778697764] 'applied index is now lower than readState.Index' (duration: 44.588773ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:28:38.391475Z","caller":"traceutil/trace.go:171","msg":"trace[2021148929] transaction","detail":"{read_only:false; response_revision:5793; number_of_response:1; }","duration":"142.225012ms","start":"2026-09-23T03:28:38.249227Z","end":"2026-09-23T03:28:38.391452Z","steps":["trace[2021148929] 'process raft request' (duration: 141.879744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:38.391702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.691337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82683c94-048e-4e18-971a-d196910c28af\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-23T03:28:38.392052Z","caller":"traceutil/trace.go:171","msg":"trace[1824620988] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82683c94-048e-4e18-971a-d196910c28af; range_end:; response_count:1; response_revision:5794; }","duration":"118.117788ms","start":"2026-09-23T03:28:38.273902Z","end":"2026-09-23T03:28:38.39202Z","steps":["trace[1824620988] 'agreement among raft nodes before linearized reading' (duration: 117.589795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:28:38.417742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.22001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-23T03:28:38.41783Z","caller":"traceutil/trace.go:171","msg":"trace[408552415] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5795; }","duration":"100.359954ms","start":"2026-09-23T03:28:38.317457Z","end":"2026-09-23T03:28:38.417817Z","steps":["trace[408552415] 'agreement among raft nodes before linearized reading' (duration: 99.758489ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:28:43.936999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-09-23T03:28:44.01541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"76.866353ms","hash":2472169593,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T03:28:44.015561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2472169593,"revision":2988,"compact-revision":-1} {"level":"info","ts":"2026-09-23T03:29:38.946114Z","caller":"traceutil/trace.go:171","msg":"trace[631801494] transaction","detail":"{read_only:false; response_revision:6339; number_of_response:1; }","duration":"115.619699ms","start":"2026-09-23T03:29:38.830457Z","end":"2026-09-23T03:29:38.946076Z","steps":["trace[631801494] 'process raft request' (duration: 102.989698ms)","trace[631801494] 'compare' (duration: 12.409205ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:33:43.945557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5882} {"level":"info","ts":"2026-09-23T03:33:44.035995Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5882,"took":"87.809984ms","hash":1685142718,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":26726400,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-23T03:33:44.036153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1685142718,"revision":5882,"compact-revision":2988} {"level":"info","ts":"2026-09-23T03:35:00.168863Z","caller":"traceutil/trace.go:171","msg":"trace[1389737756] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"215.078233ms","start":"2026-09-23T03:34:59.953755Z","end":"2026-09-23T03:35:00.168833Z","steps":["trace[1389737756] 'process raft request' (duration: 214.865928ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:11.804034Z","caller":"traceutil/trace.go:171","msg":"trace[379987182] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"218.70694ms","start":"2026-09-23T03:35:11.585291Z","end":"2026-09-23T03:35:11.803998Z","steps":["trace[379987182] 'process raft request' (duration: 218.484815ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:11.881659Z","caller":"traceutil/trace.go:171","msg":"trace[257960076] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"119.511293ms","start":"2026-09-23T03:35:11.762076Z","end":"2026-09-23T03:35:11.881588Z","steps":["trace[257960076] 'process raft request' (duration: 79.517536ms)","trace[257960076] 'compare' (duration: 39.237999ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:35:12.642822Z","caller":"traceutil/trace.go:171","msg":"trace[58406000] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"122.811264ms","start":"2026-09-23T03:35:12.519976Z","end":"2026-09-23T03:35:12.642787Z","steps":["trace[58406000] 'process raft request' (duration: 122.614589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:13.166075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.352927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-23T03:35:13.16631Z","caller":"traceutil/trace.go:171","msg":"trace[1723645495] transaction","detail":"{read_only:false; response_revision:8745; number_of_response:1; }","duration":"180.705603ms","start":"2026-09-23T03:35:12.985591Z","end":"2026-09-23T03:35:13.166297Z","steps":["trace[1723645495] 'process raft request' (duration: 180.600441ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:13.16636Z","caller":"traceutil/trace.go:171","msg":"trace[657177384] linearizableReadLoop","detail":"{readStateIndex:9055; appliedIndex:9054; }","duration":"199.644476ms","start":"2026-09-23T03:35:12.966664Z","end":"2026-09-23T03:35:13.166309Z","steps":["trace[657177384] 'read index received' (duration: 83.061723ms)","trace[657177384] 'applied index is now lower than readState.Index' (duration: 116.572832ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:35:13.166523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.84725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:35:13.166551Z","caller":"traceutil/trace.go:171","msg":"trace[184146989] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8745; }","duration":"199.922712ms","start":"2026-09-23T03:35:12.966619Z","end":"2026-09-23T03:35:13.166542Z","steps":["trace[184146989] 'agreement among raft nodes before linearized reading' (duration: 199.860551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:13.166654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.17893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:35:13.166694Z","caller":"traceutil/trace.go:171","msg":"trace[1149953976] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8745; }","duration":"180.300734ms","start":"2026-09-23T03:35:12.986382Z","end":"2026-09-23T03:35:13.166683Z","steps":["trace[1149953976] 'agreement among raft nodes before linearized reading' (duration: 180.1463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:13.166736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.663295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-db4fl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-23T03:35:13.166819Z","caller":"traceutil/trace.go:171","msg":"trace[465258689] range","detail":"{range_begin:/registry/pods/openstack/keepalived-db4fl; range_end:; response_count:1; response_revision:8745; }","duration":"137.788958ms","start":"2026-09-23T03:35:13.029011Z","end":"2026-09-23T03:35:13.1668Z","steps":["trace[465258689] 'agreement among raft nodes before linearized reading' (duration: 137.588754ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:14.834416Z","caller":"traceutil/trace.go:171","msg":"trace[538961177] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"101.918173ms","start":"2026-09-23T03:35:14.732459Z","end":"2026-09-23T03:35:14.834377Z","steps":["trace[538961177] 'process raft request' (duration: 12.466878ms)","trace[538961177] 'compare' (duration: 89.294091ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:35:39.270613Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002424.wal"} {"level":"info","ts":"2026-09-23T03:35:43.147367Z","caller":"traceutil/trace.go:171","msg":"trace[344027167] linearizableReadLoop","detail":"{readStateIndex:9274; appliedIndex:9273; }","duration":"320.321867ms","start":"2026-09-23T03:35:42.827011Z","end":"2026-09-23T03:35:43.147333Z","steps":["trace[344027167] 'read index received' (duration: 320.059431ms)","trace[344027167] 'applied index is now lower than readState.Index' (duration: 261.136µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:35:43.147434Z","caller":"traceutil/trace.go:171","msg":"trace[4838429] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"351.194795ms","start":"2026-09-23T03:35:42.796201Z","end":"2026-09-23T03:35:43.147396Z","steps":["trace[4838429] 'process raft request' (duration: 350.912728ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:43.147586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:35:42.796186Z","time spent":"351.318288ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T03:35:43.147734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.645405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-23T03:35:43.148073Z","caller":"traceutil/trace.go:171","msg":"trace[79755543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8954; }","duration":"321.086486ms","start":"2026-09-23T03:35:42.826973Z","end":"2026-09-23T03:35:43.14806Z","steps":["trace[79755543] 'agreement among raft nodes before linearized reading' (duration: 320.523332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:43.148115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:35:42.826955Z","time spent":"321.149837ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-23T03:35:43.16395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.681474ms","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-23T03:35:43.164031Z","caller":"traceutil/trace.go:171","msg":"trace[1903394771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8955; }","duration":"188.800258ms","start":"2026-09-23T03:35:42.975211Z","end":"2026-09-23T03:35:43.164011Z","steps":["trace[1903394771] 'agreement among raft nodes before linearized reading' (duration: 188.561802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:43.163997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.286509ms","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-23T03:35:43.164135Z","caller":"traceutil/trace.go:171","msg":"trace[1945914741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8955; }","duration":"274.513554ms","start":"2026-09-23T03:35:42.889598Z","end":"2026-09-23T03:35:43.164111Z","steps":["trace[1945914741] 'agreement among raft nodes before linearized reading' (duration: 274.058823ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:43.16443Z","caller":"traceutil/trace.go:171","msg":"trace[452496918] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"157.948651ms","start":"2026-09-23T03:35:43.006447Z","end":"2026-09-23T03:35:43.164396Z","steps":["trace[452496918] 'process raft request' (duration: 156.890855ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:43.270152Z","caller":"traceutil/trace.go:171","msg":"trace[1212920052] transaction","detail":"{read_only:false; response_revision:8956; number_of_response:1; }","duration":"117.181198ms","start":"2026-09-23T03:35:43.152941Z","end":"2026-09-23T03:35:43.270123Z","steps":["trace[1212920052] 'process raft request' (duration: 116.171594ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:43.270442Z","caller":"traceutil/trace.go:171","msg":"trace[331789593] transaction","detail":"{read_only:false; response_revision:8958; number_of_response:1; }","duration":"102.113868ms","start":"2026-09-23T03:35:43.168306Z","end":"2026-09-23T03:35:43.27042Z","steps":["trace[331789593] 'process raft request' (duration: 102.064637ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:43.270676Z","caller":"traceutil/trace.go:171","msg":"trace[1958159942] transaction","detail":"{read_only:false; response_revision:8957; number_of_response:1; }","duration":"102.355803ms","start":"2026-09-23T03:35:43.168292Z","end":"2026-09-23T03:35:43.270648Z","steps":["trace[1958159942] 'process raft request' (duration: 101.950004ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:47.34358Z","caller":"traceutil/trace.go:171","msg":"trace[1062804295] transaction","detail":"{read_only:false; response_revision:8975; number_of_response:1; }","duration":"179.086537ms","start":"2026-09-23T03:35:47.164469Z","end":"2026-09-23T03:35:47.343556Z","steps":["trace[1062804295] 'process raft request' (duration: 178.913253ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:47.344595Z","caller":"traceutil/trace.go:171","msg":"trace[195126044] transaction","detail":"{read_only:false; response_revision:8976; number_of_response:1; }","duration":"162.308276ms","start":"2026-09-23T03:35:47.182233Z","end":"2026-09-23T03:35:47.344541Z","steps":["trace[195126044] 'process raft request' (duration: 162.05167ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:56.378941Z","caller":"traceutil/trace.go:171","msg":"trace[1415129598] linearizableReadLoop","detail":"{readStateIndex:9348; appliedIndex:9347; }","duration":"155.599285ms","start":"2026-09-23T03:35:56.223322Z","end":"2026-09-23T03:35:56.378922Z","steps":["trace[1415129598] 'read index received' (duration: 155.453891ms)","trace[1415129598] 'applied index is now lower than readState.Index' (duration: 144.524µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:35:56.378988Z","caller":"traceutil/trace.go:171","msg":"trace[942773474] transaction","detail":"{read_only:false; response_revision:9026; number_of_response:1; }","duration":"157.159012ms","start":"2026-09-23T03:35:56.221801Z","end":"2026-09-23T03:35:56.37896Z","steps":["trace[942773474] 'process raft request' (duration: 156.978018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:56.37905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.712027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T03:35:56.379077Z","caller":"traceutil/trace.go:171","msg":"trace[469267255] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:9026; }","duration":"155.775109ms","start":"2026-09-23T03:35:56.223293Z","end":"2026-09-23T03:35:56.379068Z","steps":["trace[469267255] 'agreement among raft nodes before linearized reading' (duration: 155.704908ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:58.162316Z","caller":"traceutil/trace.go:171","msg":"trace[1060972625] linearizableReadLoop","detail":"{readStateIndex:9365; appliedIndex:9364; }","duration":"126.093631ms","start":"2026-09-23T03:35:58.036201Z","end":"2026-09-23T03:35:58.162294Z","steps":["trace[1060972625] 'read index received' (duration: 81.271161ms)","trace[1060972625] 'applied index is now lower than readState.Index' (duration: 44.82146ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:35:58.162429Z","caller":"traceutil/trace.go:171","msg":"trace[1904181656] transaction","detail":"{read_only:false; response_revision:9042; number_of_response:1; }","duration":"126.349226ms","start":"2026-09-23T03:35:58.036057Z","end":"2026-09-23T03:35:58.162406Z","steps":["trace[1904181656] 'process raft request' (duration: 81.332792ms)","trace[1904181656] 'compare' (duration: 44.771478ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:35:58.162756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.534551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-09-23T03:35:58.162801Z","caller":"traceutil/trace.go:171","msg":"trace[1554112871] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:9042; }","duration":"126.627963ms","start":"2026-09-23T03:35:58.036162Z","end":"2026-09-23T03:35:58.16279Z","steps":["trace[1554112871] 'agreement among raft nodes before linearized reading' (duration: 126.254464ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:35:58.166299Z","caller":"traceutil/trace.go:171","msg":"trace[563821922] transaction","detail":"{read_only:false; response_revision:9043; number_of_response:1; }","duration":"101.584095ms","start":"2026-09-23T03:35:58.064688Z","end":"2026-09-23T03:35:58.166272Z","steps":["trace[563821922] 'process raft request' (duration: 97.642191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:58.166537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.982825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-g2dt7\" ","response":"range_response_count:1 size:4668"} {"level":"info","ts":"2026-09-23T03:35:58.16661Z","caller":"traceutil/trace.go:171","msg":"trace[107225231] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-g2dt7; range_end:; response_count:1; response_revision:9043; }","duration":"102.087567ms","start":"2026-09-23T03:35:58.064506Z","end":"2026-09-23T03:35:58.166594Z","steps":["trace[107225231] 'agreement among raft nodes before linearized reading' (duration: 101.971504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:35:58.166873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.462958ms","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-23T03:35:58.166911Z","caller":"traceutil/trace.go:171","msg":"trace[1977897004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9043; }","duration":"118.54425ms","start":"2026-09-23T03:35:58.048358Z","end":"2026-09-23T03:35:58.166902Z","steps":["trace[1977897004] 'agreement among raft nodes before linearized reading' (duration: 118.326675ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:36:55.797759Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1b80133015df7bbe","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T03:36:55.807156Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-23T03:36:55.807301Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-23T03:37:22.722284Z","caller":"traceutil/trace.go:171","msg":"trace[13823577] transaction","detail":"{read_only:false; response_revision:9867; number_of_response:1; }","duration":"122.595712ms","start":"2026-09-23T03:37:22.599658Z","end":"2026-09-23T03:37:22.722254Z","steps":["trace[13823577] 'process raft request' (duration: 122.377127ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:37:22.875833Z","caller":"traceutil/trace.go:171","msg":"trace[478041857] linearizableReadLoop","detail":"{readStateIndex:10215; appliedIndex:10213; }","duration":"227.651865ms","start":"2026-09-23T03:37:22.648159Z","end":"2026-09-23T03:37:22.875811Z","steps":["trace[478041857] 'read index received' (duration: 73.850436ms)","trace[478041857] 'applied index is now lower than readState.Index' (duration: 153.800368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:37:22.875955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.781757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T03:37:22.875984Z","caller":"traceutil/trace.go:171","msg":"trace[65896298] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:9867; }","duration":"227.868929ms","start":"2026-09-23T03:37:22.648107Z","end":"2026-09-23T03:37:22.875976Z","steps":["trace[65896298] 'agreement among raft nodes before linearized reading' (duration: 227.792468ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:37:22.876421Z","caller":"traceutil/trace.go:171","msg":"trace[463368659] transaction","detail":"{read_only:false; response_revision:9868; number_of_response:1; }","duration":"115.563425ms","start":"2026-09-23T03:37:22.760837Z","end":"2026-09-23T03:37:22.876401Z","steps":["trace[463368659] 'process raft request' (duration: 114.888908ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:38:43.953308Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8040} {"level":"info","ts":"2026-09-23T03:38:44.019975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8040,"took":"64.514533ms","hash":1133885804,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":27873280,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T03:38:44.020041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1133885804,"revision":8040,"compact-revision":5882} {"level":"info","ts":"2026-09-23T03:43:01.992135Z","caller":"traceutil/trace.go:171","msg":"trace[1312168904] transaction","detail":"{read_only:false; response_revision:12310; number_of_response:1; }","duration":"120.398446ms","start":"2026-09-23T03:43:01.871702Z","end":"2026-09-23T03:43:01.9921Z","steps":["trace[1312168904] 'process raft request' (duration: 120.187962ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:43:43.960304Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10406} {"level":"info","ts":"2026-09-23T03:43:44.046348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10406,"took":"82.902008ms","hash":3929153366,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":26492928,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-23T03:43:44.046414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3929153366,"revision":10406,"compact-revision":8040} {"level":"info","ts":"2026-09-23T03:48:43.968589Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12612} {"level":"info","ts":"2026-09-23T03:48:44.050368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12612,"took":"79.149927ms","hash":2570422780,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":27770880,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-23T03:48:44.05044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2570422780,"revision":12612,"compact-revision":10406} {"level":"info","ts":"2026-09-23T03:49:12.539181Z","caller":"traceutil/trace.go:171","msg":"trace[1060969936] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"129.763749ms","start":"2026-09-23T03:49:12.40938Z","end":"2026-09-23T03:49:12.539143Z","steps":["trace[1060969936] 'process raft request' (duration: 129.525063ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:49:50.141511Z","caller":"traceutil/trace.go:171","msg":"trace[1263971632] transaction","detail":"{read_only:false; response_revision:15018; number_of_response:1; }","duration":"111.697096ms","start":"2026-09-23T03:49:50.02977Z","end":"2026-09-23T03:49:50.141467Z","steps":["trace[1263971632] 'process raft request' (duration: 90.16816ms)","trace[1263971632] 'compare' (duration: 21.411693ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:49:50.996406Z","caller":"traceutil/trace.go:171","msg":"trace[989316854] linearizableReadLoop","detail":"{readStateIndex:15543; appliedIndex:15542; }","duration":"318.516721ms","start":"2026-09-23T03:49:50.677865Z","end":"2026-09-23T03:49:50.996381Z","steps":["trace[989316854] 'read index received' (duration: 318.289926ms)","trace[989316854] 'applied index is now lower than readState.Index' (duration: 223.465µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:49:50.996509Z","caller":"traceutil/trace.go:171","msg":"trace[1960400571] transaction","detail":"{read_only:false; response_revision:15020; number_of_response:1; }","duration":"327.054755ms","start":"2026-09-23T03:49:50.669444Z","end":"2026-09-23T03:49:50.996499Z","steps":["trace[1960400571] 'process raft request' (duration: 326.742168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:49:50.996626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:49:50.669421Z","time spent":"327.111387ms","remote":"127.0.0.1:60534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T03:49:50.996684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.789608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:49:50.996811Z","caller":"traceutil/trace.go:171","msg":"trace[157042942] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:15020; }","duration":"318.938691ms","start":"2026-09-23T03:49:50.67785Z","end":"2026-09-23T03:49:50.996788Z","steps":["trace[157042942] 'agreement among raft nodes before linearized reading' (duration: 318.681225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:49:50.99687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:49:50.677811Z","time spent":"319.046244ms","remote":"127.0.0.1:60568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-09-23T03:49:51.001481Z","caller":"traceutil/trace.go:171","msg":"trace[74151427] transaction","detail":"{read_only:false; response_revision:15021; number_of_response:1; }","duration":"318.674575ms","start":"2026-09-23T03:49:50.682786Z","end":"2026-09-23T03:49:51.00146Z","steps":["trace[74151427] 'process raft request' (duration: 318.498251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:49:51.00156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.399164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:49:51.001577Z","caller":"traceutil/trace.go:171","msg":"trace[659929693] transaction","detail":"{read_only:false; response_revision:15022; number_of_response:1; }","duration":"302.305722ms","start":"2026-09-23T03:49:50.69924Z","end":"2026-09-23T03:49:51.001546Z","steps":["trace[659929693] 'process raft request' (duration: 302.168039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:49:51.001633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.308675ms","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-23T03:49:51.001747Z","caller":"traceutil/trace.go:171","msg":"trace[1482342843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15022; }","duration":"280.468839ms","start":"2026-09-23T03:49:50.721259Z","end":"2026-09-23T03:49:51.001728Z","steps":["trace[1482342843] 'agreement among raft nodes before linearized reading' (duration: 280.191503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:49:51.001761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.863552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-23T03:49:51.001762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:49:50.699221Z","time spent":"302.428946ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T03:49:51.001787Z","caller":"traceutil/trace.go:171","msg":"trace[868519227] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15022; }","duration":"162.930014ms","start":"2026-09-23T03:49:50.83885Z","end":"2026-09-23T03:49:51.001781Z","steps":["trace[868519227] 'agreement among raft nodes before linearized reading' (duration: 162.78549ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:49:51.001612Z","caller":"traceutil/trace.go:171","msg":"trace[521262840] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:15022; }","duration":"107.502955ms","start":"2026-09-23T03:49:50.894098Z","end":"2026-09-23T03:49:51.0016Z","steps":["trace[521262840] 'agreement among raft nodes before linearized reading' (duration: 107.380742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:49:51.001599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:49:50.68276Z","time spent":"318.782407ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T03:50:01.201021Z","caller":"traceutil/trace.go:171","msg":"trace[763027558] transaction","detail":"{read_only:false; response_revision:15191; number_of_response:1; }","duration":"141.735015ms","start":"2026-09-23T03:50:01.059256Z","end":"2026-09-23T03:50:01.200991Z","steps":["trace[763027558] 'process raft request' (duration: 76.934023ms)","trace[763027558] 'compare' (duration: 64.658619ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:50:01.201039Z","caller":"traceutil/trace.go:171","msg":"trace[241319652] linearizableReadLoop","detail":"{readStateIndex:15717; appliedIndex:15715; }","duration":"127.69798ms","start":"2026-09-23T03:50:01.07332Z","end":"2026-09-23T03:50:01.201018Z","steps":["trace[241319652] 'read index received' (duration: 41.621458ms)","trace[241319652] 'applied index is now lower than readState.Index' (duration: 86.075642ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:50:01.201344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.004047ms","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-23T03:50:01.201407Z","caller":"traceutil/trace.go:171","msg":"trace[830265154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15192; }","duration":"128.102129ms","start":"2026-09-23T03:50:01.073287Z","end":"2026-09-23T03:50:01.201389Z","steps":["trace[830265154] 'agreement among raft nodes before linearized reading' (duration: 127.756631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:01.617608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.017483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-23T03:50:01.617877Z","caller":"traceutil/trace.go:171","msg":"trace[1434559307] transaction","detail":"{read_only:false; response_revision:15194; number_of_response:1; }","duration":"191.59941ms","start":"2026-09-23T03:50:01.426252Z","end":"2026-09-23T03:50:01.617851Z","steps":["trace[1434559307] 'process raft request' (duration: 191.512988ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:50:01.617996Z","caller":"traceutil/trace.go:171","msg":"trace[221631149] linearizableReadLoop","detail":"{readStateIndex:15719; appliedIndex:15718; }","duration":"353.297704ms","start":"2026-09-23T03:50:01.264632Z","end":"2026-09-23T03:50:01.617929Z","steps":["trace[221631149] 'read index received' (duration: 177.878871ms)","trace[221631149] 'applied index is now lower than readState.Index' (duration: 175.411483ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:50:01.618154Z","caller":"traceutil/trace.go:171","msg":"trace[1325557488] transaction","detail":"{read_only:false; response_revision:15193; number_of_response:1; }","duration":"412.899511ms","start":"2026-09-23T03:50:01.205128Z","end":"2026-09-23T03:50:01.618028Z","steps":["trace[1325557488] 'process raft request' (duration: 237.367306ms)","trace[1325557488] 'compare' (duration: 174.88292ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:50:01.618288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.618791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-23T03:50:01.618308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:01.205112Z","time spent":"413.116107ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T03:50:01.618353Z","caller":"traceutil/trace.go:171","msg":"trace[1771506992] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15194; }","duration":"353.758845ms","start":"2026-09-23T03:50:01.264579Z","end":"2026-09-23T03:50:01.618338Z","steps":["trace[1771506992] 'agreement among raft nodes before linearized reading' (duration: 353.517049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:50:01.6184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:50:01.264535Z","time spent":"353.852677ms","remote":"127.0.0.1:60534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-23T03:50:26.464848Z","caller":"traceutil/trace.go:171","msg":"trace[471793054] transaction","detail":"{read_only:false; response_revision:15561; number_of_response:1; }","duration":"112.486007ms","start":"2026-09-23T03:50:26.352324Z","end":"2026-09-23T03:50:26.46481Z","steps":["trace[471793054] 'process raft request' (duration: 109.020273ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:51:09.295922Z","caller":"traceutil/trace.go:171","msg":"trace[2096145716] transaction","detail":"{read_only:false; response_revision:16111; number_of_response:1; }","duration":"112.411174ms","start":"2026-09-23T03:51:09.183482Z","end":"2026-09-23T03:51:09.295893Z","steps":["trace[2096145716] 'process raft request' (duration: 112.23686ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:51:29.095432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.258219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-23T03:51:29.095557Z","caller":"traceutil/trace.go:171","msg":"trace[1416838699] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:16219; }","duration":"215.488314ms","start":"2026-09-23T03:51:28.88004Z","end":"2026-09-23T03:51:29.095528Z","steps":["trace[1416838699] 'count revisions from in-memory index tree' (duration: 215.169617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:51:29.326755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.114748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-23T03:51:29.326894Z","caller":"traceutil/trace.go:171","msg":"trace[2108363651] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16220; }","duration":"100.302672ms","start":"2026-09-23T03:51:29.226569Z","end":"2026-09-23T03:51:29.326872Z","steps":["trace[2108363651] 'range keys from in-memory index tree' (duration: 100.024026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:51:29.327435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.059829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769795"} {"level":"info","ts":"2026-09-23T03:51:29.327512Z","caller":"traceutil/trace.go:171","msg":"trace[1623947880] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16220; }","duration":"127.174382ms","start":"2026-09-23T03:51:29.20032Z","end":"2026-09-23T03:51:29.327495Z","steps":["trace[1623947880] 'range keys from in-memory index tree' (duration: 126.112906ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:53:43.983648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14604} {"level":"info","ts":"2026-09-23T03:53:44.065996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14604,"took":"79.597862ms","hash":350166788,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":30560256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T03:53:44.066067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":350166788,"revision":14604,"compact-revision":12612} {"level":"info","ts":"2026-09-23T03:57:25.336374Z","caller":"traceutil/trace.go:171","msg":"trace[700654938] transaction","detail":"{read_only:false; response_revision:18793; number_of_response:1; }","duration":"109.822452ms","start":"2026-09-23T03:57:25.226529Z","end":"2026-09-23T03:57:25.336351Z","steps":["trace[700654938] 'process raft request' (duration: 109.181826ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:57:26.145453Z","caller":"traceutil/trace.go:171","msg":"trace[504252865] linearizableReadLoop","detail":"{readStateIndex:19444; appliedIndex:19443; }","duration":"192.251424ms","start":"2026-09-23T03:57:25.953168Z","end":"2026-09-23T03:57:26.14542Z","steps":["trace[504252865] 'read index received' (duration: 176.844968ms)","trace[504252865] 'applied index is now lower than readState.Index' (duration: 15.404866ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T03:57:26.145636Z","caller":"traceutil/trace.go:171","msg":"trace[508189293] transaction","detail":"{read_only:false; response_revision:18797; number_of_response:1; }","duration":"215.720788ms","start":"2026-09-23T03:57:25.929896Z","end":"2026-09-23T03:57:26.145617Z","steps":["trace[508189293] 'process raft request' (duration: 200.058106ms)","trace[508189293] 'compare' (duration: 14.998676ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T03:57:26.146128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.927131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T03:57:26.14625Z","caller":"traceutil/trace.go:171","msg":"trace[409181559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18797; }","duration":"193.131866ms","start":"2026-09-23T03:57:25.953091Z","end":"2026-09-23T03:57:26.146223Z","steps":["trace[409181559] 'agreement among raft nodes before linearized reading' (duration: 192.749317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:57:26.590152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.986695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cczpm\" ","response":"range_response_count:1 size:9792"} {"level":"warn","ts":"2026-09-23T03:57:26.590194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.775465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x7dtr\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-09-23T03:57:26.590264Z","caller":"traceutil/trace.go:171","msg":"trace[1706014450] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cczpm; range_end:; response_count:1; response_revision:18799; }","duration":"214.149519ms","start":"2026-09-23T03:57:26.376092Z","end":"2026-09-23T03:57:26.590242Z","steps":["trace[1706014450] 'range keys from in-memory index tree' (duration: 213.677428ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T03:57:26.590282Z","caller":"traceutil/trace.go:171","msg":"trace[49657576] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x7dtr; range_end:; response_count:1; response_revision:18799; }","duration":"334.941579ms","start":"2026-09-23T03:57:26.255319Z","end":"2026-09-23T03:57:26.590261Z","steps":["trace[49657576] 'range keys from in-memory index tree' (duration: 334.412146ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T03:57:26.590384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T03:57:26.255294Z","time spent":"335.028641ms","remote":"127.0.0.1:60534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22117,"request content":"key:\"/registry/pods/openstack/nova-compute-default-x7dtr\" "} {"level":"info","ts":"2026-09-23T03:58:16.691665Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1b80133015df7bbe","local-member-applied-index":20012,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T03:58:16.699514Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20012} {"level":"info","ts":"2026-09-23T03:58:16.699747Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15012} {"level":"info","ts":"2026-09-23T03:58:43.993234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17049} {"level":"info","ts":"2026-09-23T03:58:44.096406Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17049,"took":"99.609089ms","hash":883012934,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":34050048,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-23T03:58:44.096483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":883012934,"revision":17049,"compact-revision":14604} {"level":"info","ts":"2026-09-23T04:03:44.000495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19656} {"level":"info","ts":"2026-09-23T04:03:44.126774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19656,"took":"122.748325ms","hash":229279068,"current-db-size-bytes":56979456,"current-db-size":"57 MB","current-db-size-in-use-bytes":30236672,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:03:44.126846Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":229279068,"revision":19656,"compact-revision":17049} {"level":"info","ts":"2026-09-23T04:03:48.232514Z","caller":"traceutil/trace.go:171","msg":"trace[1210326784] transaction","detail":"{read_only:false; response_revision:21758; number_of_response:1; }","duration":"104.111136ms","start":"2026-09-23T04:03:48.128368Z","end":"2026-09-23T04:03:48.232479Z","steps":["trace[1210326784] 'process raft request' (duration: 103.941982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:03:48.428822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.804718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:03:48.42906Z","caller":"traceutil/trace.go:171","msg":"trace[1136084346] linearizableReadLoop","detail":"{readStateIndex:22505; appliedIndex:22503; }","duration":"164.344876ms","start":"2026-09-23T04:03:48.264695Z","end":"2026-09-23T04:03:48.42904Z","steps":["trace[1136084346] 'read index received' (duration: 48.217111ms)","trace[1136084346] 'applied index is now lower than readState.Index' (duration: 116.126495ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:03:48.429214Z","caller":"traceutil/trace.go:171","msg":"trace[1668064892] transaction","detail":"{read_only:false; response_revision:21761; number_of_response:1; }","duration":"157.03307ms","start":"2026-09-23T04:03:48.272088Z","end":"2026-09-23T04:03:48.429121Z","steps":["trace[1668064892] 'process raft request' (duration: 156.906677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:03:48.42922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.50332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:03:48.429267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.677412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T04:03:48.429306Z","caller":"traceutil/trace.go:171","msg":"trace[1967651937] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:21761; }","duration":"117.750424ms","start":"2026-09-23T04:03:48.311545Z","end":"2026-09-23T04:03:48.429295Z","steps":["trace[1967651937] 'agreement among raft nodes before linearized reading' (duration: 117.646082ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:03:48.429304Z","caller":"traceutil/trace.go:171","msg":"trace[456026566] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:21761; }","duration":"164.644963ms","start":"2026-09-23T04:03:48.264645Z","end":"2026-09-23T04:03:48.42929Z","steps":["trace[456026566] 'agreement among raft nodes before linearized reading' (duration: 164.488779ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:03:48.429668Z","caller":"traceutil/trace.go:171","msg":"trace[1715546829] transaction","detail":"{read_only:false; response_revision:21760; number_of_response:1; }","duration":"191.723594ms","start":"2026-09-23T04:03:48.237367Z","end":"2026-09-23T04:03:48.429091Z","steps":["trace[1715546829] 'process raft request' (duration: 191.570991ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:03:48.429629Z","caller":"traceutil/trace.go:171","msg":"trace[1236396248] transaction","detail":"{read_only:false; response_revision:21759; number_of_response:1; }","duration":"208.827387ms","start":"2026-09-23T04:03:48.22029Z","end":"2026-09-23T04:03:48.429117Z","steps":["trace[1236396248] 'process raft request' (duration: 92.64577ms)","trace[1236396248] 'compare' (duration: 115.664995ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:03:48.813803Z","caller":"traceutil/trace.go:171","msg":"trace[671247731] transaction","detail":"{read_only:false; response_revision:21762; number_of_response:1; }","duration":"131.67559ms","start":"2026-09-23T04:03:48.682101Z","end":"2026-09-23T04:03:48.813777Z","steps":["trace[671247731] 'process raft request' (duration: 131.542207ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:03:48.853867Z","caller":"traceutil/trace.go:171","msg":"trace[270529356] linearizableReadLoop","detail":"{readStateIndex:22508; appliedIndex:22506; }","duration":"162.105362ms","start":"2026-09-23T04:03:48.691733Z","end":"2026-09-23T04:03:48.853838Z","steps":["trace[270529356] 'read index received' (duration: 121.904005ms)","trace[270529356] 'applied index is now lower than readState.Index' (duration: 40.200147ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:03:48.854194Z","caller":"traceutil/trace.go:171","msg":"trace[2011675930] transaction","detail":"{read_only:false; response_revision:21763; number_of_response:1; }","duration":"172.037901ms","start":"2026-09-23T04:03:48.68213Z","end":"2026-09-23T04:03:48.854167Z","steps":["trace[2011675930] 'process raft request' (duration: 171.55096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:03:48.854268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.522032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:03:48.854318Z","caller":"traceutil/trace.go:171","msg":"trace[427286619] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:21763; }","duration":"162.628855ms","start":"2026-09-23T04:03:48.691676Z","end":"2026-09-23T04:03:48.854305Z","steps":["trace[427286619] 'agreement among raft nodes before linearized reading' (duration: 162.528142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:03:49.256654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.044792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:03:49.25691Z","caller":"traceutil/trace.go:171","msg":"trace[578913583] transaction","detail":"{read_only:false; response_revision:21766; number_of_response:1; }","duration":"279.649341ms","start":"2026-09-23T04:03:48.977223Z","end":"2026-09-23T04:03:49.256872Z","steps":["trace[578913583] 'process raft request' (duration: 134.287973ms)","trace[578913583] 'compare' (duration: 144.915338ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:07:56.686686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.550778ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:07:56.686832Z","caller":"traceutil/trace.go:171","msg":"trace[875585444] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24340; }","duration":"178.717682ms","start":"2026-09-23T04:07:56.508095Z","end":"2026-09-23T04:07:56.686813Z","steps":["trace[875585444] 'range keys from in-memory index tree' (duration: 178.528788ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:07:56.686956Z","caller":"traceutil/trace.go:171","msg":"trace[135319423] transaction","detail":"{read_only:false; response_revision:24341; number_of_response:1; }","duration":"225.762735ms","start":"2026-09-23T04:07:56.461156Z","end":"2026-09-23T04:07:56.686918Z","steps":["trace[135319423] 'process raft request' (duration: 156.767064ms)","trace[135319423] 'compare' (duration: 68.699004ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:07:56.692365Z","caller":"traceutil/trace.go:171","msg":"trace[479876909] transaction","detail":"{read_only:false; response_revision:24342; number_of_response:1; }","duration":"191.378926ms","start":"2026-09-23T04:07:56.500962Z","end":"2026-09-23T04:07:56.692341Z","steps":["trace[479876909] 'process raft request' (duration: 191.217743ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:08:44.007618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21735} {"level":"info","ts":"2026-09-23T04:08:44.116264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21735,"took":"105.036049ms","hash":2997623399,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":42803200,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-23T04:08:44.116327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2997623399,"revision":21735,"compact-revision":19656} {"level":"info","ts":"2026-09-23T04:09:31.828752Z","caller":"traceutil/trace.go:171","msg":"trace[1781929454] transaction","detail":"{read_only:false; response_revision:25303; number_of_response:1; }","duration":"101.530023ms","start":"2026-09-23T04:09:31.726985Z","end":"2026-09-23T04:09:31.828515Z","steps":["trace[1781929454] 'process raft request' (duration: 101.348119ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:09:31.837506Z","caller":"traceutil/trace.go:171","msg":"trace[1147330265] linearizableReadLoop","detail":"{readStateIndex:26149; appliedIndex:26147; }","duration":"104.603107ms","start":"2026-09-23T04:09:31.73288Z","end":"2026-09-23T04:09:31.837483Z","steps":["trace[1147330265] 'read index received' (duration: 95.542149ms)","trace[1147330265] 'applied index is now lower than readState.Index' (duration: 9.059848ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:09:31.837531Z","caller":"traceutil/trace.go:171","msg":"trace[1239486404] transaction","detail":"{read_only:false; response_revision:25304; number_of_response:1; }","duration":"105.592622ms","start":"2026-09-23T04:09:31.731917Z","end":"2026-09-23T04:09:31.83751Z","steps":["trace[1239486404] 'process raft request' (duration: 105.443839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:09:31.837652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.749192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:09:31.837706Z","caller":"traceutil/trace.go:171","msg":"trace[865697173] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25304; }","duration":"104.846403ms","start":"2026-09-23T04:09:31.732846Z","end":"2026-09-23T04:09:31.837693Z","steps":["trace[865697173] 'agreement among raft nodes before linearized reading' (duration: 104.729551ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:08.477883Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069ac.wal"} {"level":"warn","ts":"2026-09-23T04:11:40.465756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.731957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:40.466038Z","caller":"traceutil/trace.go:171","msg":"trace[296314152] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:26551; }","duration":"131.27657ms","start":"2026-09-23T04:11:40.33474Z","end":"2026-09-23T04:11:40.466017Z","steps":["trace[296314152] 'count revisions from in-memory index tree' (duration: 130.600494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:40.882561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.556319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:11:40.882889Z","caller":"traceutil/trace.go:171","msg":"trace[1532374657] linearizableReadLoop","detail":"{readStateIndex:27433; appliedIndex:27432; }","duration":"171.816646ms","start":"2026-09-23T04:11:40.711058Z","end":"2026-09-23T04:11:40.882875Z","steps":["trace[1532374657] 'read index received' (duration: 31.513889ms)","trace[1532374657] 'applied index is now lower than readState.Index' (duration: 140.300477ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:11:40.882963Z","caller":"traceutil/trace.go:171","msg":"trace[771382311] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"262.491029ms","start":"2026-09-23T04:11:40.620406Z","end":"2026-09-23T04:11:40.882897Z","steps":["trace[771382311] 'process raft request' (duration: 122.183161ms)","trace[771382311] 'compare' (duration: 138.910814ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:40.883038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.98437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:11:40.883078Z","caller":"traceutil/trace.go:171","msg":"trace[865399072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26555; }","duration":"172.054682ms","start":"2026-09-23T04:11:40.711009Z","end":"2026-09-23T04:11:40.883064Z","steps":["trace[865399072] 'agreement among raft nodes before linearized reading' (duration: 171.925009ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:41.088781Z","caller":"traceutil/trace.go:171","msg":"trace[320882162] linearizableReadLoop","detail":"{readStateIndex:27434; appliedIndex:27433; }","duration":"205.845095ms","start":"2026-09-23T04:11:40.882914Z","end":"2026-09-23T04:11:41.088759Z","steps":["trace[320882162] 'read index received' (duration: 205.746362ms)","trace[320882162] 'applied index is now lower than readState.Index' (duration: 97.633µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:41.088929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.980013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:41.088895Z","caller":"traceutil/trace.go:171","msg":"trace[941244022] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"206.646484ms","start":"2026-09-23T04:11:40.882213Z","end":"2026-09-23T04:11:41.088859Z","steps":["trace[941244022] 'process raft request' (duration: 206.404418ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:41.088969Z","caller":"traceutil/trace.go:171","msg":"trace[428823342] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:26556; }","duration":"281.056616ms","start":"2026-09-23T04:11:40.807899Z","end":"2026-09-23T04:11:41.088956Z","steps":["trace[428823342] 'agreement among raft nodes before linearized reading' (duration: 280.959353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:41.089208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.309636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T04:11:41.089309Z","caller":"traceutil/trace.go:171","msg":"trace[1429540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26556; }","duration":"282.432738ms","start":"2026-09-23T04:11:40.806833Z","end":"2026-09-23T04:11:41.089266Z","steps":["trace[1429540] 'agreement among raft nodes before linearized reading' (duration: 282.164722ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:41.10416Z","caller":"traceutil/trace.go:171","msg":"trace[1503509867] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"216.932111ms","start":"2026-09-23T04:11:40.887183Z","end":"2026-09-23T04:11:41.104115Z","steps":["trace[1503509867] 'process raft request' (duration: 216.747457ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:41.317701Z","caller":"traceutil/trace.go:171","msg":"trace[888165650] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"223.760206ms","start":"2026-09-23T04:11:41.093901Z","end":"2026-09-23T04:11:41.317661Z","steps":["trace[888165650] 'process raft request' (duration: 146.853755ms)","trace[888165650] 'compare' (duration: 76.716627ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:11:41.435752Z","caller":"traceutil/trace.go:171","msg":"trace[1757232341] linearizableReadLoop","detail":"{readStateIndex:27438; appliedIndex:27437; }","duration":"113.368349ms","start":"2026-09-23T04:11:41.322357Z","end":"2026-09-23T04:11:41.435726Z","steps":["trace[1757232341] 'read index received' (duration: 99.862714ms)","trace[1757232341] 'applied index is now lower than readState.Index' (duration: 13.504765ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:11:41.435779Z","caller":"traceutil/trace.go:171","msg":"trace[1907893791] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"113.919332ms","start":"2026-09-23T04:11:41.321826Z","end":"2026-09-23T04:11:41.435745Z","steps":["trace[1907893791] 'process raft request' (duration: 100.376806ms)","trace[1907893791] 'compare' (duration: 13.398442ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:41.436021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.637906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-09-23T04:11:41.436102Z","caller":"traceutil/trace.go:171","msg":"trace[1999234461] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26560; }","duration":"113.767509ms","start":"2026-09-23T04:11:41.322315Z","end":"2026-09-23T04:11:41.436082Z","steps":["trace[1999234461] 'agreement among raft nodes before linearized reading' (duration: 113.514993ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:41.436313Z","caller":"traceutil/trace.go:171","msg":"trace[209657928] transaction","detail":"{read_only:false; response_revision:26561; number_of_response:1; }","duration":"113.203055ms","start":"2026-09-23T04:11:41.32306Z","end":"2026-09-23T04:11:41.436263Z","steps":["trace[209657928] 'process raft request' (duration: 112.99301ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:41.436536Z","caller":"traceutil/trace.go:171","msg":"trace[768657851] transaction","detail":"{read_only:false; response_revision:26562; number_of_response:1; }","duration":"107.356084ms","start":"2026-09-23T04:11:41.329156Z","end":"2026-09-23T04:11:41.436512Z","steps":["trace[768657851] 'process raft request' (duration: 106.993246ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:45.864924Z","caller":"traceutil/trace.go:171","msg":"trace[2031872870] transaction","detail":"{read_only:false; response_revision:26603; number_of_response:1; }","duration":"120.847528ms","start":"2026-09-23T04:11:45.744052Z","end":"2026-09-23T04:11:45.864899Z","steps":["trace[2031872870] 'process raft request' (duration: 120.687635ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:46.568148Z","caller":"traceutil/trace.go:171","msg":"trace[187087578] transaction","detail":"{read_only:false; response_revision:26604; number_of_response:1; }","duration":"262.266563ms","start":"2026-09-23T04:11:46.305858Z","end":"2026-09-23T04:11:46.568125Z","steps":["trace[187087578] 'process raft request' (duration: 262.108889ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:46.57746Z","caller":"traceutil/trace.go:171","msg":"trace[1098206393] linearizableReadLoop","detail":"{readStateIndex:27484; appliedIndex:27482; }","duration":"262.035547ms","start":"2026-09-23T04:11:46.315408Z","end":"2026-09-23T04:11:46.577444Z","steps":["trace[1098206393] 'read index received' (duration: 252.61923ms)","trace[1098206393] 'applied index is now lower than readState.Index' (duration: 9.414867ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:11:46.577716Z","caller":"traceutil/trace.go:171","msg":"trace[477532576] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"264.246091ms","start":"2026-09-23T04:11:46.313442Z","end":"2026-09-23T04:11:46.577688Z","steps":["trace[477532576] 'process raft request' (duration: 263.77716ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:46.577905Z","caller":"traceutil/trace.go:171","msg":"trace[1039464914] transaction","detail":"{read_only:false; response_revision:26606; number_of_response:1; }","duration":"136.091706ms","start":"2026-09-23T04:11:46.441763Z","end":"2026-09-23T04:11:46.577855Z","steps":["trace[1039464914] 'process raft request' (duration: 135.570823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:46.577941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.519819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-23T04:11:46.578109Z","caller":"traceutil/trace.go:171","msg":"trace[271725360] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26608; }","duration":"262.728534ms","start":"2026-09-23T04:11:46.315369Z","end":"2026-09-23T04:11:46.578097Z","steps":["trace[271725360] 'agreement among raft nodes before linearized reading' (duration: 262.382425ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:46.907509Z","caller":"traceutil/trace.go:171","msg":"trace[1312296319] linearizableReadLoop","detail":"{readStateIndex:27488; appliedIndex:27487; }","duration":"239.788802ms","start":"2026-09-23T04:11:46.667692Z","end":"2026-09-23T04:11:46.907481Z","steps":["trace[1312296319] 'read index received' (duration: 239.642038ms)","trace[1312296319] 'applied index is now lower than readState.Index' (duration: 145.484µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:11:46.907651Z","caller":"traceutil/trace.go:171","msg":"trace[118875706] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"310.154366ms","start":"2026-09-23T04:11:46.597487Z","end":"2026-09-23T04:11:46.907641Z","steps":["trace[118875706] 'process raft request' (duration: 309.822638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:46.907761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:46.597467Z","time spent":"310.216977ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:11:46.907858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.14145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:46.907953Z","caller":"traceutil/trace.go:171","msg":"trace[256301965] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26609; }","duration":"240.280864ms","start":"2026-09-23T04:11:46.667649Z","end":"2026-09-23T04:11:46.90793Z","steps":["trace[256301965] 'agreement among raft nodes before linearized reading' (duration: 240.1393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:46.908044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.976234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:46.908174Z","caller":"traceutil/trace.go:171","msg":"trace[687077209] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26609; }","duration":"124.386594ms","start":"2026-09-23T04:11:46.78377Z","end":"2026-09-23T04:11:46.908156Z","steps":["trace[687077209] 'agreement among raft nodes before linearized reading' (duration: 123.962004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:47.466347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.713137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:11:47.466454Z","caller":"traceutil/trace.go:171","msg":"trace[1076455583] linearizableReadLoop","detail":"{readStateIndex:27490; appliedIndex:27488; }","duration":"338.710692ms","start":"2026-09-23T04:11:47.12772Z","end":"2026-09-23T04:11:47.466431Z","steps":["trace[1076455583] 'read index received' (duration: 67.30464ms)","trace[1076455583] 'applied index is now lower than readState.Index' (duration: 271.404262ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:47.466607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.268381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:47.466603Z","caller":"traceutil/trace.go:171","msg":"trace[587931262] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"344.079251ms","start":"2026-09-23T04:11:47.122489Z","end":"2026-09-23T04:11:47.466568Z","steps":["trace[587931262] 'process raft request' (duration: 193.036716ms)","trace[587931262] 'compare' (duration: 150.437911ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:11:47.466659Z","caller":"traceutil/trace.go:171","msg":"trace[266451949] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:26611; }","duration":"151.388844ms","start":"2026-09-23T04:11:47.315255Z","end":"2026-09-23T04:11:47.466644Z","steps":["trace[266451949] 'agreement among raft nodes before linearized reading' (duration: 151.292362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:47.466672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.932137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-23T04:11:47.466749Z","caller":"traceutil/trace.go:171","msg":"trace[819470485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26611; }","duration":"339.0579ms","start":"2026-09-23T04:11:47.127672Z","end":"2026-09-23T04:11:47.46673Z","steps":["trace[819470485] 'agreement among raft nodes before linearized reading' (duration: 338.852055ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:47.466757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:47.122467Z","time spent":"344.204444ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:11:47.466792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:47.127651Z","time spent":"339.132172ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-23T04:11:47.466906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.377942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T04:11:47.466993Z","caller":"traceutil/trace.go:171","msg":"trace[1900930592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26611; }","duration":"126.507025ms","start":"2026-09-23T04:11:47.340467Z","end":"2026-09-23T04:11:47.466974Z","steps":["trace[1900930592] 'agreement among raft nodes before linearized reading' (duration: 126.28483ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:47.886447Z","caller":"traceutil/trace.go:171","msg":"trace[1452962290] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"411.170797ms","start":"2026-09-23T04:11:47.475253Z","end":"2026-09-23T04:11:47.886424Z","steps":["trace[1452962290] 'process raft request' (duration: 411.089785ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:47.886537Z","caller":"traceutil/trace.go:171","msg":"trace[950809983] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"414.61626ms","start":"2026-09-23T04:11:47.47188Z","end":"2026-09-23T04:11:47.886496Z","steps":["trace[950809983] 'process raft request' (duration: 414.281692ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:47.886709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:47.471863Z","time spent":"414.751753ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:11:47.886713Z","caller":"traceutil/trace.go:171","msg":"trace[867018473] transaction","detail":"{read_only:false; response_revision:26616; number_of_response:1; }","duration":"409.328853ms","start":"2026-09-23T04:11:47.477367Z","end":"2026-09-23T04:11:47.886696Z","steps":["trace[867018473] 'process raft request' (duration: 409.219ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:47.886721Z","caller":"traceutil/trace.go:171","msg":"trace[1518930652] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"411.160097ms","start":"2026-09-23T04:11:47.475533Z","end":"2026-09-23T04:11:47.886693Z","steps":["trace[1518930652] 'process raft request' (duration: 410.85079ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:47.886716Z","caller":"traceutil/trace.go:171","msg":"trace[658056901] linearizableReadLoop","detail":"{readStateIndex:27495; appliedIndex:27491; }","duration":"323.254181ms","start":"2026-09-23T04:11:47.563438Z","end":"2026-09-23T04:11:47.886693Z","steps":["trace[658056901] 'read index received' (duration: 322.769899ms)","trace[658056901] 'applied index is now lower than readState.Index' (duration: 483.292µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:47.886805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:47.47734Z","time spent":"409.409464ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:11:47.886849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:47.47552Z","time spent":"411.257299ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:11:47.886983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:47.475238Z","time spent":"411.393273ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:11:47.886982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.460436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:47.887047Z","caller":"traceutil/trace.go:171","msg":"trace[1029969950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26616; }","duration":"323.65021ms","start":"2026-09-23T04:11:47.563385Z","end":"2026-09-23T04:11:47.887036Z","steps":["trace[1029969950] 'agreement among raft nodes before linearized reading' (duration: 323.427575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:47.887106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:47.563365Z","time spent":"323.729693ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T04:11:47.90591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.783788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.112\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-23T04:11:47.905996Z","caller":"traceutil/trace.go:171","msg":"trace[798313711] range","detail":"{range_begin:/registry/masterleases/162.253.55.112; range_end:; response_count:1; response_revision:26617; }","duration":"292.91213ms","start":"2026-09-23T04:11:47.613064Z","end":"2026-09-23T04:11:47.905976Z","steps":["trace[798313711] 'agreement among raft nodes before linearized reading' (duration: 292.697505ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:48.070746Z","caller":"traceutil/trace.go:171","msg":"trace[92146204] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"148.082945ms","start":"2026-09-23T04:11:47.922635Z","end":"2026-09-23T04:11:48.070718Z","steps":["trace[92146204] 'process raft request' (duration: 144.274583ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:48.723738Z","caller":"traceutil/trace.go:171","msg":"trace[1659762414] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"150.151853ms","start":"2026-09-23T04:11:48.573567Z","end":"2026-09-23T04:11:48.723718Z","steps":["trace[1659762414] 'process raft request' (duration: 150.01528ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:49.039352Z","caller":"traceutil/trace.go:171","msg":"trace[665463416] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"454.685382ms","start":"2026-09-23T04:11:48.584642Z","end":"2026-09-23T04:11:49.039327Z","steps":["trace[665463416] 'process raft request' (duration: 411.482104ms)","trace[665463416] 'compare' (duration: 42.918771ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:49.039495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:48.584619Z","time spent":"454.800664ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:11:49.039612Z","caller":"traceutil/trace.go:171","msg":"trace[1141931806] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"454.852835ms","start":"2026-09-23T04:11:48.584739Z","end":"2026-09-23T04:11:49.039592Z","steps":["trace[1141931806] 'process raft request' (duration: 454.475156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:49.039734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:48.584732Z","time spent":"454.932308ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:11:49.03997Z","caller":"traceutil/trace.go:171","msg":"trace[569556457] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"455.165183ms","start":"2026-09-23T04:11:48.584786Z","end":"2026-09-23T04:11:49.039951Z","steps":["trace[569556457] 'process raft request' (duration: 454.724832ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:49.039964Z","caller":"traceutil/trace.go:171","msg":"trace[591838228] linearizableReadLoop","detail":"{readStateIndex:27503; appliedIndex:27500; }","duration":"233.848021ms","start":"2026-09-23T04:11:48.806092Z","end":"2026-09-23T04:11:49.03994Z","steps":["trace[591838228] 'read index received' (duration: 190.048269ms)","trace[591838228] 'applied index is now lower than readState.Index' (duration: 43.798092ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:49.040061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:48.58478Z","time spent":"455.229634ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:11:49.04011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.999865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:49.040144Z","caller":"traceutil/trace.go:171","msg":"trace[303190515] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26623; }","duration":"234.086667ms","start":"2026-09-23T04:11:48.806047Z","end":"2026-09-23T04:11:49.040134Z","steps":["trace[303190515] 'agreement among raft nodes before linearized reading' (duration: 233.983735ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:49.041983Z","caller":"traceutil/trace.go:171","msg":"trace[1065107532] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"128.273211ms","start":"2026-09-23T04:11:48.913678Z","end":"2026-09-23T04:11:49.041951Z","steps":["trace[1065107532] 'process raft request' (duration: 128.134708ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:49.736612Z","caller":"traceutil/trace.go:171","msg":"trace[1097157458] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"264.758682ms","start":"2026-09-23T04:11:49.471789Z","end":"2026-09-23T04:11:49.736548Z","steps":["trace[1097157458] 'process raft request' (duration: 264.595378ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:49.73666Z","caller":"traceutil/trace.go:171","msg":"trace[105791971] linearizableReadLoop","detail":"{readStateIndex:27506; appliedIndex:27506; }","duration":"211.398487ms","start":"2026-09-23T04:11:49.525244Z","end":"2026-09-23T04:11:49.736643Z","steps":["trace[105791971] 'read index received' (duration: 211.386977ms)","trace[105791971] 'applied index is now lower than readState.Index' (duration: 10.05µs)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:49.73683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.570442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-23T04:11:49.736877Z","caller":"traceutil/trace.go:171","msg":"trace[107272828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26626; }","duration":"211.667864ms","start":"2026-09-23T04:11:49.525199Z","end":"2026-09-23T04:11:49.736867Z","steps":["trace[107272828] 'agreement among raft nodes before linearized reading' (duration: 211.49143ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:49.762835Z","caller":"traceutil/trace.go:171","msg":"trace[1776194790] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"125.811421ms","start":"2026-09-23T04:11:49.636995Z","end":"2026-09-23T04:11:49.762806Z","steps":["trace[1776194790] 'process raft request' (duration: 125.438072ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:49.762838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.727195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-23T04:11:49.762904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.505011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:49.763217Z","caller":"traceutil/trace.go:171","msg":"trace[1118315921] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26627; }","duration":"212.110105ms","start":"2026-09-23T04:11:49.551036Z","end":"2026-09-23T04:11:49.763146Z","steps":["trace[1118315921] 'agreement among raft nodes before linearized reading' (duration: 211.715715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:49.76305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.035899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-23T04:11:49.763409Z","caller":"traceutil/trace.go:171","msg":"trace[1940365668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26627; }","duration":"197.927651ms","start":"2026-09-23T04:11:49.565275Z","end":"2026-09-23T04:11:49.763273Z","steps":["trace[1940365668] 'agreement among raft nodes before linearized reading' (duration: 197.538161ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:49.763493Z","caller":"traceutil/trace.go:171","msg":"trace[1497035702] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26627; }","duration":"150.701666ms","start":"2026-09-23T04:11:49.612772Z","end":"2026-09-23T04:11:49.763474Z","steps":["trace[1497035702] 'agreement among raft nodes before linearized reading' (duration: 150.004219ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:50.089762Z","caller":"traceutil/trace.go:171","msg":"trace[360007700] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"153.02413ms","start":"2026-09-23T04:11:49.936719Z","end":"2026-09-23T04:11:50.089743Z","steps":["trace[360007700] 'process raft request' (duration: 152.837956ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:50.089758Z","caller":"traceutil/trace.go:171","msg":"trace[1778123070] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"152.98885ms","start":"2026-09-23T04:11:49.936744Z","end":"2026-09-23T04:11:50.089733Z","steps":["trace[1778123070] 'process raft request' (duration: 152.929479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:50.328527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.584924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:11:50.328782Z","caller":"traceutil/trace.go:171","msg":"trace[1553778830] linearizableReadLoop","detail":"{readStateIndex:27513; appliedIndex:27511; }","duration":"158.875203ms","start":"2026-09-23T04:11:50.169888Z","end":"2026-09-23T04:11:50.328763Z","steps":["trace[1553778830] 'read index received' (duration: 57.018082ms)","trace[1553778830] 'applied index is now lower than readState.Index' (duration: 101.856011ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:11:50.328814Z","caller":"traceutil/trace.go:171","msg":"trace[2092915005] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"391.862032ms","start":"2026-09-23T04:11:49.936931Z","end":"2026-09-23T04:11:50.328793Z","steps":["trace[2092915005] 'process raft request' (duration: 391.74583ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:11:50.328807Z","caller":"traceutil/trace.go:171","msg":"trace[1847319995] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"391.859283ms","start":"2026-09-23T04:11:49.936897Z","end":"2026-09-23T04:11:50.328756Z","steps":["trace[1847319995] 'process raft request' (duration: 289.930471ms)","trace[1847319995] 'compare' (duration: 101.400619ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:11:50.328947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:49.936917Z","time spent":"391.949955ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:11:50.328918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.997206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:11:50.329023Z","caller":"traceutil/trace.go:171","msg":"trace[2016302204] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26633; }","duration":"159.1551ms","start":"2026-09-23T04:11:50.169843Z","end":"2026-09-23T04:11:50.328998Z","steps":["trace[2016302204] 'agreement among raft nodes before linearized reading' (duration: 158.975396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:11:50.328942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:11:49.936889Z","time spent":"391.983635ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:00.964869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.39477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:00.964972Z","caller":"traceutil/trace.go:171","msg":"trace[756995569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26707; }","duration":"113.543214ms","start":"2026-09-23T04:12:00.851401Z","end":"2026-09-23T04:12:00.964944Z","steps":["trace[756995569] 'count revisions from in-memory index tree' (duration: 113.272807ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:01.181062Z","caller":"traceutil/trace.go:171","msg":"trace[1850110507] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"100.247012ms","start":"2026-09-23T04:12:01.080788Z","end":"2026-09-23T04:12:01.181035Z","steps":["trace[1850110507] 'process raft request' (duration: 100.061827ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:01.235181Z","caller":"traceutil/trace.go:171","msg":"trace[1266592738] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"141.297716ms","start":"2026-09-23T04:12:01.09386Z","end":"2026-09-23T04:12:01.235158Z","steps":["trace[1266592738] 'process raft request' (duration: 141.123352ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:01.235459Z","caller":"traceutil/trace.go:171","msg":"trace[1888876079] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"141.119452ms","start":"2026-09-23T04:12:01.094329Z","end":"2026-09-23T04:12:01.235448Z","steps":["trace[1888876079] 'process raft request' (duration: 140.790234ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:01.23574Z","caller":"traceutil/trace.go:171","msg":"trace[2009829315] transaction","detail":"{read_only:false; response_revision:26711; number_of_response:1; }","duration":"139.532114ms","start":"2026-09-23T04:12:01.096182Z","end":"2026-09-23T04:12:01.235714Z","steps":["trace[2009829315] 'process raft request' (duration: 139.289798ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:02.048862Z","caller":"traceutil/trace.go:171","msg":"trace[1057744754] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"194.518628ms","start":"2026-09-23T04:12:01.854317Z","end":"2026-09-23T04:12:02.048836Z","steps":["trace[1057744754] 'process raft request' (duration: 194.338284ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:02.093632Z","caller":"traceutil/trace.go:171","msg":"trace[1615169028] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"124.116331ms","start":"2026-09-23T04:12:01.96947Z","end":"2026-09-23T04:12:02.093587Z","steps":["trace[1615169028] 'process raft request' (duration: 124.010038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:02.505208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.798259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-23T04:12:02.505275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.327014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-23T04:12:02.505327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.852142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T04:12:02.505364Z","caller":"traceutil/trace.go:171","msg":"trace[834990624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26716; }","duration":"361.444136ms","start":"2026-09-23T04:12:02.143904Z","end":"2026-09-23T04:12:02.505349Z","steps":["trace[834990624] 'range keys from in-memory index tree' (duration: 361.194631ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:02.505332Z","caller":"traceutil/trace.go:171","msg":"trace[309347384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26716; }","duration":"362.995494ms","start":"2026-09-23T04:12:02.142318Z","end":"2026-09-23T04:12:02.505313Z","steps":["trace[309347384] 'range keys from in-memory index tree' (duration: 362.594304ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:02.505374Z","caller":"traceutil/trace.go:171","msg":"trace[1584941699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26716; }","duration":"125.929594ms","start":"2026-09-23T04:12:02.379431Z","end":"2026-09-23T04:12:02.505361Z","steps":["trace[1584941699] 'range keys from in-memory index tree' (duration: 125.646387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:02.505404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.143894Z","time spent":"361.502188ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-23T04:12:02.50543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.142272Z","time spent":"363.138007ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-23T04:12:02.5057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.601049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-23T04:12:02.50574Z","caller":"traceutil/trace.go:171","msg":"trace[1398870260] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26716; }","duration":"342.64104ms","start":"2026-09-23T04:12:02.163089Z","end":"2026-09-23T04:12:02.50573Z","steps":["trace[1398870260] 'range keys from in-memory index tree' (duration: 342.482626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:02.505766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.163079Z","time spent":"342.681551ms","remote":"127.0.0.1:60534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-23T04:12:02.505884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.331027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:02.505966Z","caller":"traceutil/trace.go:171","msg":"trace[1491092575] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26716; }","duration":"275.436031ms","start":"2026-09-23T04:12:02.230517Z","end":"2026-09-23T04:12:02.505953Z","steps":["trace[1491092575] 'count revisions from in-memory index tree' (duration: 275.274516ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:02.506196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.225023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T04:12:02.506594Z","caller":"traceutil/trace.go:171","msg":"trace[107540383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26716; }","duration":"103.620673ms","start":"2026-09-23T04:12:02.402957Z","end":"2026-09-23T04:12:02.506578Z","steps":["trace[107540383] 'range keys from in-memory index tree' (duration: 103.108191ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:02.842799Z","caller":"traceutil/trace.go:171","msg":"trace[982414024] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"332.35304ms","start":"2026-09-23T04:12:02.510417Z","end":"2026-09-23T04:12:02.84277Z","steps":["trace[982414024] 'process raft request' (duration: 332.191606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:02.842955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.510402Z","time spent":"332.473513ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:03.131648Z","caller":"traceutil/trace.go:171","msg":"trace[1289342382] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"620.835507ms","start":"2026-09-23T04:12:02.510787Z","end":"2026-09-23T04:12:03.131623Z","steps":["trace[1289342382] 'process raft request' (duration: 566.371856ms)","trace[1289342382] 'compare' (duration: 54.274606ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:12:03.131805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.510776Z","time spent":"620.954579ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:03.132045Z","caller":"traceutil/trace.go:171","msg":"trace[93508312] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"621.132814ms","start":"2026-09-23T04:12:02.510861Z","end":"2026-09-23T04:12:03.131993Z","steps":["trace[93508312] 'process raft request' (duration: 620.719254ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:03.132204Z","caller":"traceutil/trace.go:171","msg":"trace[1837552782] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"621.103423ms","start":"2026-09-23T04:12:02.511076Z","end":"2026-09-23T04:12:03.132179Z","steps":["trace[1837552782] 'process raft request' (duration: 620.803886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.132233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.510841Z","time spent":"621.288428ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:03.132364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.511051Z","time spent":"621.223207ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:03.132639Z","caller":"traceutil/trace.go:171","msg":"trace[776412174] linearizableReadLoop","detail":"{readStateIndex:27600; appliedIndex:27598; }","duration":"621.754949ms","start":"2026-09-23T04:12:02.510859Z","end":"2026-09-23T04:12:03.132614Z","steps":["trace[776412174] 'read index received' (duration: 331.768056ms)","trace[776412174] 'applied index is now lower than readState.Index' (duration: 289.983103ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:03.132755Z","caller":"traceutil/trace.go:171","msg":"trace[349874735] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"363.878575ms","start":"2026-09-23T04:12:02.76886Z","end":"2026-09-23T04:12:03.132739Z","steps":["trace[349874735] 'process raft request' (duration: 363.242479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.132859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.768839Z","time spent":"363.968557ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:03.132884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.480878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:12:03.132911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.331652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:03.132954Z","caller":"traceutil/trace.go:171","msg":"trace[2016277500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26721; }","duration":"568.59882ms","start":"2026-09-23T04:12:02.564338Z","end":"2026-09-23T04:12:03.132937Z","steps":["trace[2016277500] 'agreement among raft nodes before linearized reading' (duration: 568.439426ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:03.132978Z","caller":"traceutil/trace.go:171","msg":"trace[980207553] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26721; }","duration":"175.447855ms","start":"2026-09-23T04:12:02.957514Z","end":"2026-09-23T04:12:03.132962Z","steps":["trace[980207553] 'agreement among raft nodes before linearized reading' (duration: 175.324132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.132997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.564323Z","time spent":"568.665732ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-23T04:12:03.133161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.272632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19231"} {"level":"info","ts":"2026-09-23T04:12:03.133223Z","caller":"traceutil/trace.go:171","msg":"trace[265963949] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26721; }","duration":"622.380484ms","start":"2026-09-23T04:12:02.510826Z","end":"2026-09-23T04:12:03.133206Z","steps":["trace[265963949] 'agreement among raft nodes before linearized reading' (duration: 622.002705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.133264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:02.510808Z","time spent":"622.444006ms","remote":"127.0.0.1:60524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19254,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-23T04:12:03.621918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.207251ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-23T04:12:03.736394Z","caller":"traceutil/trace.go:171","msg":"trace[1021008863] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"494.79485ms","start":"2026-09-23T04:12:03.241574Z","end":"2026-09-23T04:12:03.736369Z","steps":["trace[1021008863] 'process raft request' (duration: 494.699678ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:03.736421Z","caller":"traceutil/trace.go:171","msg":"trace[729014182] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"495.284692ms","start":"2026-09-23T04:12:03.241113Z","end":"2026-09-23T04:12:03.736398Z","steps":["trace[729014182] 'process raft request' (duration: 495.108238ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:03.736443Z","caller":"traceutil/trace.go:171","msg":"trace[381763713] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"549.002405ms","start":"2026-09-23T04:12:03.18739Z","end":"2026-09-23T04:12:03.736392Z","steps":["trace[381763713] 'process raft request' (duration: 548.675997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.736542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.241083Z","time spent":"495.395435ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:03.736544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.241557Z","time spent":"494.915213ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:03.736699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.187362Z","time spent":"549.19016ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:03.736769Z","caller":"traceutil/trace.go:171","msg":"trace[35392846] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"494.696328ms","start":"2026-09-23T04:12:03.242051Z","end":"2026-09-23T04:12:03.736747Z","steps":["trace[35392846] 'process raft request' (duration: 494.280037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.736855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.242035Z","time spent":"494.77554ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:03.737068Z","caller":"traceutil/trace.go:171","msg":"trace[191084536] linearizableReadLoop","detail":"{readStateIndex:27605; appliedIndex:27603; }","duration":"548.047892ms","start":"2026-09-23T04:12:03.189001Z","end":"2026-09-23T04:12:03.737049Z","steps":["trace[191084536] 'read index received' (duration: 219.699599ms)","trace[191084536] 'applied index is now lower than readState.Index' (duration: 328.347393ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:03.737157Z","caller":"traceutil/trace.go:171","msg":"trace[2050664931] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"328.675691ms","start":"2026-09-23T04:12:03.408469Z","end":"2026-09-23T04:12:03.737144Z","steps":["trace[2050664931] 'process raft request' (duration: 328.538808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.737232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.408447Z","time spent":"328.750343ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:03.737322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.293927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-09-23T04:12:03.73736Z","caller":"traceutil/trace.go:171","msg":"trace[1949250194] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:26727; }","duration":"548.40667ms","start":"2026-09-23T04:12:03.188939Z","end":"2026-09-23T04:12:03.737345Z","steps":["trace[1949250194] 'agreement among raft nodes before linearized reading' (duration: 548.225736ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.737391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.188913Z","time spent":"548.469812ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-09-23T04:12:03.737472Z","caller":"traceutil/trace.go:171","msg":"trace[969260420] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"422.982829ms","start":"2026-09-23T04:12:03.314474Z","end":"2026-09-23T04:12:03.737456Z","steps":["trace[969260420] 'process raft request' (duration: 422.442046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.737577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.314446Z","time spent":"423.082831ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:03.737761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.555986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277881"} {"level":"info","ts":"2026-09-23T04:12:03.73781Z","caller":"traceutil/trace.go:171","msg":"trace[738011323] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26727; }","duration":"485.632148ms","start":"2026-09-23T04:12:03.252161Z","end":"2026-09-23T04:12:03.737794Z","steps":["trace[738011323] 'agreement among raft nodes before linearized reading' (duration: 485.214178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.737841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.25215Z","time spent":"485.682449ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":277904,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-09-23T04:12:03.737977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.436132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:03.738011Z","caller":"traceutil/trace.go:171","msg":"trace[516857600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26727; }","duration":"172.507914ms","start":"2026-09-23T04:12:03.565493Z","end":"2026-09-23T04:12:03.738001Z","steps":["trace[516857600] 'agreement among raft nodes before linearized reading' (duration: 172.456393ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.738103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.04328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:03.738131Z","caller":"traceutil/trace.go:171","msg":"trace[953617242] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26727; }","duration":"470.111522ms","start":"2026-09-23T04:12:03.26801Z","end":"2026-09-23T04:12:03.738122Z","steps":["trace[953617242] 'agreement among raft nodes before linearized reading' (duration: 470.066231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:03.738153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.267989Z","time spent":"470.158753ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-23T04:12:03.738153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.073926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:03.738215Z","caller":"traceutil/trace.go:171","msg":"trace[473074664] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:26727; }","duration":"168.154918ms","start":"2026-09-23T04:12:03.570041Z","end":"2026-09-23T04:12:03.738195Z","steps":["trace[473074664] 'agreement among raft nodes before linearized reading' (duration: 168.053945ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:04.439099Z","caller":"traceutil/trace.go:171","msg":"trace[1775461568] linearizableReadLoop","detail":"{readStateIndex:27611; appliedIndex:27610; }","duration":"385.015018ms","start":"2026-09-23T04:12:04.054061Z","end":"2026-09-23T04:12:04.439076Z","steps":["trace[1775461568] 'read index received' (duration: 384.882815ms)","trace[1775461568] 'applied index is now lower than readState.Index' (duration: 131.293µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:04.439164Z","caller":"traceutil/trace.go:171","msg":"trace[1478185751] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"645.539846ms","start":"2026-09-23T04:12:03.793595Z","end":"2026-09-23T04:12:04.439135Z","steps":["trace[1478185751] 'process raft request' (duration: 645.308191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:04.439331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.228613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-23T04:12:04.439349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:03.793576Z","time spent":"645.655589ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:04.439369Z","caller":"traceutil/trace.go:171","msg":"trace[177786156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26728; }","duration":"385.338386ms","start":"2026-09-23T04:12:04.05402Z","end":"2026-09-23T04:12:04.439358Z","steps":["trace[177786156] 'agreement among raft nodes before linearized reading' (duration: 385.166982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:04.439401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:04.054004Z","time spent":"385.389057ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-23T04:12:04.749863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.669853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:12:04.750092Z","caller":"traceutil/trace.go:171","msg":"trace[508396508] transaction","detail":"{read_only:false; response_revision:26730; number_of_response:1; }","duration":"305.843318ms","start":"2026-09-23T04:12:04.44423Z","end":"2026-09-23T04:12:04.750073Z","steps":["trace[508396508] 'process raft request' (duration: 305.756586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:04.750168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:04.444198Z","time spent":"305.93751ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:04.750246Z","caller":"traceutil/trace.go:171","msg":"trace[379726765] transaction","detail":"{read_only:false; response_revision:26729; number_of_response:1; }","duration":"649.959714ms","start":"2026-09-23T04:12:04.100237Z","end":"2026-09-23T04:12:04.750197Z","steps":["trace[379726765] 'process raft request' (duration: 357.87483ms)","trace[379726765] 'compare' (duration: 291.440698ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:12:04.75043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:04.100217Z","time spent":"650.124887ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:12:04.750276Z","caller":"traceutil/trace.go:171","msg":"trace[226735190] linearizableReadLoop","detail":"{readStateIndex:27612; appliedIndex:27611; }","duration":"311.095905ms","start":"2026-09-23T04:12:04.43916Z","end":"2026-09-23T04:12:04.750256Z","steps":["trace[226735190] 'read index received' (duration: 19.053332ms)","trace[226735190] 'applied index is now lower than readState.Index' (duration: 292.040493ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:12:04.750473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.488828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-09-23T04:12:04.750516Z","caller":"traceutil/trace.go:171","msg":"trace[625954283] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:26730; }","duration":"594.606631ms","start":"2026-09-23T04:12:04.1559Z","end":"2026-09-23T04:12:04.750506Z","steps":["trace[625954283] 'agreement among raft nodes before linearized reading' (duration: 594.429947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:04.750556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:04.155876Z","time spent":"594.672782ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1589,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"warn","ts":"2026-09-23T04:12:04.750659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.987194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:12:04.750705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.229842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-b77cfcd55-2qk78.18d7d62f647ede5d\" ","response":"range_response_count:1 size:2106"} {"level":"info","ts":"2026-09-23T04:12:04.750735Z","caller":"traceutil/trace.go:171","msg":"trace[1519791283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26730; }","duration":"175.129567ms","start":"2026-09-23T04:12:04.575588Z","end":"2026-09-23T04:12:04.750718Z","steps":["trace[1519791283] 'agreement among raft nodes before linearized reading' (duration: 174.986574ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:04.75076Z","caller":"traceutil/trace.go:171","msg":"trace[2026967152] range","detail":"{range_begin:/registry/events/openstack/glance-api-b77cfcd55-2qk78.18d7d62f647ede5d; range_end:; response_count:1; response_revision:26730; }","duration":"119.322594ms","start":"2026-09-23T04:12:04.631425Z","end":"2026-09-23T04:12:04.750748Z","steps":["trace[2026967152] 'agreement among raft nodes before linearized reading' (duration: 119.17641ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:05.037667Z","caller":"traceutil/trace.go:171","msg":"trace[267270692] transaction","detail":"{read_only:false; response_revision:26731; number_of_response:1; }","duration":"121.698221ms","start":"2026-09-23T04:12:04.915945Z","end":"2026-09-23T04:12:05.037643Z","steps":["trace[267270692] 'process raft request' (duration: 121.583198ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:05.037905Z","caller":"traceutil/trace.go:171","msg":"trace[721731479] linearizableReadLoop","detail":"{readStateIndex:27614; appliedIndex:27613; }","duration":"284.461169ms","start":"2026-09-23T04:12:04.753433Z","end":"2026-09-23T04:12:05.037894Z","steps":["trace[721731479] 'read index received' (duration: 270.403108ms)","trace[721731479] 'applied index is now lower than readState.Index' (duration: 14.057091ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:12:05.038488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.029593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9e5caa18-a2a1-43ee-8d84-022fe1e423a9\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-09-23T04:12:05.038571Z","caller":"traceutil/trace.go:171","msg":"trace[2100266610] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9e5caa18-a2a1-43ee-8d84-022fe1e423a9; range_end:; response_count:1; response_revision:26731; }","duration":"285.138425ms","start":"2026-09-23T04:12:04.753412Z","end":"2026-09-23T04:12:05.03855Z","steps":["trace[2100266610] 'agreement among raft nodes before linearized reading' (duration: 284.910239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:05.039055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.890574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-23T04:12:05.039126Z","caller":"traceutil/trace.go:171","msg":"trace[767946419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26731; }","duration":"192.001847ms","start":"2026-09-23T04:12:04.847108Z","end":"2026-09-23T04:12:05.03911Z","steps":["trace[767946419] 'agreement among raft nodes before linearized reading' (duration: 190.993322ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:05.177175Z","caller":"traceutil/trace.go:171","msg":"trace[1262791724] transaction","detail":"{read_only:false; response_revision:26732; number_of_response:1; }","duration":"138.364724ms","start":"2026-09-23T04:12:05.038784Z","end":"2026-09-23T04:12:05.177149Z","steps":["trace[1262791724] 'process raft request' (duration: 137.870052ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:05.178344Z","caller":"traceutil/trace.go:171","msg":"trace[1310977030] transaction","detail":"{read_only:false; response_revision:26733; number_of_response:1; }","duration":"135.769312ms","start":"2026-09-23T04:12:05.042545Z","end":"2026-09-23T04:12:05.178314Z","steps":["trace[1310977030] 'process raft request' (duration: 135.491255ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:10.996674Z","caller":"traceutil/trace.go:171","msg":"trace[1379808187] transaction","detail":"{read_only:false; response_revision:26831; number_of_response:1; }","duration":"267.137359ms","start":"2026-09-23T04:12:10.729509Z","end":"2026-09-23T04:12:10.996647Z","steps":["trace[1379808187] 'process raft request' (duration: 266.956515ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:11.265037Z","caller":"traceutil/trace.go:171","msg":"trace[1977863385] linearizableReadLoop","detail":"{readStateIndex:27717; appliedIndex:27715; }","duration":"491.47738ms","start":"2026-09-23T04:12:10.773535Z","end":"2026-09-23T04:12:11.265013Z","steps":["trace[1977863385] 'read index received' (duration: 222.924387ms)","trace[1977863385] 'applied index is now lower than readState.Index' (duration: 268.551983ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:11.265208Z","caller":"traceutil/trace.go:171","msg":"trace[1037428683] transaction","detail":"{read_only:false; response_revision:26832; number_of_response:1; }","duration":"497.089056ms","start":"2026-09-23T04:12:10.767993Z","end":"2026-09-23T04:12:11.265082Z","steps":["trace[1037428683] 'process raft request' (duration: 496.86895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:11.265474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:10.767974Z","time spent":"497.407254ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:12:11.265248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.692125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:12:11.265744Z","caller":"traceutil/trace.go:171","msg":"trace[1421065926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26832; }","duration":"492.203968ms","start":"2026-09-23T04:12:10.773506Z","end":"2026-09-23T04:12:11.26571Z","steps":["trace[1421065926] 'agreement among raft nodes before linearized reading' (duration: 491.618354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:11.265824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:12:10.773495Z","time spent":"492.30645ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-23T04:12:11.43664Z","caller":"traceutil/trace.go:171","msg":"trace[790847618] transaction","detail":"{read_only:false; response_revision:26835; number_of_response:1; }","duration":"167.281757ms","start":"2026-09-23T04:12:11.269339Z","end":"2026-09-23T04:12:11.43662Z","steps":["trace[790847618] 'process raft request' (duration: 135.736402ms)","trace[790847618] 'compare' (duration: 31.446662ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:11.585922Z","caller":"traceutil/trace.go:171","msg":"trace[1576931074] transaction","detail":"{read_only:false; response_revision:26836; number_of_response:1; }","duration":"288.491627ms","start":"2026-09-23T04:12:11.297404Z","end":"2026-09-23T04:12:11.585895Z","steps":["trace[1576931074] 'process raft request' (duration: 240.082283ms)","trace[1576931074] 'compare' (duration: 48.21751ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:11.58628Z","caller":"traceutil/trace.go:171","msg":"trace[1701025147] transaction","detail":"{read_only:false; response_revision:26838; number_of_response:1; }","duration":"287.928113ms","start":"2026-09-23T04:12:11.29834Z","end":"2026-09-23T04:12:11.586268Z","steps":["trace[1701025147] 'process raft request' (duration: 287.744699ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:11.586393Z","caller":"traceutil/trace.go:171","msg":"trace[1072706884] transaction","detail":"{read_only:false; response_revision:26839; number_of_response:1; }","duration":"287.545814ms","start":"2026-09-23T04:12:11.298838Z","end":"2026-09-23T04:12:11.586383Z","steps":["trace[1072706884] 'process raft request' (duration: 287.348269ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:11.586395Z","caller":"traceutil/trace.go:171","msg":"trace[650238167] transaction","detail":"{read_only:false; response_revision:26837; number_of_response:1; }","duration":"288.166229ms","start":"2026-09-23T04:12:11.298177Z","end":"2026-09-23T04:12:11.586343Z","steps":["trace[650238167] 'process raft request' (duration: 287.674737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:18.146607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.15424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:18.146663Z","caller":"traceutil/trace.go:171","msg":"trace[1144284387] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:26912; }","duration":"112.257492ms","start":"2026-09-23T04:12:18.034392Z","end":"2026-09-23T04:12:18.14665Z","steps":["trace[1144284387] 'count revisions from in-memory index tree' (duration: 112.065508ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:20.548675Z","caller":"traceutil/trace.go:171","msg":"trace[551982164] transaction","detail":"{read_only:false; response_revision:26932; number_of_response:1; }","duration":"210.69656ms","start":"2026-09-23T04:12:20.337932Z","end":"2026-09-23T04:12:20.548628Z","steps":["trace[551982164] 'process raft request' (duration: 210.439744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:42.074638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.808584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:12:42.074848Z","caller":"traceutil/trace.go:171","msg":"trace[1026880895] transaction","detail":"{read_only:false; response_revision:27108; number_of_response:1; }","duration":"198.808692ms","start":"2026-09-23T04:12:41.876021Z","end":"2026-09-23T04:12:42.074829Z","steps":["trace[1026880895] 'process raft request' (duration: 198.72337ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:42.074921Z","caller":"traceutil/trace.go:171","msg":"trace[1364649544] transaction","detail":"{read_only:false; response_revision:27107; number_of_response:1; }","duration":"205.596587ms","start":"2026-09-23T04:12:41.869269Z","end":"2026-09-23T04:12:42.074866Z","steps":["trace[1364649544] 'process raft request' (duration: 55.450686ms)","trace[1364649544] 'compare' (duration: 149.68338ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:42.075148Z","caller":"traceutil/trace.go:171","msg":"trace[1331578784] transaction","detail":"{read_only:false; response_revision:27111; number_of_response:1; }","duration":"193.257286ms","start":"2026-09-23T04:12:41.881881Z","end":"2026-09-23T04:12:42.075139Z","steps":["trace[1331578784] 'process raft request' (duration: 193.208195ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:42.075165Z","caller":"traceutil/trace.go:171","msg":"trace[1234360828] transaction","detail":"{read_only:false; response_revision:27109; number_of_response:1; }","duration":"195.094971ms","start":"2026-09-23T04:12:41.880027Z","end":"2026-09-23T04:12:42.075122Z","steps":["trace[1234360828] 'process raft request' (duration: 194.770083ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:42.075174Z","caller":"traceutil/trace.go:171","msg":"trace[957378463] transaction","detail":"{read_only:false; response_revision:27110; number_of_response:1; }","duration":"194.401414ms","start":"2026-09-23T04:12:41.880737Z","end":"2026-09-23T04:12:42.075138Z","steps":["trace[957378463] 'process raft request' (duration: 194.275601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:42.933117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.449546ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-23T04:12:43.499739Z","caller":"traceutil/trace.go:171","msg":"trace[368232265] transaction","detail":"{read_only:false; response_revision:27113; number_of_response:1; }","duration":"101.416469ms","start":"2026-09-23T04:12:43.398292Z","end":"2026-09-23T04:12:43.499709Z","steps":["trace[368232265] 'process raft request' (duration: 101.230435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:45.02902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.295651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:12:45.02912Z","caller":"traceutil/trace.go:171","msg":"trace[1705655340] transaction","detail":"{read_only:false; response_revision:27126; number_of_response:1; }","duration":"192.698973ms","start":"2026-09-23T04:12:44.836403Z","end":"2026-09-23T04:12:45.029102Z","steps":["trace[1705655340] 'process raft request' (duration: 50.241068ms)","trace[1705655340] 'compare' (duration: 142.197239ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:45.609706Z","caller":"traceutil/trace.go:171","msg":"trace[1363667233] transaction","detail":"{read_only:false; response_revision:27128; number_of_response:1; }","duration":"103.243014ms","start":"2026-09-23T04:12:45.506434Z","end":"2026-09-23T04:12:45.609677Z","steps":["trace[1363667233] 'process raft request' (duration: 103.008738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:46.16118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.370692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:12:46.161374Z","caller":"traceutil/trace.go:171","msg":"trace[1287851291] linearizableReadLoop","detail":"{readStateIndex:28026; appliedIndex:28023; }","duration":"267.618571ms","start":"2026-09-23T04:12:45.893738Z","end":"2026-09-23T04:12:46.161357Z","steps":["trace[1287851291] 'read index received' (duration: 111.100115ms)","trace[1287851291] 'applied index is now lower than readState.Index' (duration: 156.516905ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:46.161384Z","caller":"traceutil/trace.go:171","msg":"trace[1248736167] transaction","detail":"{read_only:false; response_revision:27131; number_of_response:1; }","duration":"272.907378ms","start":"2026-09-23T04:12:45.888448Z","end":"2026-09-23T04:12:46.161356Z","steps":["trace[1248736167] 'process raft request' (duration: 116.29102ms)","trace[1248736167] 'compare' (duration: 156.27949ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:46.161538Z","caller":"traceutil/trace.go:171","msg":"trace[1750794358] transaction","detail":"{read_only:false; response_revision:27132; number_of_response:1; }","duration":"272.826077ms","start":"2026-09-23T04:12:45.888674Z","end":"2026-09-23T04:12:46.1615Z","steps":["trace[1750794358] 'process raft request' (duration: 272.586241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:46.161591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.858006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129204"} {"level":"info","ts":"2026-09-23T04:12:46.161636Z","caller":"traceutil/trace.go:171","msg":"trace[959168136] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:27133; }","duration":"267.967519ms","start":"2026-09-23T04:12:45.893656Z","end":"2026-09-23T04:12:46.161624Z","steps":["trace[959168136] 'agreement among raft nodes before linearized reading' (duration: 267.751744ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:46.161749Z","caller":"traceutil/trace.go:171","msg":"trace[1145456145] transaction","detail":"{read_only:false; response_revision:27133; number_of_response:1; }","duration":"272.470459ms","start":"2026-09-23T04:12:45.889215Z","end":"2026-09-23T04:12:46.161686Z","steps":["trace[1145456145] 'process raft request' (duration: 272.081849ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:46.177097Z","caller":"traceutil/trace.go:171","msg":"trace[980416081] transaction","detail":"{read_only:false; response_revision:27134; number_of_response:1; }","duration":"100.357404ms","start":"2026-09-23T04:12:46.07672Z","end":"2026-09-23T04:12:46.177077Z","steps":["trace[980416081] 'process raft request' (duration: 100.20285ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:56.489587Z","caller":"traceutil/trace.go:171","msg":"trace[671224128] transaction","detail":"{read_only:false; response_revision:27211; number_of_response:1; }","duration":"127.638685ms","start":"2026-09-23T04:12:56.361926Z","end":"2026-09-23T04:12:56.489564Z","steps":["trace[671224128] 'process raft request' (duration: 127.496932ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:56.81326Z","caller":"traceutil/trace.go:171","msg":"trace[1127157032] linearizableReadLoop","detail":"{readStateIndex:28107; appliedIndex:28106; }","duration":"105.54433ms","start":"2026-09-23T04:12:56.707691Z","end":"2026-09-23T04:12:56.813235Z","steps":["trace[1127157032] 'read index received' (duration: 105.371816ms)","trace[1127157032] 'applied index is now lower than readState.Index' (duration: 171.274µs)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:12:56.813374Z","caller":"traceutil/trace.go:171","msg":"trace[1435224009] transaction","detail":"{read_only:false; response_revision:27212; number_of_response:1; }","duration":"111.480154ms","start":"2026-09-23T04:12:56.701861Z","end":"2026-09-23T04:12:56.813341Z","steps":["trace[1435224009] 'process raft request' (duration: 111.174746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:12:56.813436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.737884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:12:56.813499Z","caller":"traceutil/trace.go:171","msg":"trace[442494651] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:27212; }","duration":"105.858707ms","start":"2026-09-23T04:12:56.707626Z","end":"2026-09-23T04:12:56.813485Z","steps":["trace[442494651] 'agreement among raft nodes before linearized reading' (duration: 105.735054ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:12:56.95245Z","caller":"traceutil/trace.go:171","msg":"trace[1669108375] transaction","detail":"{read_only:false; response_revision:27214; number_of_response:1; }","duration":"112.636632ms","start":"2026-09-23T04:12:56.839788Z","end":"2026-09-23T04:12:56.952425Z","steps":["trace[1669108375] 'process raft request' (duration: 112.441157ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:07.175184Z","caller":"traceutil/trace.go:171","msg":"trace[1698917791] transaction","detail":"{read_only:false; response_revision:27293; number_of_response:1; }","duration":"278.438293ms","start":"2026-09-23T04:13:06.896705Z","end":"2026-09-23T04:13:07.175144Z","steps":["trace[1698917791] 'process raft request' (duration: 225.93535ms)","trace[1698917791] 'compare' (duration: 52.059302ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:07.180258Z","caller":"traceutil/trace.go:171","msg":"trace[1906863327] linearizableReadLoop","detail":"{readStateIndex:28192; appliedIndex:28190; }","duration":"105.15532ms","start":"2026-09-23T04:13:07.075066Z","end":"2026-09-23T04:13:07.180221Z","steps":["trace[1906863327] 'read index received' (duration: 47.709247ms)","trace[1906863327] 'applied index is now lower than readState.Index' (duration: 57.444603ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:07.180447Z","caller":"traceutil/trace.go:171","msg":"trace[165801238] transaction","detail":"{read_only:false; response_revision:27294; number_of_response:1; }","duration":"184.761051ms","start":"2026-09-23T04:13:06.995668Z","end":"2026-09-23T04:13:07.180429Z","steps":["trace[165801238] 'process raft request' (duration: 184.348481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:07.180787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.688743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:07.180865Z","caller":"traceutil/trace.go:171","msg":"trace[563349618] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:27294; }","duration":"105.814116ms","start":"2026-09-23T04:13:07.075035Z","end":"2026-09-23T04:13:07.180849Z","steps":["trace[563349618] 'agreement among raft nodes before linearized reading' (duration: 105.650672ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:07.468984Z","caller":"traceutil/trace.go:171","msg":"trace[1317773297] transaction","detail":"{read_only:false; response_revision:27295; number_of_response:1; }","duration":"284.845999ms","start":"2026-09-23T04:13:07.183783Z","end":"2026-09-23T04:13:07.468629Z","steps":["trace[1317773297] 'process raft request' (duration: 220.942199ms)","trace[1317773297] 'compare' (duration: 63.715055ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:07.615126Z","caller":"traceutil/trace.go:171","msg":"trace[210034923] linearizableReadLoop","detail":"{readStateIndex:28195; appliedIndex:28192; }","duration":"298.590432ms","start":"2026-09-23T04:13:07.316511Z","end":"2026-09-23T04:13:07.615101Z","steps":["trace[210034923] 'read index received' (duration: 88.304722ms)","trace[210034923] 'applied index is now lower than readState.Index' (duration: 210.28444ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:07.615691Z","caller":"traceutil/trace.go:171","msg":"trace[454064123] transaction","detail":"{read_only:false; response_revision:27296; number_of_response:1; }","duration":"430.989253ms","start":"2026-09-23T04:13:07.184687Z","end":"2026-09-23T04:13:07.615676Z","steps":["trace[454064123] 'process raft request' (duration: 430.148133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:07.615812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:07.18466Z","time spent":"431.068314ms","remote":"127.0.0.1:60508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:07.616052Z","caller":"traceutil/trace.go:171","msg":"trace[1776767337] transaction","detail":"{read_only:false; response_revision:27297; number_of_response:1; }","duration":"419.031712ms","start":"2026-09-23T04:13:07.196986Z","end":"2026-09-23T04:13:07.616018Z","steps":["trace[1776767337] 'process raft request' (duration: 418.006708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:07.616129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.589296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-09-23T04:13:07.6162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:07.196962Z","time spent":"419.160286ms","remote":"127.0.0.1:60888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:07.616025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.311124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:07.616226Z","caller":"traceutil/trace.go:171","msg":"trace[623440874] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27297; }","duration":"299.72413ms","start":"2026-09-23T04:13:07.316471Z","end":"2026-09-23T04:13:07.616195Z","steps":["trace[623440874] 'agreement among raft nodes before linearized reading' (duration: 299.452683ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:07.616261Z","caller":"traceutil/trace.go:171","msg":"trace[1925324228] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:27297; }","duration":"192.57403ms","start":"2026-09-23T04:13:07.423669Z","end":"2026-09-23T04:13:07.616243Z","steps":["trace[1925324228] 'agreement among raft nodes before linearized reading' (duration: 192.319234ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.000082Z","caller":"traceutil/trace.go:171","msg":"trace[1350829890] transaction","detail":"{read_only:false; response_revision:27300; number_of_response:1; }","duration":"314.769865ms","start":"2026-09-23T04:13:07.685289Z","end":"2026-09-23T04:13:08.000059Z","steps":["trace[1350829890] 'process raft request' (duration: 314.622201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:08.00024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:07.685271Z","time spent":"314.885397ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:08.217892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.311218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:08.218006Z","caller":"traceutil/trace.go:171","msg":"trace[1567880048] linearizableReadLoop","detail":"{readStateIndex:28200; appliedIndex:28198; }","duration":"392.339065ms","start":"2026-09-23T04:13:07.82565Z","end":"2026-09-23T04:13:08.217989Z","steps":["trace[1567880048] 'read index received' (duration: 174.259016ms)","trace[1567880048] 'applied index is now lower than readState.Index' (duration: 218.078979ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:08.218098Z","caller":"traceutil/trace.go:171","msg":"trace[1956730160] transaction","detail":"{read_only:false; response_revision:27301; number_of_response:1; }","duration":"524.119101ms","start":"2026-09-23T04:13:07.693967Z","end":"2026-09-23T04:13:08.218086Z","steps":["trace[1956730160] 'process raft request' (duration: 377.520066ms)","trace[1956730160] 'compare' (duration: 146.012111ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:08.218158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:07.693943Z","time spent":"524.184933ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:08.218483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.805477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-23T04:13:08.218584Z","caller":"traceutil/trace.go:171","msg":"trace[1398166195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27301; }","duration":"237.947941ms","start":"2026-09-23T04:13:07.980611Z","end":"2026-09-23T04:13:08.218558Z","steps":["trace[1398166195] 'agreement among raft nodes before linearized reading' (duration: 237.643003ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:08.218616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.385869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419199"} {"level":"info","ts":"2026-09-23T04:13:08.218656Z","caller":"traceutil/trace.go:171","msg":"trace[592158374] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:27301; }","duration":"195.470531ms","start":"2026-09-23T04:13:08.023172Z","end":"2026-09-23T04:13:08.218643Z","steps":["trace[592158374] 'agreement among raft nodes before linearized reading' (duration: 195.209884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:08.218485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.815037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:13:08.218732Z","caller":"traceutil/trace.go:171","msg":"trace[463297931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27301; }","duration":"393.120634ms","start":"2026-09-23T04:13:07.825589Z","end":"2026-09-23T04:13:08.21871Z","steps":["trace[463297931] 'agreement among raft nodes before linearized reading' (duration: 392.643063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:08.218879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:07.825564Z","time spent":"393.262577ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-23T04:13:08.504963Z","caller":"traceutil/trace.go:171","msg":"trace[888476016] transaction","detail":"{read_only:false; response_revision:27303; number_of_response:1; }","duration":"281.754113ms","start":"2026-09-23T04:13:08.223183Z","end":"2026-09-23T04:13:08.504937Z","steps":["trace[888476016] 'process raft request' (duration: 281.564409ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.505263Z","caller":"traceutil/trace.go:171","msg":"trace[1589241151] transaction","detail":"{read_only:false; response_revision:27304; number_of_response:1; }","duration":"249.45261ms","start":"2026-09-23T04:13:08.255795Z","end":"2026-09-23T04:13:08.505248Z","steps":["trace[1589241151] 'process raft request' (duration: 249.101971ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.505803Z","caller":"traceutil/trace.go:171","msg":"trace[1189154763] transaction","detail":"{read_only:false; response_revision:27305; number_of_response:1; }","duration":"242.893761ms","start":"2026-09-23T04:13:08.262894Z","end":"2026-09-23T04:13:08.505788Z","steps":["trace[1189154763] 'process raft request' (duration: 242.261356ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.50615Z","caller":"traceutil/trace.go:171","msg":"trace[821638386] transaction","detail":"{read_only:false; response_revision:27307; number_of_response:1; }","duration":"241.154128ms","start":"2026-09-23T04:13:08.264984Z","end":"2026-09-23T04:13:08.506138Z","steps":["trace[821638386] 'process raft request' (duration: 241.046856ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.506272Z","caller":"traceutil/trace.go:171","msg":"trace[64024102] transaction","detail":"{read_only:false; response_revision:27306; number_of_response:1; }","duration":"242.613154ms","start":"2026-09-23T04:13:08.263646Z","end":"2026-09-23T04:13:08.506259Z","steps":["trace[64024102] 'process raft request' (duration: 242.078651ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.54113Z","caller":"traceutil/trace.go:171","msg":"trace[513083899] linearizableReadLoop","detail":"{readStateIndex:28207; appliedIndex:28201; }","duration":"256.668905ms","start":"2026-09-23T04:13:08.284435Z","end":"2026-09-23T04:13:08.541104Z","steps":["trace[513083899] 'read index received' (duration: 220.338053ms)","trace[513083899] 'applied index is now lower than readState.Index' (duration: 36.329431ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:08.541767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.298656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:08.541793Z","caller":"traceutil/trace.go:171","msg":"trace[757324398] transaction","detail":"{read_only:false; response_revision:27308; number_of_response:1; }","duration":"258.002057ms","start":"2026-09-23T04:13:08.283618Z","end":"2026-09-23T04:13:08.54162Z","steps":["trace[757324398] 'process raft request' (duration: 257.242299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:08.542147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.695777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-23T04:13:08.542177Z","caller":"traceutil/trace.go:171","msg":"trace[1044609278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27308; }","duration":"235.729497ms","start":"2026-09-23T04:13:08.306439Z","end":"2026-09-23T04:13:08.542169Z","steps":["trace[1044609278] 'agreement among raft nodes before linearized reading' (duration: 235.636435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:08.542099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.654359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-23T04:13:08.542302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.63281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-23T04:13:08.542352Z","caller":"traceutil/trace.go:171","msg":"trace[730689890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27308; }","duration":"255.688711ms","start":"2026-09-23T04:13:08.286631Z","end":"2026-09-23T04:13:08.54232Z","steps":["trace[730689890] 'agreement among raft nodes before linearized reading' (duration: 255.608209ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.542293Z","caller":"traceutil/trace.go:171","msg":"trace[268412803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27308; }","duration":"257.879104ms","start":"2026-09-23T04:13:08.284395Z","end":"2026-09-23T04:13:08.542274Z","steps":["trace[268412803] 'agreement among raft nodes before linearized reading' (duration: 257.108925ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:08.541842Z","caller":"traceutil/trace.go:171","msg":"trace[2050315473] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:27308; }","duration":"256.412799ms","start":"2026-09-23T04:13:08.285414Z","end":"2026-09-23T04:13:08.541827Z","steps":["trace[2050315473] 'agreement among raft nodes before linearized reading' (duration: 256.281836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:08.747865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.418872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:08.747974Z","caller":"traceutil/trace.go:171","msg":"trace[1119530318] transaction","detail":"{read_only:false; response_revision:27309; number_of_response:1; }","duration":"197.92445ms","start":"2026-09-23T04:13:08.55003Z","end":"2026-09-23T04:13:08.747954Z","steps":["trace[1119530318] 'process raft request' (duration: 18.325944ms)","trace[1119530318] 'compare' (duration: 179.273068ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:09.086515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.09621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-23T04:13:09.086753Z","caller":"traceutil/trace.go:171","msg":"trace[791700328] transaction","detail":"{read_only:false; response_revision:27310; number_of_response:1; }","duration":"536.246935ms","start":"2026-09-23T04:13:08.55048Z","end":"2026-09-23T04:13:09.086726Z","steps":["trace[791700328] 'process raft request' (duration: 350.802668ms)","trace[791700328] 'compare' (duration: 184.882083ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:09.086897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:08.550451Z","time spent":"536.400839ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:09.086964Z","caller":"traceutil/trace.go:171","msg":"trace[458995617] linearizableReadLoop","detail":"{readStateIndex:28210; appliedIndex:28207; }","duration":"521.18181ms","start":"2026-09-23T04:13:08.565751Z","end":"2026-09-23T04:13:09.086933Z","steps":["trace[458995617] 'read index received' (duration: 2.618664ms)","trace[458995617] 'applied index is now lower than readState.Index' (duration: 518.561916ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:09.087128Z","caller":"traceutil/trace.go:171","msg":"trace[2124725068] transaction","detail":"{read_only:false; response_revision:27311; number_of_response:1; }","duration":"531.239475ms","start":"2026-09-23T04:13:08.555876Z","end":"2026-09-23T04:13:09.087116Z","steps":["trace[2124725068] 'process raft request' (duration: 530.774543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:09.087236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:08.555853Z","time spent":"531.305766ms","remote":"127.0.0.1:60630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-23T04:13:09.087541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.673292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:09.087666Z","caller":"traceutil/trace.go:171","msg":"trace[199577101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27311; }","duration":"521.970019ms","start":"2026-09-23T04:13:08.56567Z","end":"2026-09-23T04:13:09.08764Z","steps":["trace[199577101] 'agreement among raft nodes before linearized reading' (duration: 521.676492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:09.087724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:08.565649Z","time spent":"522.062601ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-23T04:13:09.114098Z","caller":"traceutil/trace.go:171","msg":"trace[292436269] transaction","detail":"{read_only:false; response_revision:27312; number_of_response:1; }","duration":"392.826227ms","start":"2026-09-23T04:13:08.721226Z","end":"2026-09-23T04:13:09.114052Z","steps":["trace[292436269] 'process raft request' (duration: 392.276284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:09.114141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.917809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-23T04:13:09.114291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:08.721181Z","time spent":"393.023592ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419257,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-23T04:13:09.114307Z","caller":"traceutil/trace.go:171","msg":"trace[1146981182] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:27312; }","duration":"146.147935ms","start":"2026-09-23T04:13:08.968141Z","end":"2026-09-23T04:13:09.114289Z","steps":["trace[1146981182] 'agreement among raft nodes before linearized reading' (duration: 145.910589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:09.114391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.142197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-23T04:13:09.114478Z","caller":"traceutil/trace.go:171","msg":"trace[72969086] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:27312; }","duration":"337.2809ms","start":"2026-09-23T04:13:08.777178Z","end":"2026-09-23T04:13:09.114459Z","steps":["trace[72969086] 'agreement among raft nodes before linearized reading' (duration: 337.133916ms)"],"step_count":1} {"level":"warn","ts":"2026-09-23T04:13:09.114524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-23T04:13:08.777159Z","time spent":"337.352852ms","remote":"127.0.0.1:37508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-09-23T04:13:09.305823Z","caller":"traceutil/trace.go:171","msg":"trace[1982409147] transaction","detail":"{read_only:false; response_revision:27313; number_of_response:1; }","duration":"117.707875ms","start":"2026-09-23T04:13:09.188083Z","end":"2026-09-23T04:13:09.305791Z","steps":["trace[1982409147] 'process raft request' (duration: 50.041534ms)","trace[1982409147] 'compare' (duration: 67.447436ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:44.014466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24799} {"level":"info","ts":"2026-09-23T04:13:44.165396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24799,"took":"146.748429ms","hash":1754475113,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":37236736,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-23T04:13:44.165482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1754475113,"revision":24799,"compact-revision":21735} {"level":"info","ts":"2026-09-23T04:13:52.863527Z","caller":"traceutil/trace.go:171","msg":"trace[1851851089] transaction","detail":"{read_only:false; response_revision:27806; number_of_response:1; }","duration":"168.035935ms","start":"2026-09-23T04:13:52.695463Z","end":"2026-09-23T04:13:52.863499Z","steps":["trace[1851851089] 'process raft request' (duration: 167.847261ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.901206Z","caller":"traceutil/trace.go:171","msg":"trace[339821654] transaction","detail":"{read_only:false; response_revision:27808; number_of_response:1; }","duration":"196.384873ms","start":"2026-09-23T04:13:52.704797Z","end":"2026-09-23T04:13:52.901182Z","steps":["trace[339821654] 'process raft request' (duration: 196.309261ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.901214Z","caller":"traceutil/trace.go:171","msg":"trace[2054101227] transaction","detail":"{read_only:false; response_revision:27807; number_of_response:1; }","duration":"200.583395ms","start":"2026-09-23T04:13:52.700595Z","end":"2026-09-23T04:13:52.901178Z","steps":["trace[2054101227] 'process raft request' (duration: 183.238814ms)","trace[2054101227] 'compare' (duration: 17.154706ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:13:52.903091Z","caller":"traceutil/trace.go:171","msg":"trace[1853682984] transaction","detail":"{read_only:false; response_revision:27809; number_of_response:1; }","duration":"159.838506ms","start":"2026-09-23T04:13:52.7432Z","end":"2026-09-23T04:13:52.903038Z","steps":["trace[1853682984] 'process raft request' (duration: 157.94826ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.903524Z","caller":"traceutil/trace.go:171","msg":"trace[600613968] transaction","detail":"{read_only:false; response_revision:27810; number_of_response:1; }","duration":"146.019762ms","start":"2026-09-23T04:13:52.757445Z","end":"2026-09-23T04:13:52.903464Z","steps":["trace[600613968] 'process raft request' (duration: 145.12102ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:13:52.917126Z","caller":"traceutil/trace.go:171","msg":"trace[956558204] linearizableReadLoop","detail":"{readStateIndex:28719; appliedIndex:28714; }","duration":"156.083805ms","start":"2026-09-23T04:13:52.760979Z","end":"2026-09-23T04:13:52.917063Z","steps":["trace[956558204] 'read index received' (duration: 102.379333ms)","trace[956558204] 'applied index is now lower than readState.Index' (duration: 53.702872ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:13:52.917512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.527846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-23T04:13:52.917559Z","caller":"traceutil/trace.go:171","msg":"trace[2121275839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27810; }","duration":"156.647559ms","start":"2026-09-23T04:13:52.760898Z","end":"2026-09-23T04:13:52.917546Z","steps":["trace[2121275839] 'agreement among raft nodes before linearized reading' (duration: 156.308671ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:07.496874Z","caller":"traceutil/trace.go:171","msg":"trace[1021648387] transaction","detail":"{read_only:false; response_revision:27915; number_of_response:1; }","duration":"101.43317ms","start":"2026-09-23T04:14:07.395407Z","end":"2026-09-23T04:14:07.496841Z","steps":["trace[1021648387] 'process raft request' (duration: 101.226025ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:14:08.665544Z","caller":"traceutil/trace.go:171","msg":"trace[1177260360] transaction","detail":"{read_only:false; response_revision:27922; number_of_response:1; }","duration":"121.302142ms","start":"2026-09-23T04:14:08.544224Z","end":"2026-09-23T04:14:08.665526Z","steps":["trace[1177260360] 'process raft request' (duration: 95.914166ms)","trace[1177260360] 'compare' (duration: 25.307724ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:16:14.364282Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1b80133015df7bbe","local-member-applied-index":30013,"local-member-snapshot-index":20012,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T04:16:14.372621Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30013} {"level":"info","ts":"2026-09-23T04:16:14.372779Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25013} {"level":"info","ts":"2026-09-23T04:16:48.481099Z","caller":"traceutil/trace.go:171","msg":"trace[2140425928] transaction","detail":"{read_only:false; response_revision:29461; number_of_response:1; }","duration":"106.303378ms","start":"2026-09-23T04:16:48.37475Z","end":"2026-09-23T04:16:48.481054Z","steps":["trace[2140425928] 'process raft request' (duration: 106.007051ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:18:44.022051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27738} {"level":"info","ts":"2026-09-23T04:18:44.169323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27738,"took":"141.564342ms","hash":888060950,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":35569664,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-23T04:18:44.169384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":888060950,"revision":27738,"compact-revision":24799} {"level":"info","ts":"2026-09-23T04:23:44.031089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30434} {"level":"info","ts":"2026-09-23T04:23:44.207764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30434,"took":"172.327717ms","hash":2946044840,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":32194560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T04:23:44.207854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2946044840,"revision":30434,"compact-revision":27738} {"level":"info","ts":"2026-09-23T04:28:26.284444Z","caller":"traceutil/trace.go:171","msg":"trace[1586090391] transaction","detail":"{read_only:false; response_revision:34781; number_of_response:1; }","duration":"154.042505ms","start":"2026-09-23T04:28:26.130365Z","end":"2026-09-23T04:28:26.284408Z","steps":["trace[1586090391] 'process raft request' (duration: 69.838109ms)","trace[1586090391] 'compare' (duration: 83.904309ms)"],"step_count":2} {"level":"info","ts":"2026-09-23T04:28:26.284418Z","caller":"traceutil/trace.go:171","msg":"trace[453589550] linearizableReadLoop","detail":"{readStateIndex:35885; appliedIndex:35884; }","duration":"113.207394ms","start":"2026-09-23T04:28:26.171175Z","end":"2026-09-23T04:28:26.284382Z","steps":["trace[453589550] 'read index received' (duration: 28.976897ms)","trace[453589550] 'applied index is now lower than readState.Index' (duration: 84.228617ms)"],"step_count":2} {"level":"warn","ts":"2026-09-23T04:28:26.285026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.809209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-23T04:28:26.285124Z","caller":"traceutil/trace.go:171","msg":"trace[1243020028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:34781; }","duration":"113.989963ms","start":"2026-09-23T04:28:26.171113Z","end":"2026-09-23T04:28:26.285103Z","steps":["trace[1243020028] 'agreement among raft nodes before linearized reading' (duration: 113.376178ms)"],"step_count":1} {"level":"info","ts":"2026-09-23T04:28:44.041651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32692} {"level":"info","ts":"2026-09-23T04:28:44.201252Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32692,"took":"155.152813ms","hash":998560779,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T04:28:44.201344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":998560779,"revision":32692,"compact-revision":30434} {"level":"info","ts":"2026-09-23T04:33:44.048856Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34915} {"level":"info","ts":"2026-09-23T04:33:44.18256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34915,"took":"129.373143ms","hash":4047760120,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31854592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T04:33:44.182611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4047760120,"revision":34915,"compact-revision":32692} {"level":"info","ts":"2026-09-23T04:37:09.068609Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1b80133015df7bbe","local-member-applied-index":40014,"local-member-snapshot-index":30013,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T04:37:09.074702Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40014} {"level":"info","ts":"2026-09-23T04:37:09.074793Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35014} {"level":"info","ts":"2026-09-23T04:38:44.056729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37152} {"level":"info","ts":"2026-09-23T04:38:44.18012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37152,"took":"120.020188ms","hash":3839125698,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":32337920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-23T04:38:44.180215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3839125698,"revision":37152,"compact-revision":34915} {"level":"info","ts":"2026-09-23T04:43:44.063674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39490} {"level":"info","ts":"2026-09-23T04:43:44.217899Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39490,"took":"150.089631ms","hash":3752649775,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":31346688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-23T04:43:44.217956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3752649775,"revision":39490,"compact-revision":37152} {"level":"info","ts":"2026-09-23T04:48:44.072837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41723} {"level":"info","ts":"2026-09-23T04:48:44.206728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41723,"took":"129.765324ms","hash":329753185,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:48:44.206803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":329753185,"revision":41723,"compact-revision":39490} {"level":"info","ts":"2026-09-23T04:53:44.081648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43948} {"level":"info","ts":"2026-09-23T04:53:44.213706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43948,"took":"127.592261ms","hash":2338738668,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":29831168,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:53:44.213786Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2338738668,"revision":43948,"compact-revision":41723} {"level":"info","ts":"2026-09-23T04:58:44.088785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":46222} {"level":"info","ts":"2026-09-23T04:58:44.22119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":46222,"took":"128.772902ms","hash":1310652099,"current-db-size-bytes":59088896,"current-db-size":"59 MB","current-db-size-in-use-bytes":29655040,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-23T04:58:44.221249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1310652099,"revision":46222,"compact-revision":43948} {"level":"info","ts":"2026-09-23T04:58:53.103782Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1b80133015df7bbe","local-member-applied-index":50015,"local-member-snapshot-index":40014,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-23T04:58:53.111998Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50015} {"level":"info","ts":"2026-09-23T04:58:53.112175Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45015}