{"level":"warn","ts":"2026-06-23T02:56:02.099234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T02:56:02.099323Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-06-23T02:56:02.099409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T02:56:02.099418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-06-23T02:56:02.099435Z","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-06-23T02:56:02.100087Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-06-23T02:56:02.10016Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-06-23T02:56:02.103043Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.669937ms"} {"level":"info","ts":"2026-06-23T02:56:02.112045Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-06-23T02:56:02.112127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-06-23T02:56:02.112149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-06-23T02:56:02.112159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-23T02:56:02.11217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-06-23T02:56:02.112203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-06-23T02:56:02.117742Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-23T02:56:02.119346Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-23T02:56:02.120898Z","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-06-23T02:56:02.123374Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-23T02:56:02.123664Z","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-06-23T02:56:02.123846Z","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-06-23T02:56:02.123872Z","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-06-23T02:56:02.123843Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-23T02:56:02.126022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-06-23T02:56:02.126572Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-06-23T02:56:02.128472Z","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-06-23T02:56:02.128616Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-06-23T02:56:02.128694Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-06-23T02:56:02.128737Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-23T02:56:02.128812Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-23T02:56:03.013059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-06-23T02:56:03.013117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-23T02:56:03.01314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-06-23T02:56:03.013159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-06-23T02:56:03.01317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-06-23T02:56:03.013185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-06-23T02:56:03.013199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-06-23T02:56:03.032111Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-06-23T02:56:03.03213Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T02:56:03.032165Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T02:56:03.032211Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T02:56:03.032486Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-23T02:56:03.032594Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-23T02:56:03.033845Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T02:56:03.034003Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T02:56:03.03406Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T02:56:03.036234Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-06-23T02:56:03.036298Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-23T02:56:51.372935Z","caller":"traceutil/trace.go:171","msg":"trace[1475416378] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"163.360381ms","start":"2026-06-23T02:56:51.209533Z","end":"2026-06-23T02:56:51.372894Z","steps":["trace[1475416378] 'process raft request' (duration: 162.581942ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:56:51.611236Z","caller":"traceutil/trace.go:171","msg":"trace[264993738] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"171.81805ms","start":"2026-06-23T02:56:51.439397Z","end":"2026-06-23T02:56:51.611215Z","steps":["trace[264993738] 'process raft request' (duration: 171.647165ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:56:52.156725Z","caller":"traceutil/trace.go:171","msg":"trace[115946035] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"365.321317ms","start":"2026-06-23T02:56:51.791384Z","end":"2026-06-23T02:56:52.156705Z","steps":["trace[115946035] 'process raft request' (duration: 365.205634ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:56:52.156837Z","caller":"traceutil/trace.go:171","msg":"trace[1703791843] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:631; }","duration":"254.892195ms","start":"2026-06-23T02:56:51.901869Z","end":"2026-06-23T02:56:52.156762Z","steps":["trace[1703791843] 'read index received' (duration: 254.876795ms)","trace[1703791843] 'applied index is now lower than readState.Index' (duration: 12.87µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T02:56:52.1572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.385416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gncfl\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-06-23T02:56:52.157348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T02:56:51.791371Z","time spent":"365.46736ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T02:56:52.157406Z","caller":"traceutil/trace.go:171","msg":"trace[601328853] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gncfl; range_end:; response_count:1; response_revision:606; }","duration":"255.663994ms","start":"2026-06-23T02:56:51.901696Z","end":"2026-06-23T02:56:52.15736Z","steps":["trace[601328853] 'agreement among raft nodes before linearized reading' (duration: 255.229593ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:56:52.286763Z","caller":"traceutil/trace.go:171","msg":"trace[150358137] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"129.81477ms","start":"2026-06-23T02:56:52.156921Z","end":"2026-06-23T02:56:52.286736Z","steps":["trace[150358137] 'read index received' (duration: 128.633211ms)","trace[150358137] 'applied index is now lower than readState.Index' (duration: 1.180509ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T02:56:52.287218Z","caller":"traceutil/trace.go:171","msg":"trace[770070774] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"270.805768ms","start":"2026-06-23T02:56:52.015989Z","end":"2026-06-23T02:56:52.286794Z","steps":["trace[770070774] 'process raft request' (duration: 269.698341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T02:56:52.287627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.048768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T02:56:52.287834Z","caller":"traceutil/trace.go:171","msg":"trace[1609792662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"143.280573ms","start":"2026-06-23T02:56:52.144487Z","end":"2026-06-23T02:56:52.287767Z","steps":["trace[1609792662] 'agreement among raft nodes before linearized reading' (duration: 142.993566ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:56:52.292097Z","caller":"traceutil/trace.go:171","msg":"trace[2022569099] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"121.486494ms","start":"2026-06-23T02:56:52.170569Z","end":"2026-06-23T02:56:52.292056Z","steps":["trace[2022569099] 'process raft request' (duration: 120.272833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T02:58:26.387704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.522792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-23T02:58:26.38775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.126405ms","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-06-23T02:58:26.387791Z","caller":"traceutil/trace.go:171","msg":"trace[926396755] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:1852; }","duration":"310.641334ms","start":"2026-06-23T02:58:26.077136Z","end":"2026-06-23T02:58:26.387777Z","steps":["trace[926396755] 'range keys from in-memory index tree' (duration: 310.387679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T02:58:26.387801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.763719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-915c21b9-5037-491d-99fb-ec9cbd4bdf01\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-23T02:58:26.387823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T02:58:26.077114Z","time spent":"310.702056ms","remote":"127.0.0.1:50454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.132\" "} {"level":"info","ts":"2026-06-23T02:58:26.387829Z","caller":"traceutil/trace.go:171","msg":"trace[1318146162] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"189.217067ms","start":"2026-06-23T02:58:26.198592Z","end":"2026-06-23T02:58:26.38781Z","steps":["trace[1318146162] 'range keys from in-memory index tree' (duration: 188.967351ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:58:26.387837Z","caller":"traceutil/trace.go:171","msg":"trace[512760378] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-915c21b9-5037-491d-99fb-ec9cbd4bdf01; range_end:; response_count:1; response_revision:1852; }","duration":"295.817691ms","start":"2026-06-23T02:58:26.092007Z","end":"2026-06-23T02:58:26.387825Z","steps":["trace[512760378] 'range keys from in-memory index tree' (duration: 295.676837ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:58:26.762782Z","caller":"traceutil/trace.go:171","msg":"trace[1502080410] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"283.014855ms","start":"2026-06-23T02:58:26.479745Z","end":"2026-06-23T02:58:26.76276Z","steps":["trace[1502080410] 'read index received' (duration: 282.920403ms)","trace[1502080410] 'applied index is now lower than readState.Index' (duration: 93.262µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T02:58:26.762934Z","caller":"traceutil/trace.go:171","msg":"trace[2075807573] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"366.667294ms","start":"2026-06-23T02:58:26.396182Z","end":"2026-06-23T02:58:26.762849Z","steps":["trace[2075807573] 'process raft request' (duration: 366.436188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T02:58:26.763049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.287751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10668"} {"level":"info","ts":"2026-06-23T02:58:26.763095Z","caller":"traceutil/trace.go:171","msg":"trace[918817532] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1854; }","duration":"283.386274ms","start":"2026-06-23T02:58:26.479699Z","end":"2026-06-23T02:58:26.763085Z","steps":["trace[918817532] 'agreement among raft nodes before linearized reading' (duration: 283.175269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T02:58:26.763265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T02:58:26.396173Z","time spent":"366.859579ms","remote":"127.0.0.1:50454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T02:58:26.776141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.657883ms","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-06-23T02:58:26.776218Z","caller":"traceutil/trace.go:171","msg":"trace[1948141237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1855; }","duration":"175.742945ms","start":"2026-06-23T02:58:26.600458Z","end":"2026-06-23T02:58:26.776201Z","steps":["trace[1948141237] 'agreement among raft nodes before linearized reading' (duration: 175.513609ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T02:59:17.0715Z","caller":"traceutil/trace.go:171","msg":"trace[534895436] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"109.514569ms","start":"2026-06-23T02:59:16.96196Z","end":"2026-06-23T02:59:17.071475Z","steps":["trace[534895436] 'process raft request' (duration: 109.351826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:00:44.22607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.139845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-23T03:00:44.226198Z","caller":"traceutil/trace.go:171","msg":"trace[688154212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2853; }","duration":"165.33144ms","start":"2026-06-23T03:00:44.060841Z","end":"2026-06-23T03:00:44.226173Z","steps":["trace[688154212] 'range keys from in-memory index tree' (duration: 164.92012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:00:44.226831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.435961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T03:00:44.226998Z","caller":"traceutil/trace.go:171","msg":"trace[815175055] linearizableReadLoop","detail":"{readStateIndex:2963; appliedIndex:2962; }","duration":"152.251879ms","start":"2026-06-23T03:00:44.07472Z","end":"2026-06-23T03:00:44.226972Z","steps":["trace[815175055] 'read index received' (duration: 20.238484ms)","trace[815175055] 'applied index is now lower than readState.Index' (duration: 132.010455ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:00:44.22702Z","caller":"traceutil/trace.go:171","msg":"trace[790143634] transaction","detail":"{read_only:false; response_revision:2854; number_of_response:1; }","duration":"156.879273ms","start":"2026-06-23T03:00:44.070091Z","end":"2026-06-23T03:00:44.226971Z","steps":["trace[790143634] 'process raft request' (duration: 24.96558ms)","trace[790143634] 'compare' (duration: 131.091033ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:00:44.22731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.550537ms","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-06-23T03:00:44.227437Z","caller":"traceutil/trace.go:171","msg":"trace[1897060527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2854; }","duration":"152.69735ms","start":"2026-06-23T03:00:44.074714Z","end":"2026-06-23T03:00:44.227412Z","steps":["trace[1897060527] 'agreement among raft nodes before linearized reading' (duration: 152.343942ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:04:58.158076Z","caller":"traceutil/trace.go:171","msg":"trace[1428981150] linearizableReadLoop","detail":"{readStateIndex:5450; appliedIndex:5449; }","duration":"118.009637ms","start":"2026-06-23T03:04:58.040039Z","end":"2026-06-23T03:04:58.158049Z","steps":["trace[1428981150] 'read index received' (duration: 117.724021ms)","trace[1428981150] 'applied index is now lower than readState.Index' (duration: 284.616µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:04:58.15812Z","caller":"traceutil/trace.go:171","msg":"trace[939014599] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"216.674384ms","start":"2026-06-23T03:04:57.941412Z","end":"2026-06-23T03:04:58.158087Z","steps":["trace[939014599] 'process raft request' (duration: 216.386637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:04:58.158406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.351456ms","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-06-23T03:04:58.15848Z","caller":"traceutil/trace.go:171","msg":"trace[188409842] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5251; }","duration":"118.467898ms","start":"2026-06-23T03:04:58.039996Z","end":"2026-06-23T03:04:58.158464Z","steps":["trace[188409842] 'agreement among raft nodes before linearized reading' (duration: 118.221153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:04:58.479769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.777858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-23T03:04:58.479871Z","caller":"traceutil/trace.go:171","msg":"trace[1453477714] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"159.854318ms","start":"2026-06-23T03:04:58.320001Z","end":"2026-06-23T03:04:58.479855Z","steps":["trace[1453477714] 'compare' (duration: 148.673756ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:04:58.544848Z","caller":"traceutil/trace.go:171","msg":"trace[406442761] linearizableReadLoop","detail":"{readStateIndex:5457; appliedIndex:5456; }","duration":"126.371761ms","start":"2026-06-23T03:04:58.418456Z","end":"2026-06-23T03:04:58.544828Z","steps":["trace[406442761] 'read index received' (duration: 126.261049ms)","trace[406442761] 'applied index is now lower than readState.Index' (duration: 109.532µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:04:58.544892Z","caller":"traceutil/trace.go:171","msg":"trace[1301928680] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"142.481865ms","start":"2026-06-23T03:04:58.402388Z","end":"2026-06-23T03:04:58.54487Z","steps":["trace[1301928680] 'process raft request' (duration: 142.289391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:04:58.545041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.568617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-wst87\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-06-23T03:04:58.545084Z","caller":"traceutil/trace.go:171","msg":"trace[786187143] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-wst87; range_end:; response_count:1; response_revision:5258; }","duration":"126.658328ms","start":"2026-06-23T03:04:58.418415Z","end":"2026-06-23T03:04:58.545073Z","steps":["trace[786187143] 'agreement among raft nodes before linearized reading' (duration: 126.506885ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:04:58.561886Z","caller":"traceutil/trace.go:171","msg":"trace[294153348] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"103.257958ms","start":"2026-06-23T03:04:58.45862Z","end":"2026-06-23T03:04:58.561878Z","steps":["trace[294153348] 'process raft request' (duration: 103.205657ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:05:20.765973Z","caller":"traceutil/trace.go:171","msg":"trace[1942380109] linearizableReadLoop","detail":"{readStateIndex:5827; appliedIndex:5826; }","duration":"372.456123ms","start":"2026-06-23T03:05:20.393489Z","end":"2026-06-23T03:05:20.765945Z","steps":["trace[1942380109] 'read index received' (duration: 372.269179ms)","trace[1942380109] 'applied index is now lower than readState.Index' (duration: 185.564µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:05:20.766079Z","caller":"traceutil/trace.go:171","msg":"trace[357547925] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"375.17974ms","start":"2026-06-23T03:05:20.39086Z","end":"2026-06-23T03:05:20.766039Z","steps":["trace[357547925] 'process raft request' (duration: 374.899113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:05:20.766264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:05:20.390846Z","time spent":"375.327033ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T03:05:20.766281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.769163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5fvhf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-23T03:05:20.766328Z","caller":"traceutil/trace.go:171","msg":"trace[604049278] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5fvhf; range_end:; response_count:1; response_revision:5621; }","duration":"372.887975ms","start":"2026-06-23T03:05:20.393428Z","end":"2026-06-23T03:05:20.766316Z","steps":["trace[604049278] 'agreement among raft nodes before linearized reading' (duration: 372.658289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:05:20.766366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:05:20.393387Z","time spent":"372.970137ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-5fvhf\" "} {"level":"info","ts":"2026-06-23T03:05:20.776606Z","caller":"traceutil/trace.go:171","msg":"trace[215060186] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"155.218555ms","start":"2026-06-23T03:05:20.621356Z","end":"2026-06-23T03:05:20.776575Z","steps":["trace[215060186] 'process raft request' (duration: 154.955748ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:05:20.776741Z","caller":"traceutil/trace.go:171","msg":"trace[228041651] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"146.910052ms","start":"2026-06-23T03:05:20.629816Z","end":"2026-06-23T03:05:20.776726Z","steps":["trace[228041651] 'process raft request' (duration: 146.672107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:05:20.776922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.205361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T03:05:20.776966Z","caller":"traceutil/trace.go:171","msg":"trace[1684536442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5623; }","duration":"177.275843ms","start":"2026-06-23T03:05:20.599679Z","end":"2026-06-23T03:05:20.776955Z","steps":["trace[1684536442] 'agreement among raft nodes before linearized reading' (duration: 177.17757ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:05:21.081351Z","caller":"traceutil/trace.go:171","msg":"trace[677347686] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"112.045333ms","start":"2026-06-23T03:05:20.96928Z","end":"2026-06-23T03:05:21.081325Z","steps":["trace[677347686] 'process raft request' (duration: 111.897719ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:06:03.072813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-06-23T03:06:03.147469Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"72.16085ms","hash":942314251,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":33751040,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-23T03:06:03.147523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":942314251,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-06-23T03:11:03.079518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5997} {"level":"info","ts":"2026-06-23T03:11:03.184955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5997,"took":"103.345821ms","hash":1261398184,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":21884928,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-23T03:11:03.185037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1261398184,"revision":5997,"compact-revision":2957} {"level":"warn","ts":"2026-06-23T03:11:36.540661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.615834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T03:11:36.540823Z","caller":"traceutil/trace.go:171","msg":"trace[1612661341] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"301.990166ms","start":"2026-06-23T03:11:36.238784Z","end":"2026-06-23T03:11:36.540774Z","steps":["trace[1612661341] 'process raft request' (duration: 182.165267ms)","trace[1612661341] 'compare' (duration: 119.45747ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:11:36.540889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:11:36.238755Z","time spent":"302.104488ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T03:11:36.588763Z","caller":"traceutil/trace.go:171","msg":"trace[787308991] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"332.914395ms","start":"2026-06-23T03:11:36.255829Z","end":"2026-06-23T03:11:36.588744Z","steps":["trace[787308991] 'process raft request' (duration: 332.883634ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:11:36.588903Z","caller":"traceutil/trace.go:171","msg":"trace[1428678513] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"333.717584ms","start":"2026-06-23T03:11:36.255164Z","end":"2026-06-23T03:11:36.588882Z","steps":["trace[1428678513] 'process raft request' (duration: 333.434077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:11:36.588984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:11:36.255813Z","time spent":"333.086789ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T03:11:36.588985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:11:36.255137Z","time spent":"333.806436ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T03:11:36.589579Z","caller":"traceutil/trace.go:171","msg":"trace[293395938] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"125.139878ms","start":"2026-06-23T03:11:36.464408Z","end":"2026-06-23T03:11:36.589547Z","steps":["trace[293395938] 'process raft request' (duration: 124.976374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:11:49.021857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.577511ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T03:11:49.021976Z","caller":"traceutil/trace.go:171","msg":"trace[1775274180] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8484; }","duration":"226.724095ms","start":"2026-06-23T03:11:48.795233Z","end":"2026-06-23T03:11:49.021957Z","steps":["trace[1775274180] 'range keys from in-memory index tree' (duration: 226.551971ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:11:49.022336Z","caller":"traceutil/trace.go:171","msg":"trace[1036117691] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"267.149283ms","start":"2026-06-23T03:11:48.755173Z","end":"2026-06-23T03:11:49.022322Z","steps":["trace[1036117691] 'process raft request' (duration: 228.147479ms)","trace[1036117691] 'compare' (duration: 38.43867ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:11:49.022525Z","caller":"traceutil/trace.go:171","msg":"trace[1563614154] linearizableReadLoop","detail":"{readStateIndex:8786; appliedIndex:8785; }","duration":"232.946406ms","start":"2026-06-23T03:11:48.789546Z","end":"2026-06-23T03:11:49.022492Z","steps":["trace[1563614154] 'read index received' (duration: 193.734877ms)","trace[1563614154] 'applied index is now lower than readState.Index' (duration: 39.210209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:11:49.022823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.261583ms","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-06-23T03:11:49.022871Z","caller":"traceutil/trace.go:171","msg":"trace[282527899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8485; }","duration":"233.367945ms","start":"2026-06-23T03:11:48.789489Z","end":"2026-06-23T03:11:49.022857Z","steps":["trace[282527899] 'agreement among raft nodes before linearized reading' (duration: 233.115209ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:11:49.969347Z","caller":"traceutil/trace.go:171","msg":"trace[929605254] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"128.482488ms","start":"2026-06-23T03:11:49.840834Z","end":"2026-06-23T03:11:49.969317Z","steps":["trace[929605254] 'process raft request' (duration: 39.094056ms)","trace[929605254] 'compare' (duration: 89.153887ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:11:49.969496Z","caller":"traceutil/trace.go:171","msg":"trace[2133471822] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"128.133049ms","start":"2026-06-23T03:11:49.841305Z","end":"2026-06-23T03:11:49.969438Z","steps":["trace[2133471822] 'process raft request' (duration: 127.975915ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:12:16.833452Z","caller":"traceutil/trace.go:171","msg":"trace[1473396144] transaction","detail":"{read_only:false; response_revision:8689; number_of_response:1; }","duration":"149.308942ms","start":"2026-06-23T03:12:16.684124Z","end":"2026-06-23T03:12:16.833433Z","steps":["trace[1473396144] 'process raft request' (duration: 149.178159ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:12:17.068063Z","caller":"traceutil/trace.go:171","msg":"trace[882381702] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"319.831779ms","start":"2026-06-23T03:12:16.748208Z","end":"2026-06-23T03:12:17.06804Z","steps":["trace[882381702] 'process raft request' (duration: 319.774887ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:12:17.068137Z","caller":"traceutil/trace.go:171","msg":"trace[655993884] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"324.032419ms","start":"2026-06-23T03:12:16.744071Z","end":"2026-06-23T03:12:17.068104Z","steps":["trace[655993884] 'process raft request' (duration: 293.593592ms)","trace[655993884] 'compare' (duration: 29.881173ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:12:17.068211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:12:16.74818Z","time spent":"319.950271ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T03:12:17.068285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:12:16.744051Z","time spent":"324.154063ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T03:12:18.00001Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000232e.wal"} {"level":"info","ts":"2026-06-23T03:12:21.36188Z","caller":"traceutil/trace.go:171","msg":"trace[1555665351] linearizableReadLoop","detail":"{readStateIndex:9020; appliedIndex:9019; }","duration":"216.61884ms","start":"2026-06-23T03:12:21.145236Z","end":"2026-06-23T03:12:21.361855Z","steps":["trace[1555665351] 'read index received' (duration: 141.476733ms)","trace[1555665351] 'applied index is now lower than readState.Index' (duration: 75.140667ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:12:21.361964Z","caller":"traceutil/trace.go:171","msg":"trace[1573121056] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"223.069086ms","start":"2026-06-23T03:12:21.138855Z","end":"2026-06-23T03:12:21.361924Z","steps":["trace[1573121056] 'process raft request' (duration: 147.920239ms)","trace[1573121056] 'compare' (duration: 74.953002ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:12:21.362012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.772054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T03:12:21.362059Z","caller":"traceutil/trace.go:171","msg":"trace[541669948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8711; }","duration":"216.864336ms","start":"2026-06-23T03:12:21.145184Z","end":"2026-06-23T03:12:21.362049Z","steps":["trace[541669948] 'agreement among raft nodes before linearized reading' (duration: 216.775134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:12:21.571974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.493899ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-06-23T03:12:21.572233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.320254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5fvhf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-23T03:12:21.572298Z","caller":"traceutil/trace.go:171","msg":"trace[496009872] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5fvhf; range_end:; response_count:1; response_revision:8711; }","duration":"290.424247ms","start":"2026-06-23T03:12:21.281853Z","end":"2026-06-23T03:12:21.572277Z","steps":["trace[496009872] 'agreement among raft nodes before linearized reading' (duration: 290.265502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:12:21.5723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.560757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-23T03:12:21.572363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.146519ms","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-06-23T03:12:21.572383Z","caller":"traceutil/trace.go:171","msg":"trace[1538876513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8711; }","duration":"368.68013ms","start":"2026-06-23T03:12:21.203684Z","end":"2026-06-23T03:12:21.572365Z","steps":["trace[1538876513] 'agreement among raft nodes before linearized reading' (duration: 368.453974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:12:21.572423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:12:21.203672Z","time spent":"368.742521ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-23T03:12:21.572425Z","caller":"traceutil/trace.go:171","msg":"trace[892658382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8711; }","duration":"373.248261ms","start":"2026-06-23T03:12:21.199159Z","end":"2026-06-23T03:12:21.572407Z","steps":["trace[892658382] 'agreement among raft nodes before linearized reading' (duration: 373.078006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:12:21.572462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:12:21.199139Z","time spent":"373.314382ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-23T03:12:21.573422Z","caller":"traceutil/trace.go:171","msg":"trace[823285813] linearizableReadLoop","detail":"{readStateIndex:9021; appliedIndex:9020; }","duration":"210.106183ms","start":"2026-06-23T03:12:21.361968Z","end":"2026-06-23T03:12:21.572075Z","steps":["trace[823285813] 'read index received' (duration: 14.497502ms)","trace[823285813] 'applied index is now lower than readState.Index' (duration: 195.607181ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:12:21.574775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.731542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T03:12:21.577482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.724758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-23T03:12:21.577583Z","caller":"traceutil/trace.go:171","msg":"trace[1912743169] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8711; }","duration":"136.868031ms","start":"2026-06-23T03:12:21.440695Z","end":"2026-06-23T03:12:21.577563Z","steps":["trace[1912743169] 'agreement among raft nodes before linearized reading' (duration: 136.644016ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:12:21.574812Z","caller":"traceutil/trace.go:171","msg":"trace[642519943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8711; }","duration":"211.810624ms","start":"2026-06-23T03:12:21.362991Z","end":"2026-06-23T03:12:21.574801Z","steps":["trace[642519943] 'agreement among raft nodes before linearized reading' (duration: 211.742433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:12:29.590949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.218425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T03:12:29.591214Z","caller":"traceutil/trace.go:171","msg":"trace[666880422] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"201.448494ms","start":"2026-06-23T03:12:29.389747Z","end":"2026-06-23T03:12:29.591195Z","steps":["trace[666880422] 'process raft request' (duration: 201.401972ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:12:29.591465Z","caller":"traceutil/trace.go:171","msg":"trace[1802237174] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8798; }","duration":"270.3414ms","start":"2026-06-23T03:12:29.321117Z","end":"2026-06-23T03:12:29.591458Z","steps":["trace[1802237174] 'process raft request' (duration: 269.967971ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:12:29.595158Z","caller":"traceutil/trace.go:171","msg":"trace[1502406058] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"272.618395ms","start":"2026-06-23T03:12:29.319015Z","end":"2026-06-23T03:12:29.591633Z","steps":["trace[1502406058] 'process raft request' (duration: 140.619842ms)","trace[1502406058] 'compare' (duration: 131.084161ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:12:30.512912Z","caller":"traceutil/trace.go:171","msg":"trace[1300429508] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"337.29132ms","start":"2026-06-23T03:12:30.175592Z","end":"2026-06-23T03:12:30.512883Z","steps":["trace[1300429508] 'process raft request' (duration: 293.373647ms)","trace[1300429508] 'compare' (duration: 43.692658ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:12:30.513048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:12:30.175576Z","time spent":"337.404182ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T03:12:30.529712Z","caller":"traceutil/trace.go:171","msg":"trace[1081297617] linearizableReadLoop","detail":"{readStateIndex:9119; appliedIndex:9117; }","duration":"285.025836ms","start":"2026-06-23T03:12:30.244667Z","end":"2026-06-23T03:12:30.529693Z","steps":["trace[1081297617] 'read index received' (duration: 224.351568ms)","trace[1081297617] 'applied index is now lower than readState.Index' (duration: 60.673358ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:12:30.529946Z","caller":"traceutil/trace.go:171","msg":"trace[692777646] transaction","detail":"{read_only:false; response_revision:8806; number_of_response:1; }","duration":"267.079121ms","start":"2026-06-23T03:12:30.262849Z","end":"2026-06-23T03:12:30.529928Z","steps":["trace[692777646] 'process raft request' (duration: 266.804475ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:12:30.530042Z","caller":"traceutil/trace.go:171","msg":"trace[499086825] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"354.197729ms","start":"2026-06-23T03:12:30.175833Z","end":"2026-06-23T03:12:30.530031Z","steps":["trace[499086825] 'process raft request' (duration: 353.690887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:12:30.530135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:12:30.175812Z","time spent":"354.25386ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T03:12:30.530143Z","caller":"traceutil/trace.go:171","msg":"trace[2011494573] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"284.224165ms","start":"2026-06-23T03:12:30.245878Z","end":"2026-06-23T03:12:30.530102Z","steps":["trace[2011494573] 'process raft request' (duration: 283.740954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:12:30.530288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.599399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-g7hfk\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-06-23T03:12:30.530384Z","caller":"traceutil/trace.go:171","msg":"trace[1678356732] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-g7hfk; range_end:; response_count:1; response_revision:8806; }","duration":"285.709102ms","start":"2026-06-23T03:12:30.24466Z","end":"2026-06-23T03:12:30.530369Z","steps":["trace[1678356732] 'agreement among raft nodes before linearized reading' (duration: 285.437425ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:13:36.17849Z","caller":"traceutil/trace.go:171","msg":"trace[1953891038] linearizableReadLoop","detail":"{readStateIndex:9820; appliedIndex:9819; }","duration":"103.840662ms","start":"2026-06-23T03:13:36.074617Z","end":"2026-06-23T03:13:36.178458Z","steps":["trace[1953891038] 'read index received' (duration: 89.849944ms)","trace[1953891038] 'applied index is now lower than readState.Index' (duration: 13.989898ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:13:36.178716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.083769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5fvhf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-23T03:13:36.178747Z","caller":"traceutil/trace.go:171","msg":"trace[956979131] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5fvhf; range_end:; response_count:1; response_revision:9489; }","duration":"104.184791ms","start":"2026-06-23T03:13:36.074555Z","end":"2026-06-23T03:13:36.17874Z","steps":["trace[956979131] 'agreement among raft nodes before linearized reading' (duration: 104.043658ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:13:36.178572Z","caller":"traceutil/trace.go:171","msg":"trace[1136518297] transaction","detail":"{read_only:false; response_revision:9489; number_of_response:1; }","duration":"127.144586ms","start":"2026-06-23T03:13:36.051377Z","end":"2026-06-23T03:13:36.178521Z","steps":["trace[1136518297] 'process raft request' (duration: 113.194599ms)","trace[1136518297] 'compare' (duration: 13.603889ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:14:01.25308Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-23T03:14:01.260321Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-23T03:14:01.260416Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-23T03:14:39.193716Z","caller":"traceutil/trace.go:171","msg":"trace[1808755115] transaction","detail":"{read_only:false; response_revision:9913; number_of_response:1; }","duration":"133.93028ms","start":"2026-06-23T03:14:39.05975Z","end":"2026-06-23T03:14:39.193681Z","steps":["trace[1808755115] 'process raft request' (duration: 132.989168ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:14:39.234049Z","caller":"traceutil/trace.go:171","msg":"trace[475449056] transaction","detail":"{read_only:false; response_revision:9914; number_of_response:1; }","duration":"115.302339ms","start":"2026-06-23T03:14:39.118702Z","end":"2026-06-23T03:14:39.234004Z","steps":["trace[475449056] 'process raft request' (duration: 114.822588ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:14:43.701292Z","caller":"traceutil/trace.go:171","msg":"trace[81206625] transaction","detail":"{read_only:false; response_revision:9939; number_of_response:1; }","duration":"113.180949ms","start":"2026-06-23T03:14:43.588067Z","end":"2026-06-23T03:14:43.701248Z","steps":["trace[81206625] 'process raft request' (duration: 112.892752ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:14:44.923932Z","caller":"traceutil/trace.go:171","msg":"trace[2086945709] linearizableReadLoop","detail":"{readStateIndex:10296; appliedIndex:10295; }","duration":"116.637121ms","start":"2026-06-23T03:14:44.807258Z","end":"2026-06-23T03:14:44.923895Z","steps":["trace[2086945709] 'read index received' (duration: 48.855851ms)","trace[2086945709] 'applied index is now lower than readState.Index' (duration: 67.77932ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:14:44.924519Z","caller":"traceutil/trace.go:171","msg":"trace[771418990] transaction","detail":"{read_only:false; response_revision:9950; number_of_response:1; }","duration":"123.550801ms","start":"2026-06-23T03:14:44.800949Z","end":"2026-06-23T03:14:44.9245Z","steps":["trace[771418990] 'process raft request' (duration: 55.233757ms)","trace[771418990] 'compare' (duration: 67.539014ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:14:44.925129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.836331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-06-23T03:14:44.925187Z","caller":"traceutil/trace.go:171","msg":"trace[2003517228] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:9950; }","duration":"117.950134ms","start":"2026-06-23T03:14:44.807217Z","end":"2026-06-23T03:14:44.925167Z","steps":["trace[2003517228] 'agreement among raft nodes before linearized reading' (duration: 117.522173ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:16:03.085518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8067} {"level":"info","ts":"2026-06-23T03:16:03.151257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8067,"took":"62.216034ms","hash":266762099,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":28217344,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-23T03:16:03.151323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":266762099,"revision":8067,"compact-revision":5997} {"level":"info","ts":"2026-06-23T03:19:12.083117Z","caller":"traceutil/trace.go:171","msg":"trace[669000750] transaction","detail":"{read_only:false; response_revision:11905; number_of_response:1; }","duration":"211.352329ms","start":"2026-06-23T03:19:11.871718Z","end":"2026-06-23T03:19:12.08307Z","steps":["trace[669000750] 'process raft request' (duration: 211.073063ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:19:30.613644Z","caller":"traceutil/trace.go:171","msg":"trace[1996284065] transaction","detail":"{read_only:false; response_revision:12011; number_of_response:1; }","duration":"141.366987ms","start":"2026-06-23T03:19:30.472227Z","end":"2026-06-23T03:19:30.613594Z","steps":["trace[1996284065] 'process raft request' (duration: 141.158842ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:19:30.957556Z","caller":"traceutil/trace.go:171","msg":"trace[1868744613] transaction","detail":"{read_only:false; response_revision:12013; number_of_response:1; }","duration":"126.965069ms","start":"2026-06-23T03:19:30.830551Z","end":"2026-06-23T03:19:30.957516Z","steps":["trace[1868744613] 'process raft request' (duration: 72.586484ms)","trace[1868744613] 'compare' (duration: 54.17763ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:21:03.09299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10472} {"level":"info","ts":"2026-06-23T03:21:03.199479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10472,"took":"104.181799ms","hash":4113206,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":25915392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-23T03:21:03.199567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4113206,"revision":10472,"compact-revision":8067} {"level":"info","ts":"2026-06-23T03:26:03.099386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12601} {"level":"info","ts":"2026-06-23T03:26:03.18136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12601,"took":"79.296366ms","hash":178838944,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":27631616,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-23T03:26:03.181429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":178838944,"revision":12601,"compact-revision":10472} {"level":"warn","ts":"2026-06-23T03:26:10.579558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.009821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T03:26:10.579639Z","caller":"traceutil/trace.go:171","msg":"trace[145363923] transaction","detail":"{read_only:false; response_revision:14669; number_of_response:1; }","duration":"259.472601ms","start":"2026-06-23T03:26:10.320155Z","end":"2026-06-23T03:26:10.579628Z","steps":["trace[145363923] 'process raft request' (duration: 146.321577ms)","trace[145363923] 'compare' (duration: 112.642222ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:26:10.58827Z","caller":"traceutil/trace.go:171","msg":"trace[709488925] linearizableReadLoop","detail":"{readStateIndex:15177; appliedIndex:15175; }","duration":"158.691435ms","start":"2026-06-23T03:26:10.429546Z","end":"2026-06-23T03:26:10.588237Z","steps":["trace[709488925] 'read index received' (duration: 36.928973ms)","trace[709488925] 'applied index is now lower than readState.Index' (duration: 121.761122ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:26:10.588308Z","caller":"traceutil/trace.go:171","msg":"trace[1020825405] transaction","detail":"{read_only:false; response_revision:14670; number_of_response:1; }","duration":"164.059904ms","start":"2026-06-23T03:26:10.424206Z","end":"2026-06-23T03:26:10.588266Z","steps":["trace[1020825405] 'process raft request' (duration: 163.824428ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:26:10.588308Z","caller":"traceutil/trace.go:171","msg":"trace[211408688] transaction","detail":"{read_only:false; response_revision:14671; number_of_response:1; }","duration":"155.449976ms","start":"2026-06-23T03:26:10.43283Z","end":"2026-06-23T03:26:10.58828Z","steps":["trace[211408688] 'process raft request' (duration: 155.359574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:10.588488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.92477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5fvhf\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-23T03:26:10.588538Z","caller":"traceutil/trace.go:171","msg":"trace[1337632676] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5fvhf; range_end:; response_count:1; response_revision:14671; }","duration":"159.020213ms","start":"2026-06-23T03:26:10.429493Z","end":"2026-06-23T03:26:10.588513Z","steps":["trace[1337632676] 'agreement among raft nodes before linearized reading' (duration: 158.87174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:26.303604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.713001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T03:26:26.303709Z","caller":"traceutil/trace.go:171","msg":"trace[407708316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14762; }","duration":"157.885885ms","start":"2026-06-23T03:26:26.145801Z","end":"2026-06-23T03:26:26.303687Z","steps":["trace[407708316] 'range keys from in-memory index tree' (duration: 157.635619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:26.303879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.333733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-06-23T03:26:26.303959Z","caller":"traceutil/trace.go:171","msg":"trace[175224596] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:14762; }","duration":"138.459145ms","start":"2026-06-23T03:26:26.16548Z","end":"2026-06-23T03:26:26.303939Z","steps":["trace[175224596] 'range keys from in-memory index tree' (duration: 138.169899ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:26:26.955642Z","caller":"traceutil/trace.go:171","msg":"trace[1221772739] transaction","detail":"{read_only:false; response_revision:14766; number_of_response:1; }","duration":"248.428864ms","start":"2026-06-23T03:26:26.707189Z","end":"2026-06-23T03:26:26.955618Z","steps":["trace[1221772739] 'process raft request' (duration: 248.294651ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:26:27.039103Z","caller":"traceutil/trace.go:171","msg":"trace[1502420171] transaction","detail":"{read_only:false; response_revision:14767; number_of_response:1; }","duration":"326.675355ms","start":"2026-06-23T03:26:26.712393Z","end":"2026-06-23T03:26:27.039068Z","steps":["trace[1502420171] 'process raft request' (duration: 314.029568ms)","trace[1502420171] 'compare' (duration: 12.40483ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:26:27.039363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:26:26.712376Z","time spent":"326.813408ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T03:26:27.039488Z","caller":"traceutil/trace.go:171","msg":"trace[493865688] linearizableReadLoop","detail":"{readStateIndex:15280; appliedIndex:15278; }","duration":"319.292346ms","start":"2026-06-23T03:26:26.720165Z","end":"2026-06-23T03:26:27.039457Z","steps":["trace[493865688] 'read index received' (duration: 235.414609ms)","trace[493865688] 'applied index is now lower than readState.Index' (duration: 83.875147ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:26:27.039556Z","caller":"traceutil/trace.go:171","msg":"trace[883713067] transaction","detail":"{read_only:false; response_revision:14768; number_of_response:1; }","duration":"293.80186ms","start":"2026-06-23T03:26:26.745711Z","end":"2026-06-23T03:26:27.039513Z","steps":["trace[883713067] 'process raft request' (duration: 293.294387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:27.039754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.571892ms","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-06-23T03:26:27.039827Z","caller":"traceutil/trace.go:171","msg":"trace[1771116844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14768; }","duration":"319.690195ms","start":"2026-06-23T03:26:26.720122Z","end":"2026-06-23T03:26:27.039812Z","steps":["trace[1771116844] 'agreement among raft nodes before linearized reading' (duration: 319.45527ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:27.039819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.247821ms","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":"warn","ts":"2026-06-23T03:26:27.039869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:26:26.720105Z","time spent":"319.756296ms","remote":"127.0.0.1:50670","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":"info","ts":"2026-06-23T03:26:27.039872Z","caller":"traceutil/trace.go:171","msg":"trace[846476793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14768; }","duration":"318.373674ms","start":"2026-06-23T03:26:26.721484Z","end":"2026-06-23T03:26:27.039858Z","steps":["trace[846476793] 'agreement among raft nodes before linearized reading' (duration: 318.156949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:27.03985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.848012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5fvhf\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-06-23T03:26:27.03991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:26:26.721453Z","time spent":"318.447895ms","remote":"127.0.0.1:50670","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":"info","ts":"2026-06-23T03:26:27.039942Z","caller":"traceutil/trace.go:171","msg":"trace[1464673491] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5fvhf; range_end:; response_count:1; response_revision:14768; }","duration":"291.997276ms","start":"2026-06-23T03:26:26.747926Z","end":"2026-06-23T03:26:27.039924Z","steps":["trace[1464673491] 'agreement among raft nodes before linearized reading' (duration: 291.73483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:27.039973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.8797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-06-23T03:26:27.039976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.002458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-5hs7g\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-06-23T03:26:27.040043Z","caller":"traceutil/trace.go:171","msg":"trace[692393512] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:14768; }","duration":"187.948211ms","start":"2026-06-23T03:26:26.852076Z","end":"2026-06-23T03:26:27.040024Z","steps":["trace[692393512] 'agreement among raft nodes before linearized reading' (duration: 187.744456ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:26:27.040061Z","caller":"traceutil/trace.go:171","msg":"trace[859670193] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-5hs7g; range_end:; response_count:1; response_revision:14768; }","duration":"273.147402ms","start":"2026-06-23T03:26:26.766892Z","end":"2026-06-23T03:26:27.040039Z","steps":["trace[859670193] 'agreement among raft nodes before linearized reading' (duration: 272.844204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:48.942307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.226888ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T03:26:48.94249Z","caller":"traceutil/trace.go:171","msg":"trace[605245191] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15075; }","duration":"147.435172ms","start":"2026-06-23T03:26:48.795028Z","end":"2026-06-23T03:26:48.942463Z","steps":["trace[605245191] 'range keys from in-memory index tree' (duration: 147.200136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:26:48.942323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.529271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-06-23T03:26:48.942525Z","caller":"traceutil/trace.go:171","msg":"trace[2060784661] transaction","detail":"{read_only:false; response_revision:15076; number_of_response:1; }","duration":"227.386844ms","start":"2026-06-23T03:26:48.715106Z","end":"2026-06-23T03:26:48.942492Z","steps":["trace[2060784661] 'process raft request' (duration: 217.2592ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:26:48.942569Z","caller":"traceutil/trace.go:171","msg":"trace[274589536] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15075; }","duration":"266.804587ms","start":"2026-06-23T03:26:48.675729Z","end":"2026-06-23T03:26:48.942534Z","steps":["trace[274589536] 'range keys from in-memory index tree' (duration: 266.323175ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:28:14.838517Z","caller":"traceutil/trace.go:171","msg":"trace[1538983889] transaction","detail":"{read_only:false; response_revision:15886; number_of_response:1; }","duration":"113.529893ms","start":"2026-06-23T03:28:14.724957Z","end":"2026-06-23T03:28:14.838487Z","steps":["trace[1538983889] 'process raft request' (duration: 113.361268ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:28:15.329559Z","caller":"traceutil/trace.go:171","msg":"trace[490151056] transaction","detail":"{read_only:false; response_revision:15888; number_of_response:1; }","duration":"138.250692ms","start":"2026-06-23T03:28:15.191287Z","end":"2026-06-23T03:28:15.329538Z","steps":["trace[490151056] 'process raft request' (duration: 138.102298ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:28:15.329963Z","caller":"traceutil/trace.go:171","msg":"trace[1530174543] linearizableReadLoop","detail":"{readStateIndex:16435; appliedIndex:16435; }","duration":"111.603807ms","start":"2026-06-23T03:28:15.21835Z","end":"2026-06-23T03:28:15.329954Z","steps":["trace[1530174543] 'read index received' (duration: 111.597997ms)","trace[1530174543] 'applied index is now lower than readState.Index' (duration: 4.7µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:28:15.330098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.499205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T03:28:15.330147Z","caller":"traceutil/trace.go:171","msg":"trace[677135633] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15888; }","duration":"111.609407ms","start":"2026-06-23T03:28:15.218513Z","end":"2026-06-23T03:28:15.330122Z","steps":["trace[677135633] 'agreement among raft nodes before linearized reading' (duration: 111.515975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:28:15.330381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.040578ms","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-06-23T03:28:15.330403Z","caller":"traceutil/trace.go:171","msg":"trace[702571455] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15888; }","duration":"112.065208ms","start":"2026-06-23T03:28:15.218333Z","end":"2026-06-23T03:28:15.330398Z","steps":["trace[702571455] 'agreement among raft nodes before linearized reading' (duration: 111.970736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:28:15.33072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.959455ms","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-06-23T03:28:15.330832Z","caller":"traceutil/trace.go:171","msg":"trace[508883279] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15888; }","duration":"112.125769ms","start":"2026-06-23T03:28:15.218683Z","end":"2026-06-23T03:28:15.330808Z","steps":["trace[508883279] 'agreement among raft nodes before linearized reading' (duration: 111.847023ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:28:15.842009Z","caller":"traceutil/trace.go:171","msg":"trace[377453764] linearizableReadLoop","detail":"{readStateIndex:16440; appliedIndex:16439; }","duration":"120.994214ms","start":"2026-06-23T03:28:15.720994Z","end":"2026-06-23T03:28:15.841988Z","steps":["trace[377453764] 'read index received' (duration: 92.396903ms)","trace[377453764] 'applied index is now lower than readState.Index' (duration: 28.596311ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:28:15.842069Z","caller":"traceutil/trace.go:171","msg":"trace[846003719] transaction","detail":"{read_only:false; response_revision:15893; number_of_response:1; }","duration":"209.056942ms","start":"2026-06-23T03:28:15.632958Z","end":"2026-06-23T03:28:15.842015Z","steps":["trace[846003719] 'process raft request' (duration: 180.497601ms)","trace[846003719] 'compare' (duration: 28.335246ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T03:28:15.842192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.177309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-23T03:28:15.842269Z","caller":"traceutil/trace.go:171","msg":"trace[1787480906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15893; }","duration":"121.292342ms","start":"2026-06-23T03:28:15.72096Z","end":"2026-06-23T03:28:15.842252Z","steps":["trace[1787480906] 'agreement among raft nodes before linearized reading' (duration: 121.093477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:28:15.842365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.280681ms","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-06-23T03:28:15.842486Z","caller":"traceutil/trace.go:171","msg":"trace[1544983633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15893; }","duration":"121.458966ms","start":"2026-06-23T03:28:15.721001Z","end":"2026-06-23T03:28:15.84246Z","steps":["trace[1544983633] 'agreement among raft nodes before linearized reading' (duration: 121.128228ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:29:51.929294Z","caller":"traceutil/trace.go:171","msg":"trace[945734257] transaction","detail":"{read_only:false; response_revision:16511; number_of_response:1; }","duration":"168.540823ms","start":"2026-06-23T03:29:51.760729Z","end":"2026-06-23T03:29:51.92927Z","steps":["trace[945734257] 'process raft request' (duration: 168.384719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:29:51.929777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.538196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-23T03:29:51.929818Z","caller":"traceutil/trace.go:171","msg":"trace[145089218] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:16511; }","duration":"157.621148ms","start":"2026-06-23T03:29:51.772186Z","end":"2026-06-23T03:29:51.929808Z","steps":["trace[145089218] 'agreement among raft nodes before linearized reading' (duration: 157.540576ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:29:51.929657Z","caller":"traceutil/trace.go:171","msg":"trace[1610520226] linearizableReadLoop","detail":"{readStateIndex:17079; appliedIndex:17079; }","duration":"157.368812ms","start":"2026-06-23T03:29:51.772222Z","end":"2026-06-23T03:29:51.92959Z","steps":["trace[1610520226] 'read index received' (duration: 157.335441ms)","trace[1610520226] 'applied index is now lower than readState.Index' (duration: 30.451µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:29:51.933528Z","caller":"traceutil/trace.go:171","msg":"trace[1942046353] transaction","detail":"{read_only:false; response_revision:16513; number_of_response:1; }","duration":"107.526238ms","start":"2026-06-23T03:29:51.825984Z","end":"2026-06-23T03:29:51.933511Z","steps":["trace[1942046353] 'process raft request' (duration: 107.458217ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:29:51.933591Z","caller":"traceutil/trace.go:171","msg":"trace[1971917152] transaction","detail":"{read_only:false; response_revision:16512; number_of_response:1; }","duration":"131.330854ms","start":"2026-06-23T03:29:51.802251Z","end":"2026-06-23T03:29:51.933582Z","steps":["trace[1971917152] 'process raft request' (duration: 131.090718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:29:51.934311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.531591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-23T03:29:51.934388Z","caller":"traceutil/trace.go:171","msg":"trace[569394714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:16513; }","duration":"151.761597ms","start":"2026-06-23T03:29:51.782607Z","end":"2026-06-23T03:29:51.934368Z","steps":["trace[569394714] 'agreement among raft nodes before linearized reading' (duration: 151.06609ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:31:03.106565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14634} {"level":"info","ts":"2026-06-23T03:31:03.181381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14634,"took":"71.769193ms","hash":28616526,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":31461376,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-23T03:31:03.181437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":28616526,"revision":14634,"compact-revision":12601} {"level":"info","ts":"2026-06-23T03:36:03.114296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17082} {"level":"info","ts":"2026-06-23T03:36:03.207016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17082,"took":"90.469457ms","hash":3993373030,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":29163520,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-23T03:36:03.207066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3993373030,"revision":17082,"compact-revision":14634} {"level":"info","ts":"2026-06-23T03:36:50.553322Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-23T03:36:50.558417Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-23T03:36:50.558582Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-06-23T03:40:04.829101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.420216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:1 size:937"} {"level":"warn","ts":"2026-06-23T03:40:04.829342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.601862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-dns\" ","response":"range_response_count:1 size:675"} {"level":"info","ts":"2026-06-23T03:40:04.829443Z","caller":"traceutil/trace.go:171","msg":"trace[357474570] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-dns; range_end:; response_count:1; response_revision:20330; }","duration":"511.727305ms","start":"2026-06-23T03:40:04.317679Z","end":"2026-06-23T03:40:04.829406Z","steps":["trace[357474570] 'range keys from in-memory index tree' (duration: 511.49183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:40:04.829504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:40:04.317655Z","time spent":"511.832447ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":698,"request content":"key:\"/registry/clusterrolebindings/system:kube-dns\" "} {"level":"info","ts":"2026-06-23T03:40:04.829454Z","caller":"traceutil/trace.go:171","msg":"trace[433831948] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:1; response_revision:20330; }","duration":"525.937439ms","start":"2026-06-23T03:40:04.303447Z","end":"2026-06-23T03:40:04.829385Z","steps":["trace[433831948] 'range keys from in-memory index tree' (duration: 525.225442ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:40:04.829552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.133422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-rbmzn\" ","response":"range_response_count:1 size:18647"} {"level":"info","ts":"2026-06-23T03:40:04.829604Z","caller":"traceutil/trace.go:171","msg":"trace[396260473] linearizableReadLoop","detail":"{readStateIndex:21041; appliedIndex:21040; }","duration":"296.557816ms","start":"2026-06-23T03:40:04.533009Z","end":"2026-06-23T03:40:04.829567Z","steps":["trace[396260473] 'read index received' (duration: 296.110906ms)","trace[396260473] 'applied index is now lower than readState.Index' (duration: 445.61µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T03:40:04.82967Z","caller":"traceutil/trace.go:171","msg":"trace[53154380] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-rbmzn; range_end:; response_count:1; response_revision:20330; }","duration":"444.293656ms","start":"2026-06-23T03:40:04.38535Z","end":"2026-06-23T03:40:04.829644Z","steps":["trace[53154380] 'range keys from in-memory index tree' (duration: 443.929517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:40:04.829734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:40:04.385332Z","time spent":"444.390108ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18670,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-rbmzn\" "} {"level":"warn","ts":"2026-06-23T03:40:04.829696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:40:04.303431Z","time spent":"526.187454ms","remote":"127.0.0.1:50750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":1,"response size":960,"request content":"key:\"/registry/clusterroles/vector\" "} {"level":"warn","ts":"2026-06-23T03:40:04.829799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.782521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-23T03:40:04.829939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.462595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-f6dgd\" ","response":"range_response_count:1 size:18672"} {"level":"info","ts":"2026-06-23T03:40:04.829955Z","caller":"traceutil/trace.go:171","msg":"trace[2114917885] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:20331; }","duration":"296.938575ms","start":"2026-06-23T03:40:04.532994Z","end":"2026-06-23T03:40:04.829933Z","steps":["trace[2114917885] 'agreement among raft nodes before linearized reading' (duration: 296.72226ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:40:04.829985Z","caller":"traceutil/trace.go:171","msg":"trace[119177681] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-f6dgd; range_end:; response_count:1; response_revision:20331; }","duration":"229.543357ms","start":"2026-06-23T03:40:04.600427Z","end":"2026-06-23T03:40:04.82997Z","steps":["trace[119177681] 'agreement among raft nodes before linearized reading' (duration: 229.411294ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T03:40:04.829772Z","caller":"traceutil/trace.go:171","msg":"trace[434436407] transaction","detail":"{read_only:false; response_revision:20331; number_of_response:1; }","duration":"305.192784ms","start":"2026-06-23T03:40:04.524538Z","end":"2026-06-23T03:40:04.829731Z","steps":["trace[434436407] 'process raft request' (duration: 304.671772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:40:04.830266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:40:04.524513Z","time spent":"305.576174ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T03:40:04.830621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.599413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-23T03:40:04.830748Z","caller":"traceutil/trace.go:171","msg":"trace[1571397569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20330; }","duration":"384.928521ms","start":"2026-06-23T03:40:04.445798Z","end":"2026-06-23T03:40:04.830727Z","steps":["trace[1571397569] 'range keys from in-memory index tree' (duration: 383.20553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T03:40:04.830922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T03:40:04.445779Z","time spent":"385.128445ms","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-23T03:41:03.123023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19087} {"level":"info","ts":"2026-06-23T03:41:03.212443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19087,"took":"85.360343ms","hash":2740228098,"current-db-size-bytes":58638336,"current-db-size":"59 MB","current-db-size-in-use-bytes":24014848,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-23T03:41:03.212543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2740228098,"revision":19087,"compact-revision":17082}