{"level":"warn","ts":"2026-09-01T05:17:03.607289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T05:17:03.607431Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.176:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.176:2380","--initial-cluster=instance=https://162.253.55.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.176:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-01T05:17:03.607675Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T05:17:03.607703Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-01T05:17:03.607734Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T05:17:03.608918Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"]} {"level":"info","ts":"2026-09-01T05:17:03.609077Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.176:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-01T05:17:03.614262Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.853106ms"} {"level":"info","ts":"2026-09-01T05:17:03.623493Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"590b810b00f00655","cluster-id":"dcc6923d0deac545"} {"level":"info","ts":"2026-09-01T05:17:03.623564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T05:17:03.623581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 0"} {"level":"info","ts":"2026-09-01T05:17:03.623593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 590b810b00f00655 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T05:17:03.623599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 1"} {"level":"info","ts":"2026-09-01T05:17:03.623632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"warn","ts":"2026-09-01T05:17:03.63083Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T05:17:03.63257Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T05:17:03.634087Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-01T05:17:03.636227Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"590b810b00f00655","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T05:17:03.636457Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"590b810b00f00655","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T05:17:03.636578Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T05:17:03.636738Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T05:17:03.636777Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T05:17:03.63806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"info","ts":"2026-09-01T05:17:03.638502Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","added-peer-id":"590b810b00f00655","added-peer-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-01T05:17:03.640727Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T05:17:03.640782Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-01T05:17:03.640827Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-01T05:17:03.641072Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"590b810b00f00655","initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T05:17:03.641133Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T05:17:04.424291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T05:17:04.424406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T05:17:04.424447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgPreVoteResp from 590b810b00f00655 at term 1"} {"level":"info","ts":"2026-09-01T05:17:04.424465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became candidate at term 2"} {"level":"info","ts":"2026-09-01T05:17:04.424475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgVoteResp from 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-01T05:17:04.424489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became leader at term 2"} {"level":"info","ts":"2026-09-01T05:17:04.424503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 590b810b00f00655 elected leader 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-01T05:17:04.425885Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"590b810b00f00655","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.176:2379]}","request-path":"/0/members/590b810b00f00655/attributes","cluster-id":"dcc6923d0deac545","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T05:17:04.426002Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:17:04.426217Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T05:17:04.426605Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T05:17:04.426756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T05:17:04.42677Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T05:17:04.427413Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:17:04.427578Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:17:04.427624Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T05:17:04.42805Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T05:17:04.431652Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.176:2379"} {"level":"info","ts":"2026-09-01T05:17:31.893046Z","caller":"traceutil/trace.go:171","msg":"trace[331774277] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"142.569249ms","start":"2026-09-01T05:17:31.75044Z","end":"2026-09-01T05:17:31.893009Z","steps":["trace[331774277] 'process raft request' (duration: 142.376183ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:17:48.901864Z","caller":"traceutil/trace.go:171","msg":"trace[350453669] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"148.772344ms","start":"2026-09-01T05:17:48.753022Z","end":"2026-09-01T05:17:48.901794Z","steps":["trace[350453669] 'read index received' (duration: 148.569238ms)","trace[350453669] 'applied index is now lower than readState.Index' (duration: 201.586µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:17:48.901937Z","caller":"traceutil/trace.go:171","msg":"trace[1967390456] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"166.977524ms","start":"2026-09-01T05:17:48.734937Z","end":"2026-09-01T05:17:48.901914Z","steps":["trace[1967390456] 'process raft request' (duration: 166.731106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:17:48.902054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.99204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:17:48.90215Z","caller":"traceutil/trace.go:171","msg":"trace[957763892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"149.157245ms","start":"2026-09-01T05:17:48.752977Z","end":"2026-09-01T05:17:48.902134Z","steps":["trace[957763892] 'agreement among raft nodes before linearized reading' (duration: 148.97357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:17:50.073501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.396392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:17:50.0736Z","caller":"traceutil/trace.go:171","msg":"trace[1022745626] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"470.564518ms","start":"2026-09-01T05:17:49.603019Z","end":"2026-09-01T05:17:50.073584Z","steps":["trace[1022745626] 'read index received' (duration: 200.507757ms)","trace[1022745626] 'applied index is now lower than readState.Index' (duration: 270.055341ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:17:50.073723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.815186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:17:50.073754Z","caller":"traceutil/trace.go:171","msg":"trace[341098528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"321.885858ms","start":"2026-09-01T05:17:49.751859Z","end":"2026-09-01T05:17:50.073745Z","steps":["trace[341098528] 'agreement among raft nodes before linearized reading' (duration: 321.806595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:17:50.073802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.759755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-79dwl\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-09-01T05:17:50.073785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:17:49.751841Z","time spent":"321.93751ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T05:17:50.073875Z","caller":"traceutil/trace.go:171","msg":"trace[506017320] range","detail":"{range_begin:/registry/pods/kube-system/cilium-79dwl; range_end:; response_count:1; response_revision:638; }","duration":"470.882828ms","start":"2026-09-01T05:17:49.602977Z","end":"2026-09-01T05:17:50.07386Z","steps":["trace[506017320] 'agreement among raft nodes before linearized reading' (duration: 470.673202ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:17:50.073867Z","caller":"traceutil/trace.go:171","msg":"trace[639106224] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"550.220642ms","start":"2026-09-01T05:17:49.52339Z","end":"2026-09-01T05:17:50.07361Z","steps":["trace[639106224] 'process raft request' (duration: 280.246223ms)","trace[639106224] 'compare' (duration: 269.253607ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:17:50.073898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:17:49.602958Z","time spent":"470.935499ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-79dwl\" "} {"level":"warn","ts":"2026-09-01T05:17:50.073962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:17:49.52336Z","time spent":"550.563252ms","remote":"127.0.0.1:52638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T05:18:12.948538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.028665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:18:12.94867Z","caller":"traceutil/trace.go:171","msg":"trace[641837772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"195.22135ms","start":"2026-09-01T05:18:12.753425Z","end":"2026-09-01T05:18:12.948646Z","steps":["trace[641837772] 'range keys from in-memory index tree' (duration: 194.912431ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:18:12.94892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.739941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6z4dw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-01T05:18:12.949085Z","caller":"traceutil/trace.go:171","msg":"trace[718027810] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6z4dw; range_end:; response_count:1; response_revision:825; }","duration":"213.007658ms","start":"2026-09-01T05:18:12.736048Z","end":"2026-09-01T05:18:12.949056Z","steps":["trace[718027810] 'range keys from in-memory index tree' (duration: 212.351149ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:18:42.24168Z","caller":"traceutil/trace.go:171","msg":"trace[856361480] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"249.0981ms","start":"2026-09-01T05:18:41.992562Z","end":"2026-09-01T05:18:42.24166Z","steps":["trace[856361480] 'process raft request' (duration: 248.974096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:19:18.647446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.111626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-01T05:19:18.647536Z","caller":"traceutil/trace.go:171","msg":"trace[465263957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1727; }","duration":"109.27685ms","start":"2026-09-01T05:19:18.53824Z","end":"2026-09-01T05:19:18.647517Z","steps":["trace[465263957] 'range keys from in-memory index tree' (duration: 108.890409ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:19:18.64757Z","caller":"traceutil/trace.go:171","msg":"trace[262706205] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"103.063706ms","start":"2026-09-01T05:19:18.54448Z","end":"2026-09-01T05:19:18.647544Z","steps":["trace[262706205] 'process raft request' (duration: 49.927395ms)","trace[262706205] 'compare' (duration: 52.821982ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:19:32.147446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.420703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:19:32.147562Z","caller":"traceutil/trace.go:171","msg":"trace[141288442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1909; }","duration":"393.555147ms","start":"2026-09-01T05:19:31.753984Z","end":"2026-09-01T05:19:32.147539Z","steps":["trace[141288442] 'range keys from in-memory index tree' (duration: 393.327881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:19:32.14822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:19:31.753923Z","time spent":"394.266039ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T05:19:32.147826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.506247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T05:19:32.148459Z","caller":"traceutil/trace.go:171","msg":"trace[284168075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1909; }","duration":"310.204748ms","start":"2026-09-01T05:19:31.838237Z","end":"2026-09-01T05:19:32.148442Z","steps":["trace[284168075] 'range keys from in-memory index tree' (duration: 309.365613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:19:32.148493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:19:31.838216Z","time spent":"310.267799ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T05:19:32.147925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.71795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T05:19:32.148602Z","caller":"traceutil/trace.go:171","msg":"trace[428384885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1909; }","duration":"204.447752ms","start":"2026-09-01T05:19:31.944146Z","end":"2026-09-01T05:19:32.148593Z","steps":["trace[428384885] 'range keys from in-memory index tree' (duration: 203.535845ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:19:48.52218Z","caller":"traceutil/trace.go:171","msg":"trace[1727836092] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"160.13103ms","start":"2026-09-01T05:19:48.362028Z","end":"2026-09-01T05:19:48.522159Z","steps":["trace[1727836092] 'process raft request' (duration: 159.385668ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:20:20.770582Z","caller":"traceutil/trace.go:171","msg":"trace[171517319] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"108.85896ms","start":"2026-09-01T05:20:20.661701Z","end":"2026-09-01T05:20:20.77056Z","steps":["trace[171517319] 'process raft request' (duration: 108.702285ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:20:20.777861Z","caller":"traceutil/trace.go:171","msg":"trace[919124616] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"114.91729ms","start":"2026-09-01T05:20:20.662887Z","end":"2026-09-01T05:20:20.777804Z","steps":["trace[919124616] 'process raft request' (duration: 114.754965ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:20:26.629561Z","caller":"traceutil/trace.go:171","msg":"trace[1607946654] linearizableReadLoop","detail":"{readStateIndex:2341; appliedIndex:2340; }","duration":"105.751817ms","start":"2026-09-01T05:20:26.52378Z","end":"2026-09-01T05:20:26.629532Z","steps":["trace[1607946654] 'read index received' (duration: 105.52775ms)","trace[1607946654] 'applied index is now lower than readState.Index' (duration: 222.987µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:20:26.629679Z","caller":"traceutil/trace.go:171","msg":"trace[788607326] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"219.260965ms","start":"2026-09-01T05:20:26.410396Z","end":"2026-09-01T05:20:26.629657Z","steps":["trace[788607326] 'process raft request' (duration: 218.985837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:20:26.630223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.405026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T05:20:26.63039Z","caller":"traceutil/trace.go:171","msg":"trace[1172435027] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2255; }","duration":"106.632583ms","start":"2026-09-01T05:20:26.52372Z","end":"2026-09-01T05:20:26.630353Z","steps":["trace[1172435027] 'agreement among raft nodes before linearized reading' (duration: 105.995023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:20:27.030638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.51294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:20:27.030839Z","caller":"traceutil/trace.go:171","msg":"trace[2126973067] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"239.690945ms","start":"2026-09-01T05:20:26.791133Z","end":"2026-09-01T05:20:27.030824Z","steps":["trace[2126973067] 'process raft request' (duration: 239.612792ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:20:27.030839Z","caller":"traceutil/trace.go:171","msg":"trace[1372027817] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2341; }","duration":"277.914276ms","start":"2026-09-01T05:20:26.752883Z","end":"2026-09-01T05:20:27.030797Z","steps":["trace[1372027817] 'read index received' (duration: 43.742µs)","trace[1372027817] 'applied index is now lower than readState.Index' (duration: 277.868684ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:20:27.03099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.087201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:20:27.03109Z","caller":"traceutil/trace.go:171","msg":"trace[1668663132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2257; }","duration":"278.228975ms","start":"2026-09-01T05:20:26.752838Z","end":"2026-09-01T05:20:27.031067Z","steps":["trace[1668663132] 'agreement among raft nodes before linearized reading' (duration: 278.07463ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:20:27.030802Z","caller":"traceutil/trace.go:171","msg":"trace[751885365] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"397.017221ms","start":"2026-09-01T05:20:26.633743Z","end":"2026-09-01T05:20:27.03076Z","steps":["trace[751885365] 'process raft request' (duration: 93.119999ms)","trace[751885365] 'compare' (duration: 303.324165ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:20:27.031531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:20:26.633721Z","time spent":"397.729182ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:20:27.039724Z","caller":"traceutil/trace.go:171","msg":"trace[1257620036] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"241.169649ms","start":"2026-09-01T05:20:26.79853Z","end":"2026-09-01T05:20:27.0397Z","steps":["trace[1257620036] 'process raft request' (duration: 241.013394ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:20:27.040075Z","caller":"traceutil/trace.go:171","msg":"trace[1776634393] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"240.897881ms","start":"2026-09-01T05:20:26.799154Z","end":"2026-09-01T05:20:27.040052Z","steps":["trace[1776634393] 'process raft request' (duration: 240.504349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:20:27.040203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.310134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T05:20:27.040286Z","caller":"traceutil/trace.go:171","msg":"trace[1796385020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2259; }","duration":"231.440199ms","start":"2026-09-01T05:20:26.808831Z","end":"2026-09-01T05:20:27.040271Z","steps":["trace[1796385020] 'agreement among raft nodes before linearized reading' (duration: 231.215112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:24:16.086723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.138374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:24:16.086815Z","caller":"traceutil/trace.go:171","msg":"trace[708794232] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:3827; }","duration":"107.268118ms","start":"2026-09-01T05:24:15.979529Z","end":"2026-09-01T05:24:16.086797Z","steps":["trace[708794232] 'range keys from in-memory index tree' (duration: 107.030201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:24:16.08682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.169145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:24:16.086886Z","caller":"traceutil/trace.go:171","msg":"trace[713596310] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:3827; }","duration":"106.259708ms","start":"2026-09-01T05:24:15.98061Z","end":"2026-09-01T05:24:16.086869Z","steps":["trace[713596310] 'range keys from in-memory index tree' (duration: 106.140595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:24:16.087022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.400852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:24:16.087089Z","caller":"traceutil/trace.go:171","msg":"trace[915158581] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:3827; }","duration":"107.488605ms","start":"2026-09-01T05:24:15.979586Z","end":"2026-09-01T05:24:16.087075Z","steps":["trace[915158581] 'count revisions from in-memory index tree' (duration: 107.371211ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:24:16.087158Z","caller":"traceutil/trace.go:171","msg":"trace[1678228268] transaction","detail":"{read_only:false; response_revision:3828; number_of_response:1; }","duration":"103.377563ms","start":"2026-09-01T05:24:15.983771Z","end":"2026-09-01T05:24:16.087148Z","steps":["trace[1678228268] 'process raft request' (duration: 11.180181ms)","trace[1678228268] 'compare' (duration: 92.063178ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:24:16.087525Z","caller":"traceutil/trace.go:171","msg":"trace[557646036] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"103.233728ms","start":"2026-09-01T05:24:15.984271Z","end":"2026-09-01T05:24:16.087505Z","steps":["trace[557646036] 'process raft request' (duration: 102.841807ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:24:16.087706Z","caller":"traceutil/trace.go:171","msg":"trace[2084105103] transaction","detail":"{read_only:false; response_revision:3830; number_of_response:1; }","duration":"103.004232ms","start":"2026-09-01T05:24:15.984688Z","end":"2026-09-01T05:24:16.087692Z","steps":["trace[2084105103] 'process raft request' (duration: 102.698423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:24:51.36727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.47192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:24:51.36736Z","caller":"traceutil/trace.go:171","msg":"trace[1875747476] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4063; }","duration":"143.605444ms","start":"2026-09-01T05:24:51.223738Z","end":"2026-09-01T05:24:51.367344Z","steps":["trace[1875747476] 'count revisions from in-memory index tree' (duration: 143.386138ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:15.093865Z","caller":"traceutil/trace.go:171","msg":"trace[1329708255] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"135.69104ms","start":"2026-09-01T05:26:14.958152Z","end":"2026-09-01T05:26:15.093843Z","steps":["trace[1329708255] 'process raft request' (duration: 135.515914ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:15.104779Z","caller":"traceutil/trace.go:171","msg":"trace[258719905] linearizableReadLoop","detail":"{readStateIndex:5234; appliedIndex:5231; }","duration":"128.439155ms","start":"2026-09-01T05:26:14.976321Z","end":"2026-09-01T05:26:15.10476Z","steps":["trace[258719905] 'read index received' (duration: 117.348936ms)","trace[258719905] 'applied index is now lower than readState.Index' (duration: 11.089279ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:15.104898Z","caller":"traceutil/trace.go:171","msg":"trace[1162757168] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"146.338645ms","start":"2026-09-01T05:26:14.958536Z","end":"2026-09-01T05:26:15.104875Z","steps":["trace[1162757168] 'process raft request' (duration: 146.054057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:15.104981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.647821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:26:15.105021Z","caller":"traceutil/trace.go:171","msg":"trace[918138305] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5044; }","duration":"128.729724ms","start":"2026-09-01T05:26:14.976281Z","end":"2026-09-01T05:26:15.105011Z","steps":["trace[918138305] 'agreement among raft nodes before linearized reading' (duration: 128.659471ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:15.105219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.056446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T05:26:15.105251Z","caller":"traceutil/trace.go:171","msg":"trace[1244269317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5044; }","duration":"124.205839ms","start":"2026-09-01T05:26:14.981039Z","end":"2026-09-01T05:26:15.105245Z","steps":["trace[1244269317] 'agreement among raft nodes before linearized reading' (duration: 124.018834ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:15.105162Z","caller":"traceutil/trace.go:171","msg":"trace[2113089370] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"146.526391ms","start":"2026-09-01T05:26:14.958625Z","end":"2026-09-01T05:26:15.105152Z","steps":["trace[2113089370] 'process raft request' (duration: 146.075427ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:15.245759Z","caller":"traceutil/trace.go:171","msg":"trace[644290211] linearizableReadLoop","detail":"{readStateIndex:5235; appliedIndex:5234; }","duration":"134.080082ms","start":"2026-09-01T05:26:15.111649Z","end":"2026-09-01T05:26:15.245729Z","steps":["trace[644290211] 'read index received' (duration: 85.961737ms)","trace[644290211] 'applied index is now lower than readState.Index' (duration: 48.116945ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:15.245773Z","caller":"traceutil/trace.go:171","msg":"trace[1851013006] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"134.32361ms","start":"2026-09-01T05:26:15.111417Z","end":"2026-09-01T05:26:15.24574Z","steps":["trace[1851013006] 'process raft request' (duration: 86.202834ms)","trace[1851013006] 'compare' (duration: 47.984672ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:26:15.245996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.325889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T05:26:15.24607Z","caller":"traceutil/trace.go:171","msg":"trace[1728041968] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5045; }","duration":"134.483034ms","start":"2026-09-01T05:26:15.111571Z","end":"2026-09-01T05:26:15.246054Z","steps":["trace[1728041968] 'agreement among raft nodes before linearized reading' (duration: 134.266797ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:15.250618Z","caller":"traceutil/trace.go:171","msg":"trace[2097738882] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"136.322308ms","start":"2026-09-01T05:26:15.114279Z","end":"2026-09-01T05:26:15.250601Z","steps":["trace[2097738882] 'process raft request' (duration: 136.163304ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:15.250809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.244264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-09-01T05:26:15.250846Z","caller":"traceutil/trace.go:171","msg":"trace[257171487] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5046; }","duration":"115.311566ms","start":"2026-09-01T05:26:15.135525Z","end":"2026-09-01T05:26:15.250837Z","steps":["trace[257171487] 'agreement among raft nodes before linearized reading' (duration: 115.208393ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:19.61133Z","caller":"traceutil/trace.go:171","msg":"trace[673798509] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"173.393517ms","start":"2026-09-01T05:26:19.437912Z","end":"2026-09-01T05:26:19.611306Z","steps":["trace[673798509] 'process raft request' (duration: 173.225122ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:19.810109Z","caller":"traceutil/trace.go:171","msg":"trace[1617771087] linearizableReadLoop","detail":"{readStateIndex:5434; appliedIndex:5433; }","duration":"187.214666ms","start":"2026-09-01T05:26:19.622872Z","end":"2026-09-01T05:26:19.810086Z","steps":["trace[1617771087] 'read index received' (duration: 163.540024ms)","trace[1617771087] 'applied index is now lower than readState.Index' (duration: 23.673412ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:19.810147Z","caller":"traceutil/trace.go:171","msg":"trace[1456970613] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"191.943227ms","start":"2026-09-01T05:26:19.61817Z","end":"2026-09-01T05:26:19.810113Z","steps":["trace[1456970613] 'process raft request' (duration: 168.245375ms)","trace[1456970613] 'compare' (duration: 23.560877ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:26:19.810409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.600544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-01T05:26:19.81048Z","caller":"traceutil/trace.go:171","msg":"trace[1047089115] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5237; }","duration":"114.705298ms","start":"2026-09-01T05:26:19.695741Z","end":"2026-09-01T05:26:19.810446Z","steps":["trace[1047089115] 'agreement among raft nodes before linearized reading' (duration: 114.523353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:19.81055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.650649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14424"} {"level":"info","ts":"2026-09-01T05:26:19.81063Z","caller":"traceutil/trace.go:171","msg":"trace[1885071752] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5237; }","duration":"187.779973ms","start":"2026-09-01T05:26:19.622828Z","end":"2026-09-01T05:26:19.810608Z","steps":["trace[1885071752] 'agreement among raft nodes before linearized reading' (duration: 187.419563ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:21.607353Z","caller":"traceutil/trace.go:171","msg":"trace[1637118748] linearizableReadLoop","detail":"{readStateIndex:5476; appliedIndex:5475; }","duration":"188.35629ms","start":"2026-09-01T05:26:21.418965Z","end":"2026-09-01T05:26:21.607321Z","steps":["trace[1637118748] 'read index received' (duration: 127.710083ms)","trace[1637118748] 'applied index is now lower than readState.Index' (duration: 60.644817ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:26:21.607508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.515414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:26:21.607556Z","caller":"traceutil/trace.go:171","msg":"trace[150994153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5278; }","duration":"188.604597ms","start":"2026-09-01T05:26:21.418936Z","end":"2026-09-01T05:26:21.607541Z","steps":["trace[150994153] 'agreement among raft nodes before linearized reading' (duration: 188.502614ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:21.607575Z","caller":"traceutil/trace.go:171","msg":"trace[849499354] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"223.063748ms","start":"2026-09-01T05:26:21.384465Z","end":"2026-09-01T05:26:21.607529Z","steps":["trace[849499354] 'process raft request' (duration: 162.255737ms)","trace[849499354] 'compare' (duration: 60.406379ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:29.393539Z","caller":"traceutil/trace.go:171","msg":"trace[173680918] linearizableReadLoop","detail":"{readStateIndex:5636; appliedIndex:5635; }","duration":"134.399371ms","start":"2026-09-01T05:26:29.259118Z","end":"2026-09-01T05:26:29.393517Z","steps":["trace[173680918] 'read index received' (duration: 46.952231ms)","trace[173680918] 'applied index is now lower than readState.Index' (duration: 87.44631ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:29.39364Z","caller":"traceutil/trace.go:171","msg":"trace[885132629] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"149.500889ms","start":"2026-09-01T05:26:29.244126Z","end":"2026-09-01T05:26:29.393627Z","steps":["trace[885132629] 'process raft request' (duration: 62.007978ms)","trace[885132629] 'compare' (duration: 87.274495ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:26:29.393794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.648968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16086"} {"level":"info","ts":"2026-09-01T05:26:29.394078Z","caller":"traceutil/trace.go:171","msg":"trace[1224586360] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5431; }","duration":"134.970788ms","start":"2026-09-01T05:26:29.259086Z","end":"2026-09-01T05:26:29.394056Z","steps":["trace[1224586360] 'agreement among raft nodes before linearized reading' (duration: 134.545926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:34.902011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.785631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:26:34.902242Z","caller":"traceutil/trace.go:171","msg":"trace[508655862] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"250.939204ms","start":"2026-09-01T05:26:34.65129Z","end":"2026-09-01T05:26:34.902229Z","steps":["trace[508655862] 'process raft request' (duration: 250.916043ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:34.902367Z","caller":"traceutil/trace.go:171","msg":"trace[605061274] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"305.82555ms","start":"2026-09-01T05:26:34.596538Z","end":"2026-09-01T05:26:34.902363Z","steps":["trace[605061274] 'process raft request' (duration: 305.589503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:34.902404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:26:34.596516Z","time spent":"305.861061ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:26:34.902611Z","caller":"traceutil/trace.go:171","msg":"trace[1870856427] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"345.488305ms","start":"2026-09-01T05:26:34.557117Z","end":"2026-09-01T05:26:34.902605Z","steps":["trace[1870856427] 'process raft request' (duration: 344.95949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:34.902637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:26:34.55711Z","time spent":"345.515366ms","remote":"127.0.0.1:52258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":524,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T05:26:34.902479Z","caller":"traceutil/trace.go:171","msg":"trace[957022671] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"346.563037ms","start":"2026-09-01T05:26:34.555893Z","end":"2026-09-01T05:26:34.902456Z","steps":["trace[957022671] 'process raft request' (duration: 180.312252ms)","trace[957022671] 'compare' (duration: 165.39948ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:34.902517Z","caller":"traceutil/trace.go:171","msg":"trace[494657207] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"254.144979ms","start":"2026-09-01T05:26:34.648322Z","end":"2026-09-01T05:26:34.902467Z","steps":["trace[494657207] 'process raft request' (duration: 253.84547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:34.90272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:26:34.555874Z","time spent":"346.787054ms","remote":"127.0.0.1:52406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1005,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T05:26:41.775366Z","caller":"traceutil/trace.go:171","msg":"trace[314541800] linearizableReadLoop","detail":"{readStateIndex:5846; appliedIndex:5844; }","duration":"108.909537ms","start":"2026-09-01T05:26:41.666437Z","end":"2026-09-01T05:26:41.775346Z","steps":["trace[314541800] 'read index received' (duration: 15.170589ms)","trace[314541800] 'applied index is now lower than readState.Index' (duration: 93.737728ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:41.775386Z","caller":"traceutil/trace.go:171","msg":"trace[454010578] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"111.805393ms","start":"2026-09-01T05:26:41.663541Z","end":"2026-09-01T05:26:41.775346Z","steps":["trace[454010578] 'process raft request' (duration: 18.087496ms)","trace[454010578] 'compare' (duration: 93.478339ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:41.775452Z","caller":"traceutil/trace.go:171","msg":"trace[806677094] transaction","detail":"{read_only:false; response_revision:5638; number_of_response:1; }","duration":"111.561096ms","start":"2026-09-01T05:26:41.663855Z","end":"2026-09-01T05:26:41.775416Z","steps":["trace[806677094] 'process raft request' (duration: 111.444252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:41.775587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.129843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-01T05:26:41.775625Z","caller":"traceutil/trace.go:171","msg":"trace[576678204] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5638; }","duration":"109.203416ms","start":"2026-09-01T05:26:41.666412Z","end":"2026-09-01T05:26:41.775616Z","steps":["trace[576678204] 'agreement among raft nodes before linearized reading' (duration: 109.039911ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:43.061397Z","caller":"traceutil/trace.go:171","msg":"trace[469531109] transaction","detail":"{read_only:false; response_revision:5680; number_of_response:1; }","duration":"131.250788ms","start":"2026-09-01T05:26:42.930122Z","end":"2026-09-01T05:26:43.061372Z","steps":["trace[469531109] 'process raft request' (duration: 131.033861ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:43.076049Z","caller":"traceutil/trace.go:171","msg":"trace[1757837488] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"139.039009ms","start":"2026-09-01T05:26:42.936987Z","end":"2026-09-01T05:26:43.076026Z","steps":["trace[1757837488] 'process raft request' (duration: 138.859174ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:43.076253Z","caller":"traceutil/trace.go:171","msg":"trace[1232357330] linearizableReadLoop","detail":"{readStateIndex:5891; appliedIndex:5888; }","duration":"129.434375ms","start":"2026-09-01T05:26:42.946721Z","end":"2026-09-01T05:26:43.076156Z","steps":["trace[1232357330] 'read index received' (duration: 114.42985ms)","trace[1232357330] 'applied index is now lower than readState.Index' (duration: 15.002365ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:43.07627Z","caller":"traceutil/trace.go:171","msg":"trace[384607248] transaction","detail":"{read_only:false; response_revision:5682; number_of_response:1; }","duration":"138.905625ms","start":"2026-09-01T05:26:42.937348Z","end":"2026-09-01T05:26:43.076253Z","steps":["trace[384607248] 'process raft request' (duration: 138.633357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:43.076519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.774035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tn4pf\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-09-01T05:26:43.076554Z","caller":"traceutil/trace.go:171","msg":"trace[1890664337] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tn4pf; range_end:; response_count:1; response_revision:5682; }","duration":"129.863537ms","start":"2026-09-01T05:26:42.946678Z","end":"2026-09-01T05:26:43.076541Z","steps":["trace[1890664337] 'agreement among raft nodes before linearized reading' (duration: 129.690772ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:43.361432Z","caller":"traceutil/trace.go:171","msg":"trace[1469482363] transaction","detail":"{read_only:false; response_revision:5684; number_of_response:1; }","duration":"102.101654ms","start":"2026-09-01T05:26:43.259307Z","end":"2026-09-01T05:26:43.361408Z","steps":["trace[1469482363] 'process raft request' (duration: 101.94964ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:43.578547Z","caller":"traceutil/trace.go:171","msg":"trace[1954795840] linearizableReadLoop","detail":"{readStateIndex:5894; appliedIndex:5892; }","duration":"314.528517ms","start":"2026-09-01T05:26:43.263997Z","end":"2026-09-01T05:26:43.578525Z","steps":["trace[1954795840] 'read index received' (duration: 97.346073ms)","trace[1954795840] 'applied index is now lower than readState.Index' (duration: 217.181524ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:26:43.578684Z","caller":"traceutil/trace.go:171","msg":"trace[1857754129] transaction","detail":"{read_only:false; response_revision:5685; number_of_response:1; }","duration":"317.701712ms","start":"2026-09-01T05:26:43.260951Z","end":"2026-09-01T05:26:43.578652Z","steps":["trace[1857754129] 'process raft request' (duration: 317.423504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:43.578825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.805246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-01T05:26:43.578832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.525124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5173c2e7-465e-4923-bf67-0bf4b3fd4e99\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-01T05:26:43.578874Z","caller":"traceutil/trace.go:171","msg":"trace[1685755752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5685; }","duration":"314.913399ms","start":"2026-09-01T05:26:43.263947Z","end":"2026-09-01T05:26:43.57886Z","steps":["trace[1685755752] 'agreement among raft nodes before linearized reading' (duration: 314.697623ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:26:43.578884Z","caller":"traceutil/trace.go:171","msg":"trace[808187442] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5173c2e7-465e-4923-bf67-0bf4b3fd4e99; range_end:; response_count:1; response_revision:5685; }","duration":"138.584066ms","start":"2026-09-01T05:26:43.440286Z","end":"2026-09-01T05:26:43.57887Z","steps":["trace[808187442] 'agreement among raft nodes before linearized reading' (duration: 138.419421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:43.578876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:26:43.260923Z","time spent":"317.875827ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:26:43.578916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:26:43.263935Z","time spent":"314.969491ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-01T05:26:45.734579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.447467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T05:26:45.734647Z","caller":"traceutil/trace.go:171","msg":"trace[1922320633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5715; }","duration":"151.531609ms","start":"2026-09-01T05:26:45.583099Z","end":"2026-09-01T05:26:45.734631Z","steps":["trace[1922320633] 'range keys from in-memory index tree' (duration: 150.56935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:45.734855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.577013ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:26:45.734997Z","caller":"traceutil/trace.go:171","msg":"trace[1247848958] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5715; }","duration":"118.734837ms","start":"2026-09-01T05:26:45.616241Z","end":"2026-09-01T05:26:45.734975Z","steps":["trace[1247848958] 'range keys from in-memory index tree' (duration: 118.558412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:26:45.734287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.270647ms","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-09-01T05:26:45.735116Z","caller":"traceutil/trace.go:171","msg":"trace[984943558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5715; }","duration":"138.146993ms","start":"2026-09-01T05:26:45.596955Z","end":"2026-09-01T05:26:45.735102Z","steps":["trace[984943558] 'range keys from in-memory index tree' (duration: 137.150113ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:27:04.453912Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2986} {"level":"info","ts":"2026-09-01T05:27:04.547341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2986,"took":"91.924254ms","hash":350402735,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":32669696,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-01T05:27:04.547412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":350402735,"revision":2986,"compact-revision":-1} {"level":"info","ts":"2026-09-01T05:27:25.722752Z","caller":"traceutil/trace.go:171","msg":"trace[508469309] transaction","detail":"{read_only:false; response_revision:6130; number_of_response:1; }","duration":"132.922897ms","start":"2026-09-01T05:27:25.589801Z","end":"2026-09-01T05:27:25.722724Z","steps":["trace[508469309] 'process raft request' (duration: 132.724971ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:04.461638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5909} {"level":"info","ts":"2026-09-01T05:32:04.549889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5909,"took":"86.454568ms","hash":783149695,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":26312704,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T05:32:04.549958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":783149695,"revision":5909,"compact-revision":2986} {"level":"info","ts":"2026-09-01T05:32:56.025857Z","caller":"traceutil/trace.go:171","msg":"trace[1377080246] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"177.907785ms","start":"2026-09-01T05:32:55.847889Z","end":"2026-09-01T05:32:56.025797Z","steps":["trace[1377080246] 'process raft request' (duration: 177.826273ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:56.026009Z","caller":"traceutil/trace.go:171","msg":"trace[1944680662] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"180.005528ms","start":"2026-09-01T05:32:55.845977Z","end":"2026-09-01T05:32:56.025982Z","steps":["trace[1944680662] 'process raft request' (duration: 142.123696ms)","trace[1944680662] 'compare' (duration: 37.423328ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:56.071342Z","caller":"traceutil/trace.go:171","msg":"trace[538764422] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"172.383662ms","start":"2026-09-01T05:32:55.898716Z","end":"2026-09-01T05:32:56.0711Z","steps":["trace[538764422] 'process raft request' (duration: 172.038162ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:56.178648Z","caller":"traceutil/trace.go:171","msg":"trace[737793373] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"102.360629ms","start":"2026-09-01T05:32:56.076261Z","end":"2026-09-01T05:32:56.178622Z","steps":["trace[737793373] 'process raft request' (duration: 74.486135ms)","trace[737793373] 'compare' (duration: 27.235316ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:32:56.178972Z","caller":"traceutil/trace.go:171","msg":"trace[1540039589] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"101.090172ms","start":"2026-09-01T05:32:56.077853Z","end":"2026-09-01T05:32:56.178943Z","steps":["trace[1540039589] 'process raft request' (duration: 100.663389ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:32:56.179448Z","caller":"traceutil/trace.go:171","msg":"trace[1422285403] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"101.03386ms","start":"2026-09-01T05:32:56.078401Z","end":"2026-09-01T05:32:56.179435Z","steps":["trace[1422285403] 'process raft request' (duration: 100.473913ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:04.6405Z","caller":"traceutil/trace.go:171","msg":"trace[104059712] linearizableReadLoop","detail":"{readStateIndex:8897; appliedIndex:8896; }","duration":"324.127302ms","start":"2026-09-01T05:33:04.316335Z","end":"2026-09-01T05:33:04.640462Z","steps":["trace[104059712] 'read index received' (duration: 323.983038ms)","trace[104059712] 'applied index is now lower than readState.Index' (duration: 143.134µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:33:04.640527Z","caller":"traceutil/trace.go:171","msg":"trace[189428109] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"343.925398ms","start":"2026-09-01T05:33:04.29657Z","end":"2026-09-01T05:33:04.640496Z","steps":["trace[189428109] 'process raft request' (duration: 343.686621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:04.640679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:33:04.296553Z","time spent":"344.048762ms","remote":"127.0.0.1:52390","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-09-01T05:33:04.640711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.352149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T05:33:04.640741Z","caller":"traceutil/trace.go:171","msg":"trace[1697382862] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8591; }","duration":"324.426821ms","start":"2026-09-01T05:33:04.316305Z","end":"2026-09-01T05:33:04.640732Z","steps":["trace[1697382862] 'agreement among raft nodes before linearized reading' (duration: 324.277007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:04.640772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:33:04.316293Z","time spent":"324.472192ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-01T05:33:04.929508Z","caller":"traceutil/trace.go:171","msg":"trace[1425238605] transaction","detail":"{read_only:false; response_revision:8592; number_of_response:1; }","duration":"418.908567ms","start":"2026-09-01T05:33:04.510572Z","end":"2026-09-01T05:33:04.929481Z","steps":["trace[1425238605] 'process raft request' (duration: 404.771358ms)","trace[1425238605] 'compare' (duration: 13.950173ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:33:04.929669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:33:04.510542Z","time spent":"419.051621ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:33:04.929925Z","caller":"traceutil/trace.go:171","msg":"trace[830201677] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"367.360001ms","start":"2026-09-01T05:33:04.562547Z","end":"2026-09-01T05:33:04.929907Z","steps":["trace[830201677] 'process raft request' (duration: 366.888217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:04.930048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:33:04.562516Z","time spent":"367.458554ms","remote":"127.0.0.1:52390","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-09-01T05:33:04.930346Z","caller":"traceutil/trace.go:171","msg":"trace[1321791411] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"366.96486ms","start":"2026-09-01T05:33:04.563352Z","end":"2026-09-01T05:33:04.930317Z","steps":["trace[1321791411] 'process raft request' (duration: 366.489246ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:04.930333Z","caller":"traceutil/trace.go:171","msg":"trace[644663467] linearizableReadLoop","detail":"{readStateIndex:8900; appliedIndex:8897; }","duration":"285.592222ms","start":"2026-09-01T05:33:04.644711Z","end":"2026-09-01T05:33:04.930303Z","steps":["trace[644663467] 'read index received' (duration: 270.543247ms)","trace[644663467] 'applied index is now lower than readState.Index' (duration: 15.046755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:33:04.930493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:33:04.563335Z","time spent":"367.084603ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:33:04.93055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.207775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:04.930608Z","caller":"traceutil/trace.go:171","msg":"trace[322911587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8594; }","duration":"177.314378ms","start":"2026-09-01T05:33:04.753279Z","end":"2026-09-01T05:33:04.930594Z","steps":["trace[322911587] 'agreement among raft nodes before linearized reading' (duration: 177.213105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:04.930677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.955223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T05:33:04.930729Z","caller":"traceutil/trace.go:171","msg":"trace[729933297] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8594; }","duration":"286.044765ms","start":"2026-09-01T05:33:04.644671Z","end":"2026-09-01T05:33:04.930715Z","steps":["trace[729933297] 'agreement among raft nodes before linearized reading' (duration: 285.784708ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:15.184497Z","caller":"traceutil/trace.go:171","msg":"trace[481080572] linearizableReadLoop","detail":"{readStateIndex:8955; appliedIndex:8953; }","duration":"103.100391ms","start":"2026-09-01T05:33:15.081373Z","end":"2026-09-01T05:33:15.184474Z","steps":["trace[481080572] 'read index received' (duration: 102.876664ms)","trace[481080572] 'applied index is now lower than readState.Index' (duration: 220.707µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:33:15.184616Z","caller":"traceutil/trace.go:171","msg":"trace[1936751853] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"207.164231ms","start":"2026-09-01T05:33:14.977432Z","end":"2026-09-01T05:33:15.184596Z","steps":["trace[1936751853] 'process raft request' (duration: 206.872032ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:15.184644Z","caller":"traceutil/trace.go:171","msg":"trace[1701126119] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"206.886543ms","start":"2026-09-01T05:33:14.977744Z","end":"2026-09-01T05:33:15.184631Z","steps":["trace[1701126119] 'process raft request' (duration: 206.689007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:15.18495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.563804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-01T05:33:15.185203Z","caller":"traceutil/trace.go:171","msg":"trace[1311596741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8647; }","duration":"103.855233ms","start":"2026-09-01T05:33:15.081321Z","end":"2026-09-01T05:33:15.185176Z","steps":["trace[1311596741] 'agreement among raft nodes before linearized reading' (duration: 103.457061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:15.926456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.66954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:15.926575Z","caller":"traceutil/trace.go:171","msg":"trace[482138838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8649; }","duration":"173.844345ms","start":"2026-09-01T05:33:15.75271Z","end":"2026-09-01T05:33:15.926555Z","steps":["trace[482138838] 'range keys from in-memory index tree' (duration: 173.580557ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:15.926672Z","caller":"traceutil/trace.go:171","msg":"trace[409274062] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"170.586348ms","start":"2026-09-01T05:33:15.756061Z","end":"2026-09-01T05:33:15.926648Z","steps":["trace[409274062] 'process raft request' (duration: 149.164244ms)","trace[409274062] 'compare' (duration: 21.269049ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:33:15.926672Z","caller":"traceutil/trace.go:171","msg":"trace[1272205334] linearizableReadLoop","detail":"{readStateIndex:8958; appliedIndex:8957; }","duration":"123.977369ms","start":"2026-09-01T05:33:15.802643Z","end":"2026-09-01T05:33:15.926621Z","steps":["trace[1272205334] 'read index received' (duration: 102.599066ms)","trace[1272205334] 'applied index is now lower than readState.Index' (duration: 21.376923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:33:15.926798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.130523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:33:15.926854Z","caller":"traceutil/trace.go:171","msg":"trace[1861737369] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:8650; }","duration":"124.219946ms","start":"2026-09-01T05:33:15.802614Z","end":"2026-09-01T05:33:15.926834Z","steps":["trace[1861737369] 'agreement among raft nodes before linearized reading' (duration: 124.106473ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:15.97877Z","caller":"traceutil/trace.go:171","msg":"trace[2125997855] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"132.700946ms","start":"2026-09-01T05:33:15.846045Z","end":"2026-09-01T05:33:15.978746Z","steps":["trace[2125997855] 'process raft request' (duration: 132.518061ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:15.979064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.076449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-8455f9ddc9-tq2xj.18d11b82d902ed2b\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-09-01T05:33:15.979193Z","caller":"traceutil/trace.go:171","msg":"trace[152106431] range","detail":"{range_begin:/registry/events/openstack/barbican-api-8455f9ddc9-tq2xj.18d11b82d902ed2b; range_end:; response_count:1; response_revision:8651; }","duration":"132.255574ms","start":"2026-09-01T05:33:15.846916Z","end":"2026-09-01T05:33:15.979171Z","steps":["trace[152106431] 'agreement among raft nodes before linearized reading' (duration: 131.881213ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:17.266605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.178556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:17.266775Z","caller":"traceutil/trace.go:171","msg":"trace[377648779] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8656; }","duration":"123.412763ms","start":"2026-09-01T05:33:17.14334Z","end":"2026-09-01T05:33:17.266753Z","steps":["trace[377648779] 'range keys from in-memory index tree' (duration: 123.058002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:17.266544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.086075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T05:33:17.266627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.65624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:17.26694Z","caller":"traceutil/trace.go:171","msg":"trace[1273228120] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8656; }","duration":"141.537309ms","start":"2026-09-01T05:33:17.125365Z","end":"2026-09-01T05:33:17.266902Z","steps":["trace[1273228120] 'range keys from in-memory index tree' (duration: 140.965501ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:17.267008Z","caller":"traceutil/trace.go:171","msg":"trace[856575303] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8656; }","duration":"123.088463ms","start":"2026-09-01T05:33:17.143902Z","end":"2026-09-01T05:33:17.266991Z","steps":["trace[856575303] 'range keys from in-memory index tree' (duration: 122.593929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:17.266627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.340693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:17.267139Z","caller":"traceutil/trace.go:171","msg":"trace[459378677] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8656; }","duration":"141.907099ms","start":"2026-09-01T05:33:17.12521Z","end":"2026-09-01T05:33:17.267117Z","steps":["trace[459378677] 'count revisions from in-memory index tree' (duration: 141.293091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:17.266671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.237608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:17.26725Z","caller":"traceutil/trace.go:171","msg":"trace[1644336078] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8656; }","duration":"123.845735ms","start":"2026-09-01T05:33:17.143394Z","end":"2026-09-01T05:33:17.267239Z","steps":["trace[1644336078] 'count revisions from in-memory index tree' (duration: 123.171375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:17.266671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.428375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:17.267337Z","caller":"traceutil/trace.go:171","msg":"trace[1768024367] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8656; }","duration":"142.188287ms","start":"2026-09-01T05:33:17.125139Z","end":"2026-09-01T05:33:17.267327Z","steps":["trace[1768024367] 'range keys from in-memory index tree' (duration: 141.328202ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:19.076109Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002316.wal"} {"level":"info","ts":"2026-09-01T05:33:43.224333Z","caller":"traceutil/trace.go:171","msg":"trace[793133490] linearizableReadLoop","detail":"{readStateIndex:9167; appliedIndex:9166; }","duration":"117.916079ms","start":"2026-09-01T05:33:43.106391Z","end":"2026-09-01T05:33:43.224307Z","steps":["trace[793133490] 'read index received' (duration: 114.286052ms)","trace[793133490] 'applied index is now lower than readState.Index' (duration: 3.629117ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:33:43.224386Z","caller":"traceutil/trace.go:171","msg":"trace[770695770] transaction","detail":"{read_only:false; response_revision:8851; number_of_response:1; }","duration":"120.373403ms","start":"2026-09-01T05:33:43.103981Z","end":"2026-09-01T05:33:43.224354Z","steps":["trace[770695770] 'process raft request' (duration: 116.698374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:43.224442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.035804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/rook-ceph.cephfs.csi.ceph.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:43.224542Z","caller":"traceutil/trace.go:171","msg":"trace[91336016] range","detail":"{range_begin:/registry/csidrivers/rook-ceph.cephfs.csi.ceph.com; range_end:; response_count:0; response_revision:8851; }","duration":"118.189178ms","start":"2026-09-01T05:33:43.106333Z","end":"2026-09-01T05:33:43.224522Z","steps":["trace[91336016] 'agreement among raft nodes before linearized reading' (duration: 118.053244ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:46.942057Z","caller":"traceutil/trace.go:171","msg":"trace[1781152077] linearizableReadLoop","detail":"{readStateIndex:9188; appliedIndex:9187; }","duration":"188.704071ms","start":"2026-09-01T05:33:46.753337Z","end":"2026-09-01T05:33:46.942041Z","steps":["trace[1781152077] 'read index received' (duration: 188.560567ms)","trace[1781152077] 'applied index is now lower than readState.Index' (duration: 142.994µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:33:46.942169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.805924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:46.942187Z","caller":"traceutil/trace.go:171","msg":"trace[660768683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8871; }","duration":"188.924238ms","start":"2026-09-01T05:33:46.753257Z","end":"2026-09-01T05:33:46.942182Z","steps":["trace[660768683] 'agreement among raft nodes before linearized reading' (duration: 188.857436ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:46.942215Z","caller":"traceutil/trace.go:171","msg":"trace[2049717391] transaction","detail":"{read_only:false; response_revision:8871; number_of_response:1; }","duration":"364.280876ms","start":"2026-09-01T05:33:46.57789Z","end":"2026-09-01T05:33:46.942171Z","steps":["trace[2049717391] 'process raft request' (duration: 364.052189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:46.942571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:33:46.577796Z","time spent":"364.539993ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:33:47.212171Z","caller":"traceutil/trace.go:171","msg":"trace[230403024] transaction","detail":"{read_only:false; response_revision:8872; number_of_response:1; }","duration":"264.796583ms","start":"2026-09-01T05:33:46.947338Z","end":"2026-09-01T05:33:47.212134Z","steps":["trace[230403024] 'process raft request' (duration: 264.662259ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:55.5491Z","caller":"traceutil/trace.go:171","msg":"trace[478576789] transaction","detail":"{read_only:false; response_revision:8918; number_of_response:1; }","duration":"116.686622ms","start":"2026-09-01T05:33:55.432387Z","end":"2026-09-01T05:33:55.549073Z","steps":["trace[478576789] 'process raft request' (duration: 116.518497ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:55.687215Z","caller":"traceutil/trace.go:171","msg":"trace[457357592] linearizableReadLoop","detail":"{readStateIndex:9238; appliedIndex:9236; }","duration":"184.522569ms","start":"2026-09-01T05:33:55.502669Z","end":"2026-09-01T05:33:55.687192Z","steps":["trace[457357592] 'read index received' (duration: 46.266189ms)","trace[457357592] 'applied index is now lower than readState.Index' (duration: 138.25527ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:33:55.687458Z","caller":"traceutil/trace.go:171","msg":"trace[1852720792] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"238.798754ms","start":"2026-09-01T05:33:55.448647Z","end":"2026-09-01T05:33:55.687446Z","steps":["trace[1852720792] 'process raft request' (duration: 238.395123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:55.687757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.068385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:33:55.687802Z","caller":"traceutil/trace.go:171","msg":"trace[1505433331] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8919; }","duration":"185.173218ms","start":"2026-09-01T05:33:55.502619Z","end":"2026-09-01T05:33:55.687792Z","steps":["trace[1505433331] 'agreement among raft nodes before linearized reading' (duration: 185.081725ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:55.687965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.354484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T05:33:55.688036Z","caller":"traceutil/trace.go:171","msg":"trace[477433805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8919; }","duration":"183.447626ms","start":"2026-09-01T05:33:55.504571Z","end":"2026-09-01T05:33:55.688019Z","steps":["trace[477433805] 'agreement among raft nodes before linearized reading' (duration: 183.2452ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:56.321115Z","caller":"traceutil/trace.go:171","msg":"trace[1982271574] transaction","detail":"{read_only:false; response_revision:8923; number_of_response:1; }","duration":"141.912429ms","start":"2026-09-01T05:33:56.179174Z","end":"2026-09-01T05:33:56.321086Z","steps":["trace[1982271574] 'process raft request' (duration: 141.764494ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:56.321765Z","caller":"traceutil/trace.go:171","msg":"trace[1543613016] linearizableReadLoop","detail":"{readStateIndex:9242; appliedIndex:9242; }","duration":"137.441516ms","start":"2026-09-01T05:33:56.184308Z","end":"2026-09-01T05:33:56.321749Z","steps":["trace[1543613016] 'read index received' (duration: 137.435576ms)","trace[1543613016] 'applied index is now lower than readState.Index' (duration: 4.84µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:33:56.321925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.600611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:33:56.32198Z","caller":"traceutil/trace.go:171","msg":"trace[122189330] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8923; }","duration":"137.706114ms","start":"2026-09-01T05:33:56.18426Z","end":"2026-09-01T05:33:56.321966Z","steps":["trace[122189330] 'agreement among raft nodes before linearized reading' (duration: 137.609021ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:33:56.367182Z","caller":"traceutil/trace.go:171","msg":"trace[1995354065] transaction","detail":"{read_only:false; response_revision:8924; number_of_response:1; }","duration":"147.448252ms","start":"2026-09-01T05:33:56.219711Z","end":"2026-09-01T05:33:56.367159Z","steps":["trace[1995354065] 'process raft request' (duration: 147.04092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:33:56.639748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.673799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T05:33:56.63983Z","caller":"traceutil/trace.go:171","msg":"trace[1942829993] linearizableReadLoop","detail":"{readStateIndex:9245; appliedIndex:9244; }","duration":"233.135447ms","start":"2026-09-01T05:33:56.406678Z","end":"2026-09-01T05:33:56.639813Z","steps":["trace[1942829993] 'read index received' (duration: 14.259472ms)","trace[1942829993] 'applied index is now lower than readState.Index' (duration: 218.874875ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:33:56.639923Z","caller":"traceutil/trace.go:171","msg":"trace[683456064] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"243.775081ms","start":"2026-09-01T05:33:56.396119Z","end":"2026-09-01T05:33:56.639894Z","steps":["trace[683456064] 'process raft request' (duration: 24.886656ms)","trace[683456064] 'compare' (duration: 218.332009ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:33:56.640009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.333503ms","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-09-01T05:33:56.640133Z","caller":"traceutil/trace.go:171","msg":"trace[1893783649] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8926; }","duration":"233.431316ms","start":"2026-09-01T05:33:56.40662Z","end":"2026-09-01T05:33:56.640051Z","steps":["trace[1893783649] 'agreement among raft nodes before linearized reading' (duration: 233.23729ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:34:00.018314Z","caller":"traceutil/trace.go:171","msg":"trace[605128049] linearizableReadLoop","detail":"{readStateIndex:9272; appliedIndex:9271; }","duration":"120.689221ms","start":"2026-09-01T05:33:59.897606Z","end":"2026-09-01T05:34:00.018295Z","steps":["trace[605128049] 'read index received' (duration: 104.45482ms)","trace[605128049] 'applied index is now lower than readState.Index' (duration: 16.233731ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:34:00.018435Z","caller":"traceutil/trace.go:171","msg":"trace[292189694] transaction","detail":"{read_only:false; response_revision:8952; number_of_response:1; }","duration":"121.34384ms","start":"2026-09-01T05:33:59.897029Z","end":"2026-09-01T05:34:00.018372Z","steps":["trace[292189694] 'process raft request' (duration: 105.085029ms)","trace[292189694] 'compare' (duration: 16.099416ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:34:00.018506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.790633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-pfspx\" ","response":"range_response_count:1 size:2801"} {"level":"warn","ts":"2026-09-01T05:34:00.018508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.878956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pfspx\" ","response":"range_response_count:1 size:4662"} {"level":"info","ts":"2026-09-01T05:34:00.018614Z","caller":"traceutil/trace.go:171","msg":"trace[1188222199] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pfspx; range_end:; response_count:1; response_revision:8952; }","duration":"121.017961ms","start":"2026-09-01T05:33:59.897575Z","end":"2026-09-01T05:34:00.018593Z","steps":["trace[1188222199] 'agreement among raft nodes before linearized reading' (duration: 120.804484ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:34:00.018536Z","caller":"traceutil/trace.go:171","msg":"trace[1721651779] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-pfspx; range_end:; response_count:1; response_revision:8952; }","duration":"120.845754ms","start":"2026-09-01T05:33:59.897678Z","end":"2026-09-01T05:34:00.018524Z","steps":["trace[1721651779] 'agreement among raft nodes before linearized reading' (duration: 120.717641ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:34:00.172506Z","caller":"traceutil/trace.go:171","msg":"trace[73510779] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8953; }","duration":"144.182125ms","start":"2026-09-01T05:34:00.028303Z","end":"2026-09-01T05:34:00.172485Z","steps":["trace[73510779] 'process raft request' (duration: 112.761966ms)","trace[73510779] 'compare' (duration: 31.334367ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:34:00.244183Z","caller":"traceutil/trace.go:171","msg":"trace[462651795] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"213.154825ms","start":"2026-09-01T05:34:00.03097Z","end":"2026-09-01T05:34:00.244125Z","steps":["trace[462651795] 'process raft request' (duration: 212.694812ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:34:00.350831Z","caller":"traceutil/trace.go:171","msg":"trace[877678682] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"117.30026ms","start":"2026-09-01T05:34:00.233508Z","end":"2026-09-01T05:34:00.350809Z","steps":["trace[877678682] 'process raft request' (duration: 73.031101ms)","trace[877678682] 'compare' (duration: 43.873088ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:34:00.350948Z","caller":"traceutil/trace.go:171","msg":"trace[764803006] linearizableReadLoop","detail":"{readStateIndex:9275; appliedIndex:9274; }","duration":"101.41463ms","start":"2026-09-01T05:34:00.249508Z","end":"2026-09-01T05:34:00.350922Z","steps":["trace[764803006] 'read index received' (duration: 57.018846ms)","trace[764803006] 'applied index is now lower than readState.Index' (duration: 44.393604ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:34:00.351158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.608725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pfspx\" ","response":"range_response_count:1 size:4642"} {"level":"info","ts":"2026-09-01T05:34:00.351186Z","caller":"traceutil/trace.go:171","msg":"trace[488984888] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pfspx; range_end:; response_count:1; response_revision:8955; }","duration":"101.710278ms","start":"2026-09-01T05:34:00.249467Z","end":"2026-09-01T05:34:00.351177Z","steps":["trace[488984888] 'agreement among raft nodes before linearized reading' (duration: 101.536093ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:35:07.570368Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"590b810b00f00655","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:35:07.577348Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T05:35:07.577494Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T05:35:28.14351Z","caller":"traceutil/trace.go:171","msg":"trace[228734304] transaction","detail":"{read_only:false; response_revision:9796; number_of_response:1; }","duration":"232.712314ms","start":"2026-09-01T05:35:27.910741Z","end":"2026-09-01T05:35:28.143454Z","steps":["trace[228734304] 'process raft request' (duration: 232.526309ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:35:28.16712Z","caller":"traceutil/trace.go:171","msg":"trace[1335544024] transaction","detail":"{read_only:false; response_revision:9797; number_of_response:1; }","duration":"255.759846ms","start":"2026-09-01T05:35:27.91133Z","end":"2026-09-01T05:35:28.167089Z","steps":["trace[1335544024] 'process raft request' (duration: 255.476738ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:35:28.167661Z","caller":"traceutil/trace.go:171","msg":"trace[917309501] transaction","detail":"{read_only:false; response_revision:9798; number_of_response:1; }","duration":"160.636142ms","start":"2026-09-01T05:35:28.006991Z","end":"2026-09-01T05:35:28.167627Z","steps":["trace[917309501] 'process raft request' (duration: 159.946912ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:37:04.467932Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8074} {"level":"info","ts":"2026-09-01T05:37:04.536358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8074,"took":"66.00707ms","hash":2562869004,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":28413952,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T05:37:04.536409Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2562869004,"revision":8074,"compact-revision":5909} {"level":"info","ts":"2026-09-01T05:40:46.874266Z","caller":"traceutil/trace.go:171","msg":"trace[832295830] linearizableReadLoop","detail":"{readStateIndex:12536; appliedIndex:12535; }","duration":"120.576228ms","start":"2026-09-01T05:40:46.753667Z","end":"2026-09-01T05:40:46.874243Z","steps":["trace[832295830] 'read index received' (duration: 120.337431ms)","trace[832295830] 'applied index is now lower than readState.Index' (duration: 237.637µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:40:46.874403Z","caller":"traceutil/trace.go:171","msg":"trace[730312796] transaction","detail":"{read_only:false; response_revision:12116; number_of_response:1; }","duration":"174.323889ms","start":"2026-09-01T05:40:46.700041Z","end":"2026-09-01T05:40:46.874365Z","steps":["trace[730312796] 'process raft request' (duration: 174.01922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:40:46.874469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.788234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:40:46.87453Z","caller":"traceutil/trace.go:171","msg":"trace[1542886617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12116; }","duration":"120.901207ms","start":"2026-09-01T05:40:46.753616Z","end":"2026-09-01T05:40:46.874517Z","steps":["trace[1542886617] 'agreement among raft nodes before linearized reading' (duration: 120.756123ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:41:05.584372Z","caller":"traceutil/trace.go:171","msg":"trace[642124973] transaction","detail":"{read_only:false; response_revision:12223; number_of_response:1; }","duration":"126.340279ms","start":"2026-09-01T05:41:05.457992Z","end":"2026-09-01T05:41:05.584333Z","steps":["trace[642124973] 'process raft request' (duration: 126.065811ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:41:05.976167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.335419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:41:05.976269Z","caller":"traceutil/trace.go:171","msg":"trace[120529764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12224; }","duration":"223.466993ms","start":"2026-09-01T05:41:05.752778Z","end":"2026-09-01T05:41:05.976245Z","steps":["trace[120529764] 'range keys from in-memory index tree' (duration: 223.213916ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:42:04.475256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10464} {"level":"info","ts":"2026-09-01T05:42:04.570769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10464,"took":"92.836428ms","hash":2137346224,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":26230784,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T05:42:04.570848Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2137346224,"revision":10464,"compact-revision":8074} {"level":"info","ts":"2026-09-01T05:47:04.487664Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12637} {"level":"info","ts":"2026-09-01T05:47:04.581582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12637,"took":"91.653012ms","hash":3362629752,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":28119040,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T05:47:04.581657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3362629752,"revision":12637,"compact-revision":10464} {"level":"info","ts":"2026-09-01T05:48:06.081862Z","caller":"traceutil/trace.go:171","msg":"trace[101428109] linearizableReadLoop","detail":"{readStateIndex:15500; appliedIndex:15499; }","duration":"135.865261ms","start":"2026-09-01T05:48:05.945942Z","end":"2026-09-01T05:48:06.081807Z","steps":["trace[101428109] 'read index received' (duration: 135.715427ms)","trace[101428109] 'applied index is now lower than readState.Index' (duration: 148.624µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:48:06.082164Z","caller":"traceutil/trace.go:171","msg":"trace[1270518247] transaction","detail":"{read_only:false; response_revision:14978; number_of_response:1; }","duration":"190.19944ms","start":"2026-09-01T05:48:05.891952Z","end":"2026-09-01T05:48:06.082151Z","steps":["trace[1270518247] 'process raft request' (duration: 189.693255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:48:06.082635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.683816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:48:06.082696Z","caller":"traceutil/trace.go:171","msg":"trace[1824172768] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:14978; }","duration":"136.785429ms","start":"2026-09-01T05:48:05.945898Z","end":"2026-09-01T05:48:06.082683Z","steps":["trace[1824172768] 'agreement among raft nodes before linearized reading' (duration: 136.670406ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:48:06.218195Z","caller":"traceutil/trace.go:171","msg":"trace[1383817060] transaction","detail":"{read_only:false; response_revision:14979; number_of_response:1; }","duration":"132.237224ms","start":"2026-09-01T05:48:06.085927Z","end":"2026-09-01T05:48:06.218165Z","steps":["trace[1383817060] 'process raft request' (duration: 131.311437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:48:22.953635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.677279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:48:22.953759Z","caller":"traceutil/trace.go:171","msg":"trace[1724580035] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:15080; }","duration":"127.849514ms","start":"2026-09-01T05:48:22.825877Z","end":"2026-09-01T05:48:22.953727Z","steps":["trace[1724580035] 'count revisions from in-memory index tree' (duration: 127.505755ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:48:34.197148Z","caller":"traceutil/trace.go:171","msg":"trace[1173261052] transaction","detail":"{read_only:false; response_revision:15254; number_of_response:1; }","duration":"220.864978ms","start":"2026-09-01T05:48:33.97626Z","end":"2026-09-01T05:48:34.197125Z","steps":["trace[1173261052] 'process raft request' (duration: 128.154104ms)","trace[1173261052] 'compare' (duration: 92.434936ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:48:34.197134Z","caller":"traceutil/trace.go:171","msg":"trace[516918743] linearizableReadLoop","detail":"{readStateIndex:15785; appliedIndex:15784; }","duration":"134.874612ms","start":"2026-09-01T05:48:34.06223Z","end":"2026-09-01T05:48:34.197104Z","steps":["trace[516918743] 'read index received' (duration: 42.22979ms)","trace[516918743] 'applied index is now lower than readState.Index' (duration: 92.642432ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:48:34.198223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.979084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T05:48:34.198314Z","caller":"traceutil/trace.go:171","msg":"trace[1943871484] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:15254; }","duration":"136.1301ms","start":"2026-09-01T05:48:34.062166Z","end":"2026-09-01T05:48:34.198296Z","steps":["trace[1943871484] 'agreement among raft nodes before linearized reading' (duration: 135.048728ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:48:34.524935Z","caller":"traceutil/trace.go:171","msg":"trace[1397075172] linearizableReadLoop","detail":"{readStateIndex:15786; appliedIndex:15785; }","duration":"273.902657ms","start":"2026-09-01T05:48:34.250974Z","end":"2026-09-01T05:48:34.524877Z","steps":["trace[1397075172] 'read index received' (duration: 220.394284ms)","trace[1397075172] 'applied index is now lower than readState.Index' (duration: 53.506473ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:48:34.524999Z","caller":"traceutil/trace.go:171","msg":"trace[1948927126] transaction","detail":"{read_only:false; response_revision:15255; number_of_response:1; }","duration":"279.030579ms","start":"2026-09-01T05:48:34.245921Z","end":"2026-09-01T05:48:34.524951Z","steps":["trace[1948927126] 'process raft request' (duration: 225.422963ms)","trace[1948927126] 'compare' (duration: 53.187743ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:48:34.525165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.506368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:48:34.525278Z","caller":"traceutil/trace.go:171","msg":"trace[828507256] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:15255; }","duration":"191.675013ms","start":"2026-09-01T05:48:34.333575Z","end":"2026-09-01T05:48:34.52525Z","steps":["trace[828507256] 'agreement among raft nodes before linearized reading' (duration: 191.493797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:48:34.525212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.214466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a48d431b-eb82-4207-b1e7-686416ce324d\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-09-01T05:48:34.525596Z","caller":"traceutil/trace.go:171","msg":"trace[547379722] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a48d431b-eb82-4207-b1e7-686416ce324d; range_end:; response_count:1; response_revision:15255; }","duration":"274.633328ms","start":"2026-09-01T05:48:34.250938Z","end":"2026-09-01T05:48:34.525571Z","steps":["trace[547379722] 'agreement among raft nodes before linearized reading' (duration: 274.103222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:48:34.525804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.212716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T05:48:34.525963Z","caller":"traceutil/trace.go:171","msg":"trace[886860897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15255; }","duration":"174.417942ms","start":"2026-09-01T05:48:34.351511Z","end":"2026-09-01T05:48:34.525929Z","steps":["trace[886860897] 'agreement among raft nodes before linearized reading' (duration: 173.959848ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:50:00.35401Z","caller":"traceutil/trace.go:171","msg":"trace[482350738] transaction","detail":"{read_only:false; response_revision:16275; number_of_response:1; }","duration":"114.33842ms","start":"2026-09-01T05:50:00.2396Z","end":"2026-09-01T05:50:00.353939Z","steps":["trace[482350738] 'process raft request' (duration: 113.565817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:50:00.396851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.468749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T05:50:00.396942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.51908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:50:00.396978Z","caller":"traceutil/trace.go:171","msg":"trace[397311013] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16275; }","duration":"125.621583ms","start":"2026-09-01T05:50:00.271324Z","end":"2026-09-01T05:50:00.396946Z","steps":["trace[397311013] 'agreement among raft nodes before linearized reading' (duration: 82.563771ms)","trace[397311013] 'range keys from in-memory index tree' (duration: 42.885047ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:50:00.397042Z","caller":"traceutil/trace.go:171","msg":"trace[1684141847] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16275; }","duration":"125.565451ms","start":"2026-09-01T05:50:00.271414Z","end":"2026-09-01T05:50:00.396979Z","steps":["trace[1684141847] 'agreement among raft nodes before linearized reading' (duration: 82.711375ms)","trace[1684141847] 'range keys from in-memory index tree' (duration: 42.797925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:50:00.397359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.253038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T05:50:00.397408Z","caller":"traceutil/trace.go:171","msg":"trace[1691772713] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:16275; }","duration":"112.366501ms","start":"2026-09-01T05:50:00.285026Z","end":"2026-09-01T05:50:00.397392Z","steps":["trace[1691772713] 'agreement among raft nodes before linearized reading' (duration: 69.049341ms)","trace[1691772713] 'count revisions from in-memory index tree' (duration: 43.236407ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:50:00.397581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.469379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T05:50:00.39764Z","caller":"traceutil/trace.go:171","msg":"trace[413867613] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16275; }","duration":"126.562761ms","start":"2026-09-01T05:50:00.271065Z","end":"2026-09-01T05:50:00.397628Z","steps":["trace[413867613] 'agreement among raft nodes before linearized reading' (duration: 83.026695ms)","trace[413867613] 'range keys from in-memory index tree' (duration: 43.468254ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:50:00.396859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.10279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T05:50:00.397728Z","caller":"traceutil/trace.go:171","msg":"trace[1261251380] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16275; }","duration":"120.046249ms","start":"2026-09-01T05:50:00.277666Z","end":"2026-09-01T05:50:00.397712Z","steps":["trace[1261251380] 'agreement among raft nodes before linearized reading' (duration: 76.44911ms)","trace[1261251380] 'range keys from in-memory index tree' (duration: 42.572838ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:50:07.538859Z","caller":"traceutil/trace.go:171","msg":"trace[2083796630] transaction","detail":"{read_only:false; response_revision:16308; number_of_response:1; }","duration":"404.006096ms","start":"2026-09-01T05:50:07.134836Z","end":"2026-09-01T05:50:07.538842Z","steps":["trace[2083796630] 'process raft request' (duration: 403.877552ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:50:07.538969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:50:07.134814Z","time spent":"404.098488ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:50:07.545407Z","caller":"traceutil/trace.go:171","msg":"trace[1546261331] linearizableReadLoop","detail":"{readStateIndex:16872; appliedIndex:16871; }","duration":"295.426504ms","start":"2026-09-01T05:50:07.249957Z","end":"2026-09-01T05:50:07.545383Z","steps":["trace[1546261331] 'read index received' (duration: 288.901832ms)","trace[1546261331] 'applied index is now lower than readState.Index' (duration: 6.523532ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:50:07.545491Z","caller":"traceutil/trace.go:171","msg":"trace[1446114422] transaction","detail":"{read_only:false; response_revision:16309; number_of_response:1; }","duration":"393.607818ms","start":"2026-09-01T05:50:07.151848Z","end":"2026-09-01T05:50:07.545456Z","steps":["trace[1446114422] 'process raft request' (duration: 391.643679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:50:07.54564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.565554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"warn","ts":"2026-09-01T05:50:07.545646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:50:07.151825Z","time spent":"393.73226ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:50:07.545639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.678352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-01T05:50:07.545679Z","caller":"traceutil/trace.go:171","msg":"trace[458446046] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16309; }","duration":"144.610005ms","start":"2026-09-01T05:50:07.401059Z","end":"2026-09-01T05:50:07.545669Z","steps":["trace[458446046] 'agreement among raft nodes before linearized reading' (duration: 144.46257ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:50:07.545692Z","caller":"traceutil/trace.go:171","msg":"trace[1181727035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16309; }","duration":"295.762504ms","start":"2026-09-01T05:50:07.249917Z","end":"2026-09-01T05:50:07.54568Z","steps":["trace[1181727035] 'agreement among raft nodes before linearized reading' (duration: 295.60716ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:52:04.495247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14656} {"level":"info","ts":"2026-09-01T05:52:04.582799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14656,"took":"84.861669ms","hash":1766385162,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":31588352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T05:52:04.583427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1766385162,"revision":14656,"compact-revision":12637} {"level":"warn","ts":"2026-09-01T05:55:57.853275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.428712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:55:57.853395Z","caller":"traceutil/trace.go:171","msg":"trace[914195819] transaction","detail":"{read_only:false; response_revision:18892; number_of_response:1; }","duration":"137.153792ms","start":"2026-09-01T05:55:57.716229Z","end":"2026-09-01T05:55:57.853382Z","steps":["trace[914195819] 'process raft request' (duration: 21.547775ms)","trace[914195819] 'compare' (duration: 115.278677ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:55:57.867375Z","caller":"traceutil/trace.go:171","msg":"trace[1785111703] transaction","detail":"{read_only:false; response_revision:18893; number_of_response:1; }","duration":"104.458448ms","start":"2026-09-01T05:55:57.762872Z","end":"2026-09-01T05:55:57.86733Z","steps":["trace[1785111703] 'process raft request' (duration: 104.253122ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:55:58.372147Z","caller":"traceutil/trace.go:171","msg":"trace[1723606927] transaction","detail":"{read_only:false; response_revision:18895; number_of_response:1; }","duration":"301.512694ms","start":"2026-09-01T05:55:58.070603Z","end":"2026-09-01T05:55:58.372116Z","steps":["trace[1723606927] 'process raft request' (duration: 301.02737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:55:58.372311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:55:58.07058Z","time spent":"301.646489ms","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:55:58.574527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.676142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T05:55:58.574611Z","caller":"traceutil/trace.go:171","msg":"trace[1928024179] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18895; }","duration":"110.813366ms","start":"2026-09-01T05:55:58.46378Z","end":"2026-09-01T05:55:58.574593Z","steps":["trace[1928024179] 'count revisions from in-memory index tree' (duration: 110.602519ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:56:03.489729Z","caller":"traceutil/trace.go:171","msg":"trace[512701983] transaction","detail":"{read_only:false; response_revision:18987; number_of_response:1; }","duration":"101.10409ms","start":"2026-09-01T05:56:03.388591Z","end":"2026-09-01T05:56:03.489695Z","steps":["trace[512701983] 'process raft request' (duration: 100.898374ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:56:36.990794Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"590b810b00f00655","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:56:36.997876Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T05:56:36.998048Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T05:57:04.50915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17042} {"level":"info","ts":"2026-09-01T05:57:04.622686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17042,"took":"108.792296ms","hash":3817819630,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":34189312,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T05:57:04.622762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3817819630,"revision":17042,"compact-revision":14656} {"level":"info","ts":"2026-09-01T06:01:54.568581Z","caller":"traceutil/trace.go:171","msg":"trace[1228131341] transaction","detail":"{read_only:false; response_revision:21685; number_of_response:1; }","duration":"123.453304ms","start":"2026-09-01T06:01:54.445081Z","end":"2026-09-01T06:01:54.568534Z","steps":["trace[1228131341] 'process raft request' (duration: 123.199987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:54.980799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.831236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T06:01:54.98096Z","caller":"traceutil/trace.go:171","msg":"trace[736199579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21685; }","duration":"227.060322ms","start":"2026-09-01T06:01:54.753873Z","end":"2026-09-01T06:01:54.980933Z","steps":["trace[736199579] 'range keys from in-memory index tree' (duration: 226.730633ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:54.981104Z","caller":"traceutil/trace.go:171","msg":"trace[2028230468] linearizableReadLoop","detail":"{readStateIndex:22427; appliedIndex:22426; }","duration":"100.653422ms","start":"2026-09-01T06:01:54.880422Z","end":"2026-09-01T06:01:54.981075Z","steps":["trace[2028230468] 'read index received' (duration: 12.442788ms)","trace[2028230468] 'applied index is now lower than readState.Index' (duration: 88.209514ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:54.981136Z","caller":"traceutil/trace.go:171","msg":"trace[1661823426] transaction","detail":"{read_only:false; response_revision:21686; number_of_response:1; }","duration":"143.272309ms","start":"2026-09-01T06:01:54.83783Z","end":"2026-09-01T06:01:54.981103Z","steps":["trace[1661823426] 'process raft request' (duration: 55.032755ms)","trace[1661823426] 'compare' (duration: 87.968236ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T06:01:54.981427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.96048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-01T06:01:54.981499Z","caller":"traceutil/trace.go:171","msg":"trace[1351338533] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21686; }","duration":"101.103525ms","start":"2026-09-01T06:01:54.880376Z","end":"2026-09-01T06:01:54.98148Z","steps":["trace[1351338533] 'agreement among raft nodes before linearized reading' (duration: 100.772625ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T06:01:55.385275Z","caller":"traceutil/trace.go:171","msg":"trace[1517344581] linearizableReadLoop","detail":"{readStateIndex:22428; appliedIndex:22427; }","duration":"324.314704ms","start":"2026-09-01T06:01:55.060935Z","end":"2026-09-01T06:01:55.38525Z","steps":["trace[1517344581] 'read index received' (duration: 324.20352ms)","trace[1517344581] 'applied index is now lower than readState.Index' (duration: 110.214µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:55.385354Z","caller":"traceutil/trace.go:171","msg":"trace[1021183715] transaction","detail":"{read_only:false; response_revision:21687; number_of_response:1; }","duration":"385.227202ms","start":"2026-09-01T06:01:55.0001Z","end":"2026-09-01T06:01:55.385327Z","steps":["trace[1021183715] 'process raft request' (duration: 384.990715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.385409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.464278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T06:01:55.38544Z","caller":"traceutil/trace.go:171","msg":"trace[1671513886] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21687; }","duration":"324.54806ms","start":"2026-09-01T06:01:55.060881Z","end":"2026-09-01T06:01:55.385429Z","steps":["trace[1671513886] 'agreement among raft nodes before linearized reading' (duration: 324.478578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.385479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.000081Z","time spent":"385.326234ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:01:55.385473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.060859Z","time spent":"324.606762ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-01T06:01:55.723083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.446762ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T06:01:55.723253Z","caller":"traceutil/trace.go:171","msg":"trace[1528885488] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21687; }","duration":"107.610206ms","start":"2026-09-01T06:01:55.615599Z","end":"2026-09-01T06:01:55.72321Z","steps":["trace[1528885488] 'range keys from in-memory index tree' (duration: 107.426161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.723319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.32111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T06:01:55.72355Z","caller":"traceutil/trace.go:171","msg":"trace[713403393] linearizableReadLoop","detail":"{readStateIndex:22430; appliedIndex:22428; }","duration":"338.190754ms","start":"2026-09-01T06:01:55.385339Z","end":"2026-09-01T06:01:55.72353Z","steps":["trace[713403393] 'read index received' (duration: 130.458262ms)","trace[713403393] 'applied index is now lower than readState.Index' (duration: 207.731332ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:55.723664Z","caller":"traceutil/trace.go:171","msg":"trace[641485165] transaction","detail":"{read_only:false; response_revision:21689; number_of_response:1; }","duration":"438.900826ms","start":"2026-09-01T06:01:55.284749Z","end":"2026-09-01T06:01:55.72365Z","steps":["trace[641485165] 'process raft request' (duration: 438.68789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.723746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.284723Z","time spent":"438.978979ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T06:01:55.72377Z","caller":"traceutil/trace.go:171","msg":"trace[1759791949] transaction","detail":"{read_only:false; response_revision:21688; number_of_response:1; }","duration":"645.104363ms","start":"2026-09-01T06:01:55.078644Z","end":"2026-09-01T06:01:55.723748Z","steps":["trace[1759791949] 'process raft request' (duration: 437.229747ms)","trace[1759791949] 'compare' (duration: 207.059822ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T06:01:55.723889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.078624Z","time spent":"645.191046ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:01:55.724013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.508235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T06:01:55.724062Z","caller":"traceutil/trace.go:171","msg":"trace[265368866] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21689; }","duration":"335.581276ms","start":"2026-09-01T06:01:55.388469Z","end":"2026-09-01T06:01:55.72405Z","steps":["trace[265368866] 'agreement among raft nodes before linearized reading' (duration: 335.503904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.724101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.388457Z","time spent":"335.633308ms","remote":"127.0.0.1:52294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-01T06:01:55.723906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.061716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-01T06:01:55.72433Z","caller":"traceutil/trace.go:171","msg":"trace[720283338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21689; }","duration":"472.517649ms","start":"2026-09-01T06:01:55.251793Z","end":"2026-09-01T06:01:55.724311Z","steps":["trace[720283338] 'agreement among raft nodes before linearized reading' (duration: 471.968153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.724389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.251765Z","time spent":"472.607432ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-01T06:01:55.724399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.189562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T06:01:55.724437Z","caller":"traceutil/trace.go:171","msg":"trace[274459345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21689; }","duration":"133.268554ms","start":"2026-09-01T06:01:55.591157Z","end":"2026-09-01T06:01:55.724425Z","steps":["trace[274459345] 'agreement among raft nodes before linearized reading' (duration: 133.13509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.724541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.540298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-01T06:01:55.724609Z","caller":"traceutil/trace.go:171","msg":"trace[1289245641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21689; }","duration":"349.652252ms","start":"2026-09-01T06:01:55.374936Z","end":"2026-09-01T06:01:55.724588Z","steps":["trace[1289245641] 'agreement among raft nodes before linearized reading' (duration: 349.452286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:55.724643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.37491Z","time spent":"349.725414ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T06:01:56.137626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.478665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T06:01:56.137802Z","caller":"traceutil/trace.go:171","msg":"trace[979709007] transaction","detail":"{read_only:false; response_revision:21690; number_of_response:1; }","duration":"408.164409ms","start":"2026-09-01T06:01:55.729602Z","end":"2026-09-01T06:01:56.137766Z","steps":["trace[979709007] 'process raft request' (duration: 159.466358ms)","trace[979709007] 'compare' (duration: 248.300159ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T06:01:56.13798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.729575Z","time spent":"408.330204ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T06:01:56.137967Z","caller":"traceutil/trace.go:171","msg":"trace[589692006] transaction","detail":"{read_only:false; response_revision:21691; number_of_response:1; }","duration":"408.342784ms","start":"2026-09-01T06:01:55.729601Z","end":"2026-09-01T06:01:56.137944Z","steps":["trace[589692006] 'process raft request' (duration: 408.142308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.13812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.729575Z","time spent":"408.466568ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T06:01:56.138122Z","caller":"traceutil/trace.go:171","msg":"trace[1585352641] linearizableReadLoop","detail":"{readStateIndex:22433; appliedIndex:22430; }","duration":"407.222091ms","start":"2026-09-01T06:01:55.730875Z","end":"2026-09-01T06:01:56.138097Z","steps":["trace[1585352641] 'read index received' (duration: 158.134678ms)","trace[1585352641] 'applied index is now lower than readState.Index' (duration: 249.085463ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:56.138177Z","caller":"traceutil/trace.go:171","msg":"trace[57585784] transaction","detail":"{read_only:false; response_revision:21692; number_of_response:1; }","duration":"408.003524ms","start":"2026-09-01T06:01:55.730142Z","end":"2026-09-01T06:01:56.138145Z","steps":["trace[57585784] 'process raft request' (duration: 407.750977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.13835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.730129Z","time spent":"408.142449ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:01:56.138349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.471889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19455"} {"level":"warn","ts":"2026-09-01T06:01:56.138391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.804877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-01T06:01:56.138411Z","caller":"traceutil/trace.go:171","msg":"trace[2099075492] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21692; }","duration":"407.559341ms","start":"2026-09-01T06:01:55.730837Z","end":"2026-09-01T06:01:56.138396Z","steps":["trace[2099075492] 'agreement among raft nodes before linearized reading' (duration: 407.322874ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.138421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.946015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T06:01:56.138441Z","caller":"traceutil/trace.go:171","msg":"trace[844796477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21692; }","duration":"201.893049ms","start":"2026-09-01T06:01:55.936533Z","end":"2026-09-01T06:01:56.138426Z","steps":["trace[844796477] 'agreement among raft nodes before linearized reading' (duration: 201.761485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.138466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.730825Z","time spent":"407.625443ms","remote":"127.0.0.1:52266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19478,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-01T06:01:56.138487Z","caller":"traceutil/trace.go:171","msg":"trace[170333360] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21692; }","duration":"318.016998ms","start":"2026-09-01T06:01:55.820449Z","end":"2026-09-01T06:01:56.138466Z","steps":["trace[170333360] 'agreement among raft nodes before linearized reading' (duration: 317.842652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.138532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:55.820321Z","time spent":"318.197593ms","remote":"127.0.0.1:52484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":92,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-09-01T06:01:56.466619Z","caller":"traceutil/trace.go:171","msg":"trace[2128445763] linearizableReadLoop","detail":"{readStateIndex:22434; appliedIndex:22433; }","duration":"313.435952ms","start":"2026-09-01T06:01:56.153141Z","end":"2026-09-01T06:01:56.466576Z","steps":["trace[2128445763] 'read index received' (duration: 313.101642ms)","trace[2128445763] 'applied index is now lower than readState.Index' (duration: 332.76µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T06:01:56.466877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.71162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-01T06:01:56.46697Z","caller":"traceutil/trace.go:171","msg":"trace[538396880] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21693; }","duration":"313.843803ms","start":"2026-09-01T06:01:56.153101Z","end":"2026-09-01T06:01:56.466945Z","steps":["trace[538396880] 'agreement among raft nodes before linearized reading' (duration: 313.603026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.467022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:56.153085Z","time spent":"313.923056ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5675,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-01T06:01:56.466641Z","caller":"traceutil/trace.go:171","msg":"trace[2076798956] transaction","detail":"{read_only:false; response_revision:21693; number_of_response:1; }","duration":"323.451538ms","start":"2026-09-01T06:01:56.143131Z","end":"2026-09-01T06:01:56.466582Z","steps":["trace[2076798956] 'process raft request' (duration: 323.059206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.467525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:56.14306Z","time spent":"324.315873ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:01:56.835119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.279838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T06:01:56.835305Z","caller":"traceutil/trace.go:171","msg":"trace[1949709225] transaction","detail":"{read_only:false; response_revision:21694; number_of_response:1; }","duration":"584.650958ms","start":"2026-09-01T06:01:56.250625Z","end":"2026-09-01T06:01:56.835276Z","steps":["trace[1949709225] 'process raft request' (duration: 396.068551ms)","trace[1949709225] 'compare' (duration: 187.922748ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:01:56.835355Z","caller":"traceutil/trace.go:171","msg":"trace[477396375] linearizableReadLoop","detail":"{readStateIndex:22435; appliedIndex:22434; }","duration":"368.631381ms","start":"2026-09-01T06:01:56.466716Z","end":"2026-09-01T06:01:56.835347Z","steps":["trace[477396375] 'read index received' (duration: 179.978422ms)","trace[477396375] 'applied index is now lower than readState.Index' (duration: 188.651979ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T06:01:56.835407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:56.2506Z","time spent":"584.761272ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T06:01:56.835323Z","caller":"traceutil/trace.go:171","msg":"trace[1376405756] transaction","detail":"{read_only:false; response_revision:21695; number_of_response:1; }","duration":"261.581122ms","start":"2026-09-01T06:01:56.573724Z","end":"2026-09-01T06:01:56.835306Z","steps":["trace[1376405756] 'process raft request' (duration: 261.485629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.836352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.841016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T06:01:56.836436Z","caller":"traceutil/trace.go:171","msg":"trace[1589749770] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:21695; }","duration":"601.99066ms","start":"2026-09-01T06:01:56.234426Z","end":"2026-09-01T06:01:56.836417Z","steps":["trace[1589749770] 'agreement among raft nodes before linearized reading' (duration: 601.157485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:01:56.83648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:01:56.234403Z","time spent":"602.066773ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":116,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-09-01T06:02:04.516667Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19657} {"level":"info","ts":"2026-09-01T06:02:04.655927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19657,"took":"135.946293ms","hash":3338864691,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":30392320,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T06:02:04.655997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3338864691,"revision":19657,"compact-revision":17042} {"level":"info","ts":"2026-09-01T06:06:05.116791Z","caller":"traceutil/trace.go:171","msg":"trace[259598180] linearizableReadLoop","detail":"{readStateIndex:25075; appliedIndex:25074; }","duration":"363.099908ms","start":"2026-09-01T06:06:04.753649Z","end":"2026-09-01T06:06:05.116749Z","steps":["trace[259598180] 'read index received' (duration: 362.740118ms)","trace[259598180] 'applied index is now lower than readState.Index' (duration: 357.97µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T06:06:05.116851Z","caller":"traceutil/trace.go:171","msg":"trace[814500382] transaction","detail":"{read_only:false; response_revision:24257; number_of_response:1; }","duration":"385.661334ms","start":"2026-09-01T06:06:04.731144Z","end":"2026-09-01T06:06:05.116806Z","steps":["trace[814500382] 'process raft request' (duration: 385.303034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:06:05.117087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:06:04.731111Z","time spent":"385.85392ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T06:06:05.117073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.406378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T06:06:05.117171Z","caller":"traceutil/trace.go:171","msg":"trace[1661287470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24257; }","duration":"363.522391ms","start":"2026-09-01T06:06:04.753628Z","end":"2026-09-01T06:06:05.117151Z","steps":["trace[1661287470] 'agreement among raft nodes before linearized reading' (duration: 363.314175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:06:05.117223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:06:04.753545Z","time spent":"363.668575ms","remote":"127.0.0.1:52092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T06:06:05.120696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.027064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T06:06:05.120778Z","caller":"traceutil/trace.go:171","msg":"trace[963627472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24258; }","duration":"306.152488ms","start":"2026-09-01T06:06:04.814601Z","end":"2026-09-01T06:06:05.120754Z","steps":["trace[963627472] 'agreement among raft nodes before linearized reading' (duration: 305.941241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T06:06:05.120824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T06:06:04.814578Z","time spent":"306.235579ms","remote":"127.0.0.1:52390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-01T06:07:04.523875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21733} {"level":"info","ts":"2026-09-01T06:07:04.646906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21733,"took":"119.006833ms","hash":946232836,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":42844160,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-01T06:07:04.646993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":946232836,"revision":21733,"compact-revision":19657} {"level":"info","ts":"2026-09-01T06:09:50.727139Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069d0.wal"} {"level":"info","ts":"2026-09-01T06:12:04.53257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24823} {"level":"info","ts":"2026-09-01T06:12:04.690023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24823,"took":"153.137005ms","hash":1338352719,"current-db-size-bytes":57901056,"current-db-size":"58 MB","current-db-size-in-use-bytes":32296960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T06:12:04.690091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1338352719,"revision":24823,"compact-revision":21733} {"level":"info","ts":"2026-09-01T06:16:10.01329Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"590b810b00f00655","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T06:16:10.020443Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T06:16:10.020557Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}