{"level":"warn","ts":"2026-05-30T09:46:17.705094Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:46:17.705191Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-05-30T09:46:17.705255Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T09:46:17.70527Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-30T09:46:17.70529Z","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-05-30T09:46:17.705958Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-30T09:46:17.706054Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-05-30T09:46:17.710562Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.288694ms"} {"level":"info","ts":"2026-05-30T09:46:17.718048Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-30T09:46:17.718146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T09:46:17.718179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-30T09:46:17.718195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T09:46:17.718203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-30T09:46:17.718249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-30T09:46:17.730779Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T09:46:17.732714Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T09:46:17.734321Z","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-05-30T09:46:17.737014Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T09:46:17.737263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T09:46:17.737321Z","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-05-30T09:46:17.737471Z","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-05-30T09:46:17.737526Z","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-05-30T09:46:17.739403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-30T09:46:17.73993Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-30T09:46:17.742797Z","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-05-30T09:46:17.743006Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-30T09:46:17.743057Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-30T09:46:17.743196Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T09:46:17.743273Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T09:46:18.219296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T09:46:18.219413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T09:46:18.219451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-30T09:46:18.219475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-30T09:46:18.219493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-30T09:46:18.219511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-30T09:46:18.219532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-30T09:46:18.221606Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:18.222494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:46:18.222496Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T09:46:18.222726Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:18.222865Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:18.222849Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T09:46:18.222941Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T09:46:18.223104Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T09:46:18.22313Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T09:46:18.226167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T09:46:18.226905Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-05-30T09:47:02.971158Z","caller":"traceutil/trace.go:171","msg":"trace[23345396] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"603.273331ms","start":"2026-05-30T09:47:02.367859Z","end":"2026-05-30T09:47:02.971133Z","steps":["trace[23345396] 'process raft request' (duration: 601.420619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:02.971806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:02.36783Z","time spent":"603.425896ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:47:03.16606Z","caller":"traceutil/trace.go:171","msg":"trace[847396337] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"162.453182ms","start":"2026-05-30T09:47:03.003569Z","end":"2026-05-30T09:47:03.166022Z","steps":["trace[847396337] 'process raft request' (duration: 151.125206ms)","trace[847396337] 'compare' (duration: 11.169561ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:47:03.201893Z","caller":"traceutil/trace.go:171","msg":"trace[1768817037] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"192.289798ms","start":"2026-05-30T09:47:03.009572Z","end":"2026-05-30T09:47:03.201862Z","steps":["trace[1768817037] 'process raft request' (duration: 191.945749ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:03.202096Z","caller":"traceutil/trace.go:171","msg":"trace[1159705068] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"155.085062ms","start":"2026-05-30T09:47:03.047002Z","end":"2026-05-30T09:47:03.202087Z","steps":["trace[1159705068] 'process raft request' (duration: 154.729372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:03.718559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.218213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T09:47:03.718717Z","caller":"traceutil/trace.go:171","msg":"trace[799446650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"325.432519ms","start":"2026-05-30T09:47:03.393258Z","end":"2026-05-30T09:47:03.71869Z","steps":["trace[799446650] 'range keys from in-memory index tree' (duration: 325.015997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:47:03.718772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:47:03.393235Z","time spent":"325.521561ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T09:47:03.72114Z","caller":"traceutil/trace.go:171","msg":"trace[876455114] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"115.170685ms","start":"2026-05-30T09:47:03.605938Z","end":"2026-05-30T09:47:03.721109Z","steps":["trace[876455114] 'process raft request' (duration: 114.511676ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:17.903861Z","caller":"traceutil/trace.go:171","msg":"trace[901880950] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"115.137604ms","start":"2026-05-30T09:47:17.7887Z","end":"2026-05-30T09:47:17.903838Z","steps":["trace[901880950] 'process raft request' (duration: 114.967379ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:47:46.148938Z","caller":"traceutil/trace.go:171","msg":"trace[1917487522] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"101.187753ms","start":"2026-05-30T09:47:46.047727Z","end":"2026-05-30T09:47:46.148915Z","steps":["trace[1917487522] 'process raft request' (duration: 101.053449ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:11.32304Z","caller":"traceutil/trace.go:171","msg":"trace[2027689781] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"136.325222ms","start":"2026-05-30T09:48:11.186686Z","end":"2026-05-30T09:48:11.323011Z","steps":["trace[2027689781] 'process raft request' (duration: 106.451055ms)","trace[2027689781] 'compare' (duration: 29.6508ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:48:31.476304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.210107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T09:48:31.476418Z","caller":"traceutil/trace.go:171","msg":"trace[1016219283] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1815; }","duration":"245.370612ms","start":"2026-05-30T09:48:31.231022Z","end":"2026-05-30T09:48:31.476392Z","steps":["trace[1016219283] 'range keys from in-memory index tree' (duration: 245.041863ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:31.965491Z","caller":"traceutil/trace.go:171","msg":"trace[299876844] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"272.592652ms","start":"2026-05-30T09:48:31.692878Z","end":"2026-05-30T09:48:31.96547Z","steps":["trace[299876844] 'process raft request' (duration: 272.482949ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:31.970687Z","caller":"traceutil/trace.go:171","msg":"trace[1203944824] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"277.676568ms","start":"2026-05-30T09:48:31.692976Z","end":"2026-05-30T09:48:31.970652Z","steps":["trace[1203944824] 'process raft request' (duration: 277.460572ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:31.970812Z","caller":"traceutil/trace.go:171","msg":"trace[1961928049] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"277.632107ms","start":"2026-05-30T09:48:31.693157Z","end":"2026-05-30T09:48:31.970789Z","steps":["trace[1961928049] 'process raft request' (duration: 277.457002ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:31.970848Z","caller":"traceutil/trace.go:171","msg":"trace[1499372039] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"277.836843ms","start":"2026-05-30T09:48:31.692998Z","end":"2026-05-30T09:48:31.970835Z","steps":["trace[1499372039] 'process raft request' (duration: 277.560035ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:31.97085Z","caller":"traceutil/trace.go:171","msg":"trace[1575105590] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"277.548915ms","start":"2026-05-30T09:48:31.693283Z","end":"2026-05-30T09:48:31.970832Z","steps":["trace[1575105590] 'process raft request' (duration: 277.38496ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:31.970996Z","caller":"traceutil/trace.go:171","msg":"trace[1679684429] linearizableReadLoop","detail":"{readStateIndex:1904; appliedIndex:1900; }","duration":"277.768961ms","start":"2026-05-30T09:48:31.69321Z","end":"2026-05-30T09:48:31.970979Z","steps":["trace[1679684429] 'read index received' (duration: 272.232052ms)","trace[1679684429] 'applied index is now lower than readState.Index' (duration: 5.535229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:48:31.971196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.966846ms","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-05-30T09:48:31.971257Z","caller":"traceutil/trace.go:171","msg":"trace[1223432090] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1826; }","duration":"278.059929ms","start":"2026-05-30T09:48:31.693179Z","end":"2026-05-30T09:48:31.971239Z","steps":["trace[1223432090] 'agreement among raft nodes before linearized reading' (duration: 277.866784ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:48:34.593994Z","caller":"traceutil/trace.go:171","msg":"trace[382179690] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"102.345586ms","start":"2026-05-30T09:48:34.491625Z","end":"2026-05-30T09:48:34.593971Z","steps":["trace[382179690] 'process raft request' (duration: 101.969696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:48:34.63926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.291046ms","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-05-30T09:48:34.63935Z","caller":"traceutil/trace.go:171","msg":"trace[1449048320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1844; }","duration":"140.476241ms","start":"2026-05-30T09:48:34.498854Z","end":"2026-05-30T09:48:34.63933Z","steps":["trace[1449048320] 'agreement among raft nodes before linearized reading' (duration: 140.243974ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:10.242606Z","caller":"traceutil/trace.go:171","msg":"trace[177122099] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"105.476086ms","start":"2026-05-30T09:49:10.137107Z","end":"2026-05-30T09:49:10.242583Z","steps":["trace[177122099] 'process raft request' (duration: 105.336832ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:22.088561Z","caller":"traceutil/trace.go:171","msg":"trace[369929590] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2233; }","duration":"123.667867ms","start":"2026-05-30T09:49:21.964829Z","end":"2026-05-30T09:49:22.088497Z","steps":["trace[369929590] 'read index received' (duration: 123.462161ms)","trace[369929590] 'applied index is now lower than readState.Index' (duration: 204.716µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:22.088652Z","caller":"traceutil/trace.go:171","msg":"trace[280306071] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"165.413055ms","start":"2026-05-30T09:49:21.923204Z","end":"2026-05-30T09:49:22.088617Z","steps":["trace[280306071] 'process raft request' (duration: 165.095556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:22.088735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.620263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:22.088778Z","caller":"traceutil/trace.go:171","msg":"trace[15993641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2143; }","duration":"102.721436ms","start":"2026-05-30T09:49:21.986046Z","end":"2026-05-30T09:49:22.088768Z","steps":["trace[15993641] 'agreement among raft nodes before linearized reading' (duration: 102.637103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:22.088858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.002758ms","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-05-30T09:49:22.088932Z","caller":"traceutil/trace.go:171","msg":"trace[1921595793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"124.131492ms","start":"2026-05-30T09:49:21.964785Z","end":"2026-05-30T09:49:22.088917Z","steps":["trace[1921595793] 'agreement among raft nodes before linearized reading' (duration: 123.891174ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:22.279862Z","caller":"traceutil/trace.go:171","msg":"trace[950368332] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"186.419628ms","start":"2026-05-30T09:49:22.093408Z","end":"2026-05-30T09:49:22.279828Z","steps":["trace[950368332] 'process raft request' (duration: 186.205472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:22.609892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.641929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T09:49:22.61004Z","caller":"traceutil/trace.go:171","msg":"trace[2060680712] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2235; }","duration":"223.178102ms","start":"2026-05-30T09:49:22.38684Z","end":"2026-05-30T09:49:22.610018Z","steps":["trace[2060680712] 'read index received' (duration: 86.051049ms)","trace[2060680712] 'applied index is now lower than readState.Index' (duration: 137.125433ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:22.610125Z","caller":"traceutil/trace.go:171","msg":"trace[1106313209] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"287.517648ms","start":"2026-05-30T09:49:22.322568Z","end":"2026-05-30T09:49:22.610086Z","steps":["trace[1106313209] 'process raft request' (duration: 150.385524ms)","trace[1106313209] 'compare' (duration: 136.506045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:22.610238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.757772ms","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":"warn","ts":"2026-05-30T09:49:22.610237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.383578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-30T09:49:22.610297Z","caller":"traceutil/trace.go:171","msg":"trace[1473535630] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2145; }","duration":"221.833295ms","start":"2026-05-30T09:49:22.388446Z","end":"2026-05-30T09:49:22.61028Z","steps":["trace[1473535630] 'agreement among raft nodes before linearized reading' (duration: 221.699481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:22.610232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.681387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:49:22.610346Z","caller":"traceutil/trace.go:171","msg":"trace[1400732768] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2145; }","duration":"215.8112ms","start":"2026-05-30T09:49:22.394524Z","end":"2026-05-30T09:49:22.610335Z","steps":["trace[1400732768] 'agreement among raft nodes before linearized reading' (duration: 215.647016ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:22.610307Z","caller":"traceutil/trace.go:171","msg":"trace[300373147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2145; }","duration":"223.488391ms","start":"2026-05-30T09:49:22.386803Z","end":"2026-05-30T09:49:22.610292Z","steps":["trace[300373147] 'agreement among raft nodes before linearized reading' (duration: 223.285425ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:49:22.743246Z","caller":"traceutil/trace.go:171","msg":"trace[1898400829] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"128.833286ms","start":"2026-05-30T09:49:22.614358Z","end":"2026-05-30T09:49:22.743191Z","steps":["trace[1898400829] 'process raft request' (duration: 98.101484ms)","trace[1898400829] 'compare' (duration: 30.563197ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:49:43.021157Z","caller":"traceutil/trace.go:171","msg":"trace[242832024] transaction","detail":"{read_only:false; response_revision:2270; number_of_response:1; }","duration":"108.007259ms","start":"2026-05-30T09:49:42.913122Z","end":"2026-05-30T09:49:43.02113Z","steps":["trace[242832024] 'process raft request' (duration: 74.158298ms)","trace[242832024] 'compare' (duration: 33.681766ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:49:43.289478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.452056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T09:49:43.289601Z","caller":"traceutil/trace.go:171","msg":"trace[1090785141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2271; }","duration":"127.645021ms","start":"2026-05-30T09:49:43.161935Z","end":"2026-05-30T09:49:43.28958Z","steps":["trace[1090785141] 'range keys from in-memory index tree' (duration: 127.227049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:49:43.28973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.744832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T09:49:43.289825Z","caller":"traceutil/trace.go:171","msg":"trace[192872028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2271; }","duration":"106.943409ms","start":"2026-05-30T09:49:43.182866Z","end":"2026-05-30T09:49:43.289809Z","steps":["trace[192872028] 'range keys from in-memory index tree' (duration: 106.508146ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:19.204485Z","caller":"traceutil/trace.go:171","msg":"trace[1294977970] transaction","detail":"{read_only:false; response_revision:2633; number_of_response:1; }","duration":"143.950072ms","start":"2026-05-30T09:50:19.060509Z","end":"2026-05-30T09:50:19.204459Z","steps":["trace[1294977970] 'process raft request' (duration: 143.754387ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:19.272411Z","caller":"traceutil/trace.go:171","msg":"trace[1407426745] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"125.811281ms","start":"2026-05-30T09:50:19.146562Z","end":"2026-05-30T09:50:19.272374Z","steps":["trace[1407426745] 'process raft request' (duration: 125.75701ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:19.272674Z","caller":"traceutil/trace.go:171","msg":"trace[396193562] transaction","detail":"{read_only:false; response_revision:2634; number_of_response:1; }","duration":"208.600817ms","start":"2026-05-30T09:50:19.064057Z","end":"2026-05-30T09:50:19.272658Z","steps":["trace[396193562] 'process raft request' (duration: 203.269854ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:47.544499Z","caller":"traceutil/trace.go:171","msg":"trace[1962421129] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"155.768221ms","start":"2026-05-30T09:50:47.388702Z","end":"2026-05-30T09:50:47.54447Z","steps":["trace[1962421129] 'process raft request' (duration: 155.616767ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:50:47.672884Z","caller":"traceutil/trace.go:171","msg":"trace[1119859174] linearizableReadLoop","detail":"{readStateIndex:2917; appliedIndex:2915; }","duration":"255.239606ms","start":"2026-05-30T09:50:47.417623Z","end":"2026-05-30T09:50:47.672863Z","steps":["trace[1119859174] 'read index received' (duration: 126.725297ms)","trace[1119859174] 'applied index is now lower than readState.Index' (duration: 128.513269ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:50:47.673177Z","caller":"traceutil/trace.go:171","msg":"trace[589107686] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"283.892708ms","start":"2026-05-30T09:50:47.389269Z","end":"2026-05-30T09:50:47.673161Z","steps":["trace[589107686] 'process raft request' (duration: 264.337477ms)","trace[589107686] 'compare' (duration: 19.16533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:50:47.673435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.797232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-30T09:50:47.67348Z","caller":"traceutil/trace.go:171","msg":"trace[1714663567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2800; }","duration":"255.884705ms","start":"2026-05-30T09:50:47.417584Z","end":"2026-05-30T09:50:47.673469Z","steps":["trace[1714663567] 'agreement among raft nodes before linearized reading' (duration: 255.758281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.215568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.031486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:50:48.21598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.133263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-30T09:50:48.216118Z","caller":"traceutil/trace.go:171","msg":"trace[652296092] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2803; }","duration":"433.284897ms","start":"2026-05-30T09:50:47.78278Z","end":"2026-05-30T09:50:48.216065Z","steps":["trace[652296092] 'range keys from in-memory index tree' (duration: 432.966749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:50:48.216195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:50:47.782767Z","time spent":"433.39087ms","remote":"127.0.0.1:35474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-05-30T09:50:48.215687Z","caller":"traceutil/trace.go:171","msg":"trace[1722073328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2803; }","duration":"228.232611ms","start":"2026-05-30T09:50:47.987431Z","end":"2026-05-30T09:50:48.215663Z","steps":["trace[1722073328] 'range keys from in-memory index tree' (duration: 227.938603ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:44.109082Z","caller":"traceutil/trace.go:171","msg":"trace[1387629022] linearizableReadLoop","detail":"{readStateIndex:5163; appliedIndex:5162; }","duration":"163.582753ms","start":"2026-05-30T09:54:43.945474Z","end":"2026-05-30T09:54:44.109057Z","steps":["trace[1387629022] 'read index received' (duration: 163.358226ms)","trace[1387629022] 'applied index is now lower than readState.Index' (duration: 223.497µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:54:44.109117Z","caller":"traceutil/trace.go:171","msg":"trace[172426816] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"166.174208ms","start":"2026-05-30T09:54:43.942914Z","end":"2026-05-30T09:54:44.109088Z","steps":["trace[172426816] 'process raft request' (duration: 165.974772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:54:44.109354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.200287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T09:54:44.109415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.669499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-05-30T09:54:44.109442Z","caller":"traceutil/trace.go:171","msg":"trace[773122609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4966; }","duration":"123.357041ms","start":"2026-05-30T09:54:43.986066Z","end":"2026-05-30T09:54:44.109423Z","steps":["trace[773122609] 'agreement among raft nodes before linearized reading' (duration: 123.186826ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:44.109497Z","caller":"traceutil/trace.go:171","msg":"trace[1488899209] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4966; }","duration":"142.805293ms","start":"2026-05-30T09:54:43.966669Z","end":"2026-05-30T09:54:44.109474Z","steps":["trace[1488899209] 'agreement among raft nodes before linearized reading' (duration: 142.565226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:54:44.109609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.197107ms","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-05-30T09:54:44.109708Z","caller":"traceutil/trace.go:171","msg":"trace[16541362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4966; }","duration":"140.357052ms","start":"2026-05-30T09:54:43.969328Z","end":"2026-05-30T09:54:44.109685Z","steps":["trace[16541362] 'agreement among raft nodes before linearized reading' (duration: 139.95893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:54:44.10961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.132489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c432591-9246-4dc7-ab23-f13c10c3faac\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-30T09:54:44.109798Z","caller":"traceutil/trace.go:171","msg":"trace[604472584] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c432591-9246-4dc7-ab23-f13c10c3faac; range_end:; response_count:1; response_revision:4966; }","duration":"164.357905ms","start":"2026-05-30T09:54:43.945424Z","end":"2026-05-30T09:54:44.109782Z","steps":["trace[604472584] 'agreement among raft nodes before linearized reading' (duration: 163.790639ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:44.276438Z","caller":"traceutil/trace.go:171","msg":"trace[151685860] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"162.512651ms","start":"2026-05-30T09:54:44.113895Z","end":"2026-05-30T09:54:44.276408Z","steps":["trace[151685860] 'process raft request' (duration: 121.539719ms)","trace[151685860] 'compare' (duration: 40.843818ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:54:44.310148Z","caller":"traceutil/trace.go:171","msg":"trace[878676430] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"119.050226ms","start":"2026-05-30T09:54:44.191081Z","end":"2026-05-30T09:54:44.310131Z","steps":["trace[878676430] 'process raft request' (duration: 118.909122ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:46.635114Z","caller":"traceutil/trace.go:171","msg":"trace[1541884371] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"109.987315ms","start":"2026-05-30T09:54:46.52509Z","end":"2026-05-30T09:54:46.635077Z","steps":["trace[1541884371] 'process raft request' (duration: 38.982366ms)","trace[1541884371] 'compare' (duration: 70.768312ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:54:52.319904Z","caller":"traceutil/trace.go:171","msg":"trace[345350162] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"106.164304ms","start":"2026-05-30T09:54:52.213713Z","end":"2026-05-30T09:54:52.319877Z","steps":["trace[345350162] 'process raft request' (duration: 105.878896ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:52.621202Z","caller":"traceutil/trace.go:171","msg":"trace[436441586] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"104.313011ms","start":"2026-05-30T09:54:52.516864Z","end":"2026-05-30T09:54:52.621177Z","steps":["trace[436441586] 'process raft request' (duration: 103.884929ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:52.621463Z","caller":"traceutil/trace.go:171","msg":"trace[859998662] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"107.39555ms","start":"2026-05-30T09:54:52.514062Z","end":"2026-05-30T09:54:52.621457Z","steps":["trace[859998662] 'process raft request' (duration: 106.469493ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:52.621544Z","caller":"traceutil/trace.go:171","msg":"trace[65469289] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"104.808175ms","start":"2026-05-30T09:54:52.51673Z","end":"2026-05-30T09:54:52.621538Z","steps":["trace[65469289] 'process raft request' (duration: 103.953941ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:57.722707Z","caller":"traceutil/trace.go:171","msg":"trace[2035147510] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"266.03759ms","start":"2026-05-30T09:54:57.456638Z","end":"2026-05-30T09:54:57.722676Z","steps":["trace[2035147510] 'process raft request' (duration: 265.69794ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:57.825226Z","caller":"traceutil/trace.go:171","msg":"trace[239955178] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"364.04681ms","start":"2026-05-30T09:54:57.461134Z","end":"2026-05-30T09:54:57.825181Z","steps":["trace[239955178] 'process raft request' (duration: 339.396008ms)","trace[239955178] 'compare' (duration: 24.431735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:54:57.825382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T09:54:57.461108Z","time spent":"364.198233ms","remote":"127.0.0.1:35470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T09:54:57.825477Z","caller":"traceutil/trace.go:171","msg":"trace[291746112] linearizableReadLoop","detail":"{readStateIndex:5443; appliedIndex:5441; }","duration":"291.075603ms","start":"2026-05-30T09:54:57.534346Z","end":"2026-05-30T09:54:57.825422Z","steps":["trace[291746112] 'read index received' (duration: 187.877343ms)","trace[291746112] 'applied index is now lower than readState.Index' (duration: 103.195209ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T09:54:57.825591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.250608ms","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-05-30T09:54:57.825621Z","caller":"traceutil/trace.go:171","msg":"trace[1635325923] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5237; }","duration":"291.32593ms","start":"2026-05-30T09:54:57.534286Z","end":"2026-05-30T09:54:57.825612Z","steps":["trace[1635325923] 'agreement among raft nodes before linearized reading' (duration: 291.271698ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:54:57.83632Z","caller":"traceutil/trace.go:171","msg":"trace[776149095] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"111.314013ms","start":"2026-05-30T09:54:57.724946Z","end":"2026-05-30T09:54:57.83626Z","steps":["trace[776149095] 'process raft request' (duration: 111.160579ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:55:03.396687Z","caller":"traceutil/trace.go:171","msg":"trace[723003341] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"158.239878ms","start":"2026-05-30T09:55:03.238421Z","end":"2026-05-30T09:55:03.39666Z","steps":["trace[723003341] 'process raft request' (duration: 149.875717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:55:05.615353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.784243ms","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-05-30T09:55:05.615464Z","caller":"traceutil/trace.go:171","msg":"trace[1580900575] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5396; }","duration":"132.966539ms","start":"2026-05-30T09:55:05.482474Z","end":"2026-05-30T09:55:05.61544Z","steps":["trace[1580900575] 'range keys from in-memory index tree' (duration: 132.349341ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:55:05.795425Z","caller":"traceutil/trace.go:171","msg":"trace[648031846] linearizableReadLoop","detail":"{readStateIndex:5604; appliedIndex:5603; }","duration":"100.08995ms","start":"2026-05-30T09:55:05.695314Z","end":"2026-05-30T09:55:05.795404Z","steps":["trace[648031846] 'read index received' (duration: 99.969956ms)","trace[648031846] 'applied index is now lower than readState.Index' (duration: 119.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T09:55:05.795487Z","caller":"traceutil/trace.go:171","msg":"trace[857406272] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"170.320597ms","start":"2026-05-30T09:55:05.625128Z","end":"2026-05-30T09:55:05.795448Z","steps":["trace[857406272] 'process raft request' (duration: 170.112431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T09:55:05.795553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.213353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T09:55:05.795591Z","caller":"traceutil/trace.go:171","msg":"trace[1932475629] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5397; }","duration":"100.301716ms","start":"2026-05-30T09:55:05.695279Z","end":"2026-05-30T09:55:05.795581Z","steps":["trace[1932475629] 'agreement among raft nodes before linearized reading' (duration: 100.209633ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T09:56:18.260399Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2970} {"level":"info","ts":"2026-05-30T09:56:18.331349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2970,"took":"69.350846ms","hash":148559557,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":34037760,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-30T09:56:18.331401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":148559557,"revision":2970,"compact-revision":-1} {"level":"info","ts":"2026-05-30T10:01:18.266299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6073} {"level":"info","ts":"2026-05-30T10:01:18.354555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6073,"took":"86.364175ms","hash":4281156873,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":23379968,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-30T10:01:18.354627Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4281156873,"revision":6073,"compact-revision":2970} {"level":"info","ts":"2026-05-30T10:01:18.754372Z","caller":"traceutil/trace.go:171","msg":"trace[201505238] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"174.252844ms","start":"2026-05-30T10:01:18.580098Z","end":"2026-05-30T10:01:18.75435Z","steps":["trace[201505238] 'process raft request' (duration: 173.973975ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:01:18.929041Z","caller":"traceutil/trace.go:171","msg":"trace[1836577238] linearizableReadLoop","detail":"{readStateIndex:8572; appliedIndex:8571; }","duration":"174.80928ms","start":"2026-05-30T10:01:18.7542Z","end":"2026-05-30T10:01:18.929009Z","steps":["trace[1836577238] 'read index received' (duration: 132.295077ms)","trace[1836577238] 'applied index is now lower than readState.Index' (duration: 42.512993ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:01:18.929138Z","caller":"traceutil/trace.go:171","msg":"trace[187636271] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"200.872809ms","start":"2026-05-30T10:01:18.728226Z","end":"2026-05-30T10:01:18.929099Z","steps":["trace[187636271] 'process raft request' (duration: 158.286275ms)","trace[187636271] 'compare' (duration: 42.283596ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:01:18.929298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.250452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-30T10:01:18.929347Z","caller":"traceutil/trace.go:171","msg":"trace[407510398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8270; }","duration":"175.334124ms","start":"2026-05-30T10:01:18.753999Z","end":"2026-05-30T10:01:18.929333Z","steps":["trace[407510398] 'agreement among raft nodes before linearized reading' (duration: 175.147539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:18.930646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.337389ms","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-05-30T10:01:18.93074Z","caller":"traceutil/trace.go:171","msg":"trace[1929157713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8272; }","duration":"170.500975ms","start":"2026-05-30T10:01:18.760217Z","end":"2026-05-30T10:01:18.930718Z","steps":["trace[1929157713] 'agreement among raft nodes before linearized reading' (duration: 170.264328ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:01:18.930631Z","caller":"traceutil/trace.go:171","msg":"trace[1443556312] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"171.893315ms","start":"2026-05-30T10:01:18.758685Z","end":"2026-05-30T10:01:18.930579Z","steps":["trace[1443556312] 'process raft request' (duration: 171.608757ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:01:31.327143Z","caller":"traceutil/trace.go:171","msg":"trace[1862207568] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"277.964738ms","start":"2026-05-30T10:01:31.04915Z","end":"2026-05-30T10:01:31.327115Z","steps":["trace[1862207568] 'process raft request' (duration: 258.478038ms)","trace[1862207568] 'compare' (duration: 19.279224ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:01:31.378712Z","caller":"traceutil/trace.go:171","msg":"trace[578936398] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"327.600055ms","start":"2026-05-30T10:01:31.051086Z","end":"2026-05-30T10:01:31.378686Z","steps":["trace[578936398] 'process raft request' (duration: 327.387669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:31.378911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:01:31.051078Z","time spent":"327.710458ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:01:31.379154Z","caller":"traceutil/trace.go:171","msg":"trace[1685841358] linearizableReadLoop","detail":"{readStateIndex:8639; appliedIndex:8636; }","duration":"212.572705ms","start":"2026-05-30T10:01:31.166557Z","end":"2026-05-30T10:01:31.37913Z","steps":["trace[1685841358] 'read index received' (duration: 141.138521ms)","trace[1685841358] 'applied index is now lower than readState.Index' (duration: 71.433214ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:01:31.379238Z","caller":"traceutil/trace.go:171","msg":"trace[1659022784] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"328.075808ms","start":"2026-05-30T10:01:31.051133Z","end":"2026-05-30T10:01:31.379209Z","steps":["trace[1659022784] 'process raft request' (duration: 327.487732ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:01:31.379214Z","caller":"traceutil/trace.go:171","msg":"trace[721273180] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"187.295569ms","start":"2026-05-30T10:01:31.191883Z","end":"2026-05-30T10:01:31.379179Z","steps":["trace[721273180] 'process raft request' (duration: 187.089053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:31.379332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.759211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:01:31.379381Z","caller":"traceutil/trace.go:171","msg":"trace[266757976] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8336; }","duration":"212.865184ms","start":"2026-05-30T10:01:31.166504Z","end":"2026-05-30T10:01:31.379369Z","steps":["trace[266757976] 'agreement among raft nodes before linearized reading' (duration: 212.767451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:31.379389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:01:31.051129Z","time spent":"328.170191ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T10:01:31.387792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.586724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-d99f6d995-tbcg5.18b44f96a929d349\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-05-30T10:01:31.387874Z","caller":"traceutil/trace.go:171","msg":"trace[1218845544] range","detail":"{range_begin:/registry/events/openstack/barbican-api-d99f6d995-tbcg5.18b44f96a929d349; range_end:; response_count:1; response_revision:8337; }","duration":"165.714917ms","start":"2026-05-30T10:01:31.222142Z","end":"2026-05-30T10:01:31.387857Z","steps":["trace[1218845544] 'agreement among raft nodes before linearized reading' (duration: 165.518132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:31.3879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.743052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T10:01:31.387953Z","caller":"traceutil/trace.go:171","msg":"trace[227997945] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:8337; }","duration":"113.860346ms","start":"2026-05-30T10:01:31.274081Z","end":"2026-05-30T10:01:31.387941Z","steps":["trace[227997945] 'agreement among raft nodes before linearized reading' (duration: 113.689021ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:01:59.220384Z","caller":"traceutil/trace.go:171","msg":"trace[186634158] linearizableReadLoop","detail":"{readStateIndex:8851; appliedIndex:8850; }","duration":"236.001895ms","start":"2026-05-30T10:01:58.984363Z","end":"2026-05-30T10:01:59.220365Z","steps":["trace[186634158] 'read index received' (duration: 235.906352ms)","trace[186634158] 'applied index is now lower than readState.Index' (duration: 94.493µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:01:59.220463Z","caller":"traceutil/trace.go:171","msg":"trace[499858803] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"270.497498ms","start":"2026-05-30T10:01:58.949934Z","end":"2026-05-30T10:01:59.220432Z","steps":["trace[499858803] 'process raft request' (duration: 270.308602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:59.22053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.1625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:01:59.220569Z","caller":"traceutil/trace.go:171","msg":"trace[478138906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8538; }","duration":"236.244943ms","start":"2026-05-30T10:01:58.984314Z","end":"2026-05-30T10:01:59.220559Z","steps":["trace[478138906] 'agreement among raft nodes before linearized reading' (duration: 236.16607ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:01:59.328018Z","caller":"traceutil/trace.go:171","msg":"trace[1110926716] linearizableReadLoop","detail":"{readStateIndex:8852; appliedIndex:8851; }","duration":"107.546562ms","start":"2026-05-30T10:01:59.220452Z","end":"2026-05-30T10:01:59.327999Z","steps":["trace[1110926716] 'read index received' (duration: 107.357047ms)","trace[1110926716] 'applied index is now lower than readState.Index' (duration: 188.835µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:01:59.328276Z","caller":"traceutil/trace.go:171","msg":"trace[373619250] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"195.613014ms","start":"2026-05-30T10:01:59.132656Z","end":"2026-05-30T10:01:59.328269Z","steps":["trace[373619250] 'process raft request' (duration: 195.205072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:59.328411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.756941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:01:59.328442Z","caller":"traceutil/trace.go:171","msg":"trace[1576434904] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8539; }","duration":"224.842724ms","start":"2026-05-30T10:01:59.10359Z","end":"2026-05-30T10:01:59.328432Z","steps":["trace[1576434904] 'agreement among raft nodes before linearized reading' (duration: 224.788172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:01:59.32854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.058028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:01:59.328552Z","caller":"traceutil/trace.go:171","msg":"trace[1325546793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8539; }","duration":"107.088969ms","start":"2026-05-30T10:01:59.221459Z","end":"2026-05-30T10:01:59.328548Z","steps":["trace[1325546793] 'agreement among raft nodes before linearized reading' (duration: 107.068408ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:02.254553Z","caller":"traceutil/trace.go:171","msg":"trace[924545704] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"185.078261ms","start":"2026-05-30T10:02:02.069449Z","end":"2026-05-30T10:02:02.254528Z","steps":["trace[924545704] 'process raft request' (duration: 184.875136ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:02.340943Z","caller":"traceutil/trace.go:171","msg":"trace[1271709444] transaction","detail":"{read_only:false; response_revision:8557; number_of_response:1; }","duration":"142.623519ms","start":"2026-05-30T10:02:02.198288Z","end":"2026-05-30T10:02:02.340912Z","steps":["trace[1271709444] 'process raft request' (duration: 142.557767ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:02.341074Z","caller":"traceutil/trace.go:171","msg":"trace[1888401849] transaction","detail":"{read_only:false; response_revision:8556; number_of_response:1; }","duration":"156.023435ms","start":"2026-05-30T10:02:02.184999Z","end":"2026-05-30T10:02:02.341022Z","steps":["trace[1888401849] 'process raft request' (duration: 155.675945ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:12.075965Z","caller":"traceutil/trace.go:171","msg":"trace[1585277411] linearizableReadLoop","detail":"{readStateIndex:8972; appliedIndex:8971; }","duration":"118.045344ms","start":"2026-05-30T10:02:11.957903Z","end":"2026-05-30T10:02:12.075949Z","steps":["trace[1585277411] 'read index received' (duration: 117.869009ms)","trace[1585277411] 'applied index is now lower than readState.Index' (duration: 174.015µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:02:12.076022Z","caller":"traceutil/trace.go:171","msg":"trace[2069356122] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"118.179399ms","start":"2026-05-30T10:02:11.957812Z","end":"2026-05-30T10:02:12.075991Z","steps":["trace[2069356122] 'process raft request' (duration: 118.012473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:02:12.076154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.23486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mv9tl\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-30T10:02:12.07618Z","caller":"traceutil/trace.go:171","msg":"trace[1942221278] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mv9tl; range_end:; response_count:1; response_revision:8653; }","duration":"118.303682ms","start":"2026-05-30T10:02:11.95787Z","end":"2026-05-30T10:02:12.076174Z","steps":["trace[1942221278] 'agreement among raft nodes before linearized reading' (duration: 118.178749ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:12.256052Z","caller":"traceutil/trace.go:171","msg":"trace[78498830] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"170.259535ms","start":"2026-05-30T10:02:12.085755Z","end":"2026-05-30T10:02:12.256014Z","steps":["trace[78498830] 'process raft request' (duration: 136.207206ms)","trace[78498830] 'compare' (duration: 33.879364ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:02:12.905632Z","caller":"traceutil/trace.go:171","msg":"trace[393819401] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"125.358674ms","start":"2026-05-30T10:02:12.780237Z","end":"2026-05-30T10:02:12.905596Z","steps":["trace[393819401] 'process raft request' (duration: 125.192209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:02:13.217279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.983329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T10:02:13.217374Z","caller":"traceutil/trace.go:171","msg":"trace[16025432] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8660; }","duration":"161.127933ms","start":"2026-05-30T10:02:13.056227Z","end":"2026-05-30T10:02:13.217355Z","steps":["trace[16025432] 'count revisions from in-memory index tree' (duration: 160.893046ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:02:37.42167Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002431.wal"} {"level":"info","ts":"2026-05-30T10:03:14.701815Z","caller":"traceutil/trace.go:171","msg":"trace[770360672] transaction","detail":"{read_only:false; response_revision:9321; number_of_response:1; }","duration":"101.57335ms","start":"2026-05-30T10:03:14.600212Z","end":"2026-05-30T10:03:14.701785Z","steps":["trace[770360672] 'process raft request' (duration: 101.429866ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:14.736953Z","caller":"traceutil/trace.go:171","msg":"trace[1936230066] transaction","detail":"{read_only:false; response_revision:9322; number_of_response:1; }","duration":"104.906045ms","start":"2026-05-30T10:03:14.632027Z","end":"2026-05-30T10:03:14.736933Z","steps":["trace[1936230066] 'process raft request' (duration: 104.594476ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:27.019637Z","caller":"traceutil/trace.go:171","msg":"trace[2044801991] transaction","detail":"{read_only:false; response_revision:9393; number_of_response:1; }","duration":"237.824668ms","start":"2026-05-30T10:03:26.781786Z","end":"2026-05-30T10:03:27.019611Z","steps":["trace[2044801991] 'process raft request' (duration: 231.723253ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:27.019627Z","caller":"traceutil/trace.go:171","msg":"trace[776396676] linearizableReadLoop","detail":"{readStateIndex:9733; appliedIndex:9732; }","duration":"134.270521ms","start":"2026-05-30T10:03:26.885333Z","end":"2026-05-30T10:03:27.019603Z","steps":["trace[776396676] 'read index received' (duration: 128.121114ms)","trace[776396676] 'applied index is now lower than readState.Index' (duration: 6.148347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:03:27.019821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.479277ms","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-05-30T10:03:27.0199Z","caller":"traceutil/trace.go:171","msg":"trace[972961595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9393; }","duration":"134.5782ms","start":"2026-05-30T10:03:26.885275Z","end":"2026-05-30T10:03:27.019853Z","steps":["trace[972961595] 'agreement among raft nodes before linearized reading' (duration: 134.414165ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:03:34.887618Z","caller":"traceutil/trace.go:171","msg":"trace[1036914172] transaction","detail":"{read_only:false; response_revision:9456; number_of_response:1; }","duration":"103.616799ms","start":"2026-05-30T10:03:34.783967Z","end":"2026-05-30T10:03:34.887584Z","steps":["trace[1036914172] 'process raft request' (duration: 103.413853ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:04:03.153853Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T10:04:03.158969Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-30T10:04:03.159054Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-30T10:06:18.276555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8268} {"level":"info","ts":"2026-05-30T10:06:18.355104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8268,"took":"76.30966ms","hash":9251145,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-30T10:06:18.355183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":9251145,"revision":8268,"compact-revision":6073} {"level":"info","ts":"2026-05-30T10:07:10.736787Z","caller":"traceutil/trace.go:171","msg":"trace[499185655] transaction","detail":"{read_only:false; response_revision:11241; number_of_response:1; }","duration":"103.655484ms","start":"2026-05-30T10:07:10.633093Z","end":"2026-05-30T10:07:10.736749Z","steps":["trace[499185655] 'process raft request' (duration: 56.366697ms)","trace[499185655] 'compare' (duration: 47.086971ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:11:18.287517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10754} {"level":"info","ts":"2026-05-30T10:11:18.373399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10754,"took":"83.438121ms","hash":3158090739,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":27267072,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-30T10:11:18.373484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3158090739,"revision":10754,"compact-revision":8268} {"level":"info","ts":"2026-05-30T10:13:10.910203Z","caller":"traceutil/trace.go:171","msg":"trace[1220413295] transaction","detail":"{read_only:false; response_revision:13686; number_of_response:1; }","duration":"147.871516ms","start":"2026-05-30T10:13:10.762308Z","end":"2026-05-30T10:13:10.910179Z","steps":["trace[1220413295] 'process raft request' (duration: 147.708952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:13:49.346111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.969978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:13:49.346206Z","caller":"traceutil/trace.go:171","msg":"trace[1536217124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13900; }","duration":"360.129342ms","start":"2026-05-30T10:13:48.986057Z","end":"2026-05-30T10:13:49.346186Z","steps":["trace[1536217124] 'range keys from in-memory index tree' (duration: 359.825543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:13:49.346256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:13:48.986029Z","time spent":"360.214925ms","remote":"127.0.0.1:35282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T10:13:49.361988Z","caller":"traceutil/trace.go:171","msg":"trace[2074917374] transaction","detail":"{read_only:false; response_revision:13901; number_of_response:1; }","duration":"276.38841ms","start":"2026-05-30T10:13:49.085577Z","end":"2026-05-30T10:13:49.361966Z","steps":["trace[2074917374] 'process raft request' (duration: 276.238046ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:13:49.362018Z","caller":"traceutil/trace.go:171","msg":"trace[1241587751] linearizableReadLoop","detail":"{readStateIndex:14389; appliedIndex:14389; }","duration":"257.722326ms","start":"2026-05-30T10:13:49.104269Z","end":"2026-05-30T10:13:49.361991Z","steps":["trace[1241587751] 'read index received' (duration: 257.711885ms)","trace[1241587751] 'applied index is now lower than readState.Index' (duration: 8.421µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:13:49.362203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.940532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-30T10:13:49.362189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.916502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T10:13:49.362237Z","caller":"traceutil/trace.go:171","msg":"trace[200247550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13901; }","duration":"258.005923ms","start":"2026-05-30T10:13:49.10422Z","end":"2026-05-30T10:13:49.362226Z","steps":["trace[200247550] 'agreement among raft nodes before linearized reading' (duration: 257.87419ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:13:49.362256Z","caller":"traceutil/trace.go:171","msg":"trace[232116308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13901; }","duration":"258.049325ms","start":"2026-05-30T10:13:49.104187Z","end":"2026-05-30T10:13:49.362237Z","steps":["trace[232116308] 'agreement among raft nodes before linearized reading' (duration: 257.860719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:13:49.366783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.893361ms","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-05-30T10:13:49.366819Z","caller":"traceutil/trace.go:171","msg":"trace[273326433] transaction","detail":"{read_only:false; response_revision:13902; number_of_response:1; }","duration":"200.85925ms","start":"2026-05-30T10:13:49.165933Z","end":"2026-05-30T10:13:49.366793Z","steps":["trace[273326433] 'process raft request' (duration: 200.524951ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:13:49.36692Z","caller":"traceutil/trace.go:171","msg":"trace[1218557041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13903; }","duration":"257.029295ms","start":"2026-05-30T10:13:49.109813Z","end":"2026-05-30T10:13:49.366843Z","steps":["trace[1218557041] 'agreement among raft nodes before linearized reading' (duration: 256.84004ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:13:49.36751Z","caller":"traceutil/trace.go:171","msg":"trace[1914373847] transaction","detail":"{read_only:false; response_revision:13903; number_of_response:1; }","duration":"143.960535ms","start":"2026-05-30T10:13:49.223533Z","end":"2026-05-30T10:13:49.367493Z","steps":["trace[1914373847] 'process raft request' (duration: 143.069999ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:14:10.578364Z","caller":"traceutil/trace.go:171","msg":"trace[43126199] transaction","detail":"{read_only:false; response_revision:14197; number_of_response:1; }","duration":"100.249916ms","start":"2026-05-30T10:14:10.478081Z","end":"2026-05-30T10:14:10.578331Z","steps":["trace[43126199] 'process raft request' (duration: 49.924918ms)","trace[43126199] 'compare' (duration: 50.089561ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:14:11.511312Z","caller":"traceutil/trace.go:171","msg":"trace[598493214] transaction","detail":"{read_only:false; response_revision:14210; number_of_response:1; }","duration":"154.852545ms","start":"2026-05-30T10:14:11.356429Z","end":"2026-05-30T10:14:11.511281Z","steps":["trace[598493214] 'process raft request' (duration: 119.021041ms)","trace[598493214] 'compare' (duration: 35.649439ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:14:11.51163Z","caller":"traceutil/trace.go:171","msg":"trace[412684235] linearizableReadLoop","detail":"{readStateIndex:14712; appliedIndex:14711; }","duration":"151.86235ms","start":"2026-05-30T10:14:11.359714Z","end":"2026-05-30T10:14:11.511576Z","steps":["trace[412684235] 'read index received' (duration: 115.751138ms)","trace[412684235] 'applied index is now lower than readState.Index' (duration: 36.105142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:14:11.511939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.20372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7a39ee81-b122-4187-a03b-884d8a351dd8\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-30T10:14:11.511979Z","caller":"traceutil/trace.go:171","msg":"trace[1301342246] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7a39ee81-b122-4187-a03b-884d8a351dd8; range_end:; response_count:1; response_revision:14212; }","duration":"152.289582ms","start":"2026-05-30T10:14:11.359676Z","end":"2026-05-30T10:14:11.511965Z","steps":["trace[1301342246] 'agreement among raft nodes before linearized reading' (duration: 152.078016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:11.973634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.207926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T10:14:11.973829Z","caller":"traceutil/trace.go:171","msg":"trace[868865580] transaction","detail":"{read_only:false; response_revision:14214; number_of_response:1; }","duration":"456.60923ms","start":"2026-05-30T10:14:11.517199Z","end":"2026-05-30T10:14:11.973808Z","steps":["trace[868865580] 'process raft request' (duration: 456.533767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:11.973969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:11.517182Z","time spent":"456.691302ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:14:11.974159Z","caller":"traceutil/trace.go:171","msg":"trace[682321155] linearizableReadLoop","detail":"{readStateIndex:14715; appliedIndex:14714; }","duration":"457.178195ms","start":"2026-05-30T10:14:11.516866Z","end":"2026-05-30T10:14:11.974044Z","steps":["trace[682321155] 'read index received' (duration: 217.476535ms)","trace[682321155] 'applied index is now lower than readState.Index' (duration: 239.69926ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T10:14:11.974189Z","caller":"traceutil/trace.go:171","msg":"trace[12213077] transaction","detail":"{read_only:false; response_revision:14213; number_of_response:1; }","duration":"457.959338ms","start":"2026-05-30T10:14:11.516141Z","end":"2026-05-30T10:14:11.9741Z","steps":["trace[12213077] 'process raft request' (duration: 218.199066ms)","trace[12213077] 'compare' (duration: 239.042052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:14:11.9743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.408904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-30T10:14:11.974386Z","caller":"traceutil/trace.go:171","msg":"trace[1450743647] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:14214; }","duration":"457.513016ms","start":"2026-05-30T10:14:11.516851Z","end":"2026-05-30T10:14:11.974364Z","steps":["trace[1450743647] 'agreement among raft nodes before linearized reading' (duration: 457.284099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:11.97443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:11.516844Z","time spent":"457.577508ms","remote":"127.0.0.1:35556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-30T10:14:11.974574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.008813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-30T10:14:11.97457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:11.516119Z","time spent":"458.215116ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T10:14:11.974626Z","caller":"traceutil/trace.go:171","msg":"trace[1935528822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14214; }","duration":"442.089235ms","start":"2026-05-30T10:14:11.532524Z","end":"2026-05-30T10:14:11.974614Z","steps":["trace[1935528822] 'agreement among raft nodes before linearized reading' (duration: 441.805177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:14:11.974687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:14:11.532512Z","time spent":"442.159177ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T10:15:31.546455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.727938ms","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-05-30T10:15:31.546551Z","caller":"traceutil/trace.go:171","msg":"trace[539954225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14989; }","duration":"115.870462ms","start":"2026-05-30T10:15:31.430661Z","end":"2026-05-30T10:15:31.546532Z","steps":["trace[539954225] 'range keys from in-memory index tree' (duration: 115.570933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:15:37.153743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.334725ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-30T10:15:37.153871Z","caller":"traceutil/trace.go:171","msg":"trace[1569945154] linearizableReadLoop","detail":"{readStateIndex:15542; appliedIndex:15541; }","duration":"320.86201ms","start":"2026-05-30T10:15:36.832986Z","end":"2026-05-30T10:15:37.153848Z","steps":["trace[1569945154] 'read index received' (duration: 99.335149ms)","trace[1569945154] 'applied index is now lower than readState.Index' (duration: 221.525261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T10:15:37.155228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.64087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T10:15:37.155301Z","caller":"traceutil/trace.go:171","msg":"trace[722626826] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:15018; }","duration":"175.763353ms","start":"2026-05-30T10:15:36.979518Z","end":"2026-05-30T10:15:37.155281Z","steps":["trace[722626826] 'agreement among raft nodes before linearized reading' (duration: 175.63003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:15:37.155489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.502267ms","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-05-30T10:15:37.155619Z","caller":"traceutil/trace.go:171","msg":"trace[987876877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15018; }","duration":"322.666301ms","start":"2026-05-30T10:15:36.832931Z","end":"2026-05-30T10:15:37.155598Z","steps":["trace[987876877] 'agreement among raft nodes before linearized reading' (duration: 322.28284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:15:37.155294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.158889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T10:15:37.155736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.26068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-05-30T10:15:37.155742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T10:15:36.832911Z","time spent":"322.809576ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-30T10:15:37.155911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.098265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-30T10:15:37.155808Z","caller":"traceutil/trace.go:171","msg":"trace[829099345] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15018; }","duration":"116.670594ms","start":"2026-05-30T10:15:37.039115Z","end":"2026-05-30T10:15:37.155786Z","steps":["trace[829099345] 'agreement among raft nodes before linearized reading' (duration: 116.112548ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:15:37.155953Z","caller":"traceutil/trace.go:171","msg":"trace[1771784038] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15018; }","duration":"117.171357ms","start":"2026-05-30T10:15:37.038772Z","end":"2026-05-30T10:15:37.155943Z","steps":["trace[1771784038] 'agreement among raft nodes before linearized reading' (duration: 117.074534ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:15:37.15582Z","caller":"traceutil/trace.go:171","msg":"trace[1517448557] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15018; }","duration":"117.374454ms","start":"2026-05-30T10:15:37.038418Z","end":"2026-05-30T10:15:37.155792Z","steps":["trace[1517448557] 'agreement among raft nodes before linearized reading' (duration: 117.165608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:15:37.155748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.737887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T10:15:37.156432Z","caller":"traceutil/trace.go:171","msg":"trace[614083390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15018; }","duration":"172.470879ms","start":"2026-05-30T10:15:36.983944Z","end":"2026-05-30T10:15:37.156414Z","steps":["trace[614083390] 'agreement among raft nodes before linearized reading' (duration: 171.723697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T10:15:37.156478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.792548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-30T10:15:37.156499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.351003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-30T10:15:37.156544Z","caller":"traceutil/trace.go:171","msg":"trace[1157066932] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15018; }","duration":"116.886631ms","start":"2026-05-30T10:15:37.039639Z","end":"2026-05-30T10:15:37.156525Z","steps":["trace[1157066932] 'agreement among raft nodes before linearized reading' (duration: 116.790378ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:15:37.156563Z","caller":"traceutil/trace.go:171","msg":"trace[179676996] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15018; }","duration":"117.433156ms","start":"2026-05-30T10:15:37.039111Z","end":"2026-05-30T10:15:37.156544Z","steps":["trace[179676996] 'agreement among raft nodes before linearized reading' (duration: 117.252601ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T10:16:18.293898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12830} {"level":"info","ts":"2026-05-30T10:16:18.372676Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12830,"took":"76.498036ms","hash":1357816605,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":30425088,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-30T10:16:18.37273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1357816605,"revision":12830,"compact-revision":10754} {"level":"info","ts":"2026-05-30T10:21:18.299428Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15287} {"level":"info","ts":"2026-05-30T10:21:18.401389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15287,"took":"99.183432ms","hash":4291387634,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":31621120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-30T10:21:18.401457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4291387634,"revision":15287,"compact-revision":12830} {"level":"info","ts":"2026-05-30T10:26:18.307798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17530} {"level":"info","ts":"2026-05-30T10:26:18.4033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17530,"took":"92.458294ms","hash":80600336,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":26566656,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-30T10:26:18.403363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":80600336,"revision":17530,"compact-revision":15287} {"level":"info","ts":"2026-05-30T10:26:58.491298Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4e46287fb5aa4785","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-30T10:26:58.511688Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-30T10:26:58.511805Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}