{"level":"warn","ts":"2026-08-24T20:39:36.373116Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:39:36.373194Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-08-24T20:39:36.373256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:39:36.373269Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-08-24T20:39:36.373284Z","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-08-24T20:39:36.373852Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-08-24T20:39:36.373921Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-08-24T20:39:36.378659Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.513327ms"} {"level":"info","ts":"2026-08-24T20:39:36.386871Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-08-24T20:39:36.387126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T20:39:36.387147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-08-24T20:39:36.387221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T20:39:36.387239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-08-24T20:39:36.387269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-08-24T20:39:36.393405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T20:39:36.39454Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T20:39:36.395693Z","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-08-24T20:39:36.397739Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T20:39:36.397946Z","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-08-24T20:39:36.397957Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T20:39:36.398099Z","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-08-24T20:39:36.39815Z","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-08-24T20:39:36.399708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-08-24T20:39:36.399902Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-08-24T20:39:36.401256Z","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-08-24T20:39:36.401335Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-08-24T20:39:36.401521Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T20:39:36.401552Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T20:39:36.401462Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-08-24T20:39:36.888442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T20:39:36.888504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T20:39:36.888529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-08-24T20:39:36.888551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-08-24T20:39:36.888563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-08-24T20:39:36.888579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-08-24T20:39:36.888592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-08-24T20:39:36.889987Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:36.891009Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T20:39:36.891011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:39:36.891042Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:39:36.89144Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T20:39:36.89148Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T20:39:36.891925Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:36.892159Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:36.892253Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:36.892627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T20:39:36.894866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-08-24T20:39:39.456079Z","caller":"traceutil/trace.go:171","msg":"trace[1897778782] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"144.504042ms","start":"2026-08-24T20:39:39.311509Z","end":"2026-08-24T20:39:39.456013Z","steps":["trace[1897778782] 'read index received' (duration: 137.013944ms)","trace[1897778782] 'applied index is now lower than readState.Index' (duration: 7.487028ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:39:39.456192Z","caller":"traceutil/trace.go:171","msg":"trace[887057052] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"145.357038ms","start":"2026-08-24T20:39:39.3108Z","end":"2026-08-24T20:39:39.456157Z","steps":["trace[887057052] 'process raft request' (duration: 137.779468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:39:39.45651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.92933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T20:39:39.456601Z","caller":"traceutil/trace.go:171","msg":"trace[165646752] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:72; }","duration":"145.137554ms","start":"2026-08-24T20:39:39.311449Z","end":"2026-08-24T20:39:39.456587Z","steps":["trace[165646752] 'agreement among raft nodes before linearized reading' (duration: 144.86366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:39:39.7575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.030534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-24T20:39:39.757614Z","caller":"traceutil/trace.go:171","msg":"trace[1597235501] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"291.951838ms","start":"2026-08-24T20:39:39.465643Z","end":"2026-08-24T20:39:39.757595Z","steps":["trace[1597235501] 'process raft request' (duration: 141.309543ms)","trace[1597235501] 'compare' (duration: 149.922242ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:39:39.868964Z","caller":"traceutil/trace.go:171","msg":"trace[778947373] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"106.172698ms","start":"2026-08-24T20:39:39.762761Z","end":"2026-08-24T20:39:39.868934Z","steps":["trace[778947373] 'process raft request' (duration: 103.102091ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:12.43489Z","caller":"traceutil/trace.go:171","msg":"trace[1566758869] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"101.759228ms","start":"2026-08-24T20:40:12.33311Z","end":"2026-08-24T20:40:12.434869Z","steps":["trace[1566758869] 'process raft request' (duration: 98.688922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:14.848544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.975425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:40:14.848692Z","caller":"traceutil/trace.go:171","msg":"trace[1698732659] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"248.816587ms","start":"2026-08-24T20:40:14.599856Z","end":"2026-08-24T20:40:14.848673Z","steps":["trace[1698732659] 'process raft request' (duration: 142.487086ms)","trace[1698732659] 'compare' (duration: 105.838972ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:40:14.849967Z","caller":"traceutil/trace.go:171","msg":"trace[989145583] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"247.739477ms","start":"2026-08-24T20:40:14.602203Z","end":"2026-08-24T20:40:14.849942Z","steps":["trace[989145583] 'read index received' (duration: 140.156822ms)","trace[989145583] 'applied index is now lower than readState.Index' (duration: 107.581175ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:40:14.850012Z","caller":"traceutil/trace.go:171","msg":"trace[2013172726] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"249.589461ms","start":"2026-08-24T20:40:14.6004Z","end":"2026-08-24T20:40:14.849989Z","steps":["trace[2013172726] 'process raft request' (duration: 249.358117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:14.85015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.91648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:40:14.850264Z","caller":"traceutil/trace.go:171","msg":"trace[2018621653] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"248.042772ms","start":"2026-08-24T20:40:14.602176Z","end":"2026-08-24T20:40:14.850219Z","steps":["trace[2018621653] 'agreement among raft nodes before linearized reading' (duration: 247.860419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:18.5684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.537019ms","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-08-24T20:40:18.568576Z","caller":"traceutil/trace.go:171","msg":"trace[1925076167] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:627; }","duration":"103.747324ms","start":"2026-08-24T20:40:18.464806Z","end":"2026-08-24T20:40:18.568553Z","steps":["trace[1925076167] 'range keys from in-memory index tree' (duration: 103.508969ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:18.581927Z","caller":"traceutil/trace.go:171","msg":"trace[846396793] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"120.43374ms","start":"2026-08-24T20:40:18.461478Z","end":"2026-08-24T20:40:18.581911Z","steps":["trace[846396793] 'process raft request' (duration: 120.295387ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:19.099904Z","caller":"traceutil/trace.go:171","msg":"trace[13833567] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"234.460543ms","start":"2026-08-24T20:40:18.865425Z","end":"2026-08-24T20:40:19.099886Z","steps":["trace[13833567] 'read index received' (duration: 227.650408ms)","trace[13833567] 'applied index is now lower than readState.Index' (duration: 6.809195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:40:19.101003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.553033ms","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-08-24T20:40:19.101101Z","caller":"traceutil/trace.go:171","msg":"trace[96477666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"235.651715ms","start":"2026-08-24T20:40:18.865408Z","end":"2026-08-24T20:40:19.101059Z","steps":["trace[96477666] 'agreement among raft nodes before linearized reading' (duration: 234.558714ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:19.101599Z","caller":"traceutil/trace.go:171","msg":"trace[1937266600] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"419.436957ms","start":"2026-08-24T20:40:18.682068Z","end":"2026-08-24T20:40:19.101505Z","steps":["trace[1937266600] 'process raft request' (duration: 410.914261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:19.101783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:40:18.68201Z","time spent":"419.674632ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:40:19.107308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.706125ms","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-08-24T20:40:19.107368Z","caller":"traceutil/trace.go:171","msg":"trace[1110903255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"241.811698ms","start":"2026-08-24T20:40:18.865539Z","end":"2026-08-24T20:40:19.107351Z","steps":["trace[1110903255] 'agreement among raft nodes before linearized reading' (duration: 241.656825ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:19.107432Z","caller":"traceutil/trace.go:171","msg":"trace[360548223] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"129.950034ms","start":"2026-08-24T20:40:18.977445Z","end":"2026-08-24T20:40:19.107395Z","steps":["trace[360548223] 'process raft request' (duration: 126.928569ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:34.986948Z","caller":"traceutil/trace.go:171","msg":"trace[27061196] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"212.332097ms","start":"2026-08-24T20:40:34.774586Z","end":"2026-08-24T20:40:34.986918Z","steps":["trace[27061196] 'process raft request' (duration: 212.166244ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:46.375937Z","caller":"traceutil/trace.go:171","msg":"trace[759682615] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"122.49326ms","start":"2026-08-24T20:41:46.253412Z","end":"2026-08-24T20:41:46.375906Z","steps":["trace[759682615] 'process raft request' (duration: 122.310336ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:46.401668Z","caller":"traceutil/trace.go:171","msg":"trace[1685388377] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"147.962696ms","start":"2026-08-24T20:41:46.253677Z","end":"2026-08-24T20:41:46.40164Z","steps":["trace[1685388377] 'process raft request' (duration: 147.762601ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:46.40168Z","caller":"traceutil/trace.go:171","msg":"trace[476769902] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1906; }","duration":"138.312445ms","start":"2026-08-24T20:41:46.263345Z","end":"2026-08-24T20:41:46.401658Z","steps":["trace[476769902] 'read index received' (duration: 112.353451ms)","trace[476769902] 'applied index is now lower than readState.Index' (duration: 25.958084ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:46.401739Z","caller":"traceutil/trace.go:171","msg":"trace[99852465] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"147.861874ms","start":"2026-08-24T20:41:46.253849Z","end":"2026-08-24T20:41:46.401711Z","steps":["trace[99852465] 'process raft request' (duration: 147.710141ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:46.401826Z","caller":"traceutil/trace.go:171","msg":"trace[843313857] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"146.588219ms","start":"2026-08-24T20:41:46.255203Z","end":"2026-08-24T20:41:46.401792Z","steps":["trace[843313857] 'process raft request' (duration: 146.417226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:46.402447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.08983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T20:41:46.402498Z","caller":"traceutil/trace.go:171","msg":"trace[623023111] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1844; }","duration":"139.187761ms","start":"2026-08-24T20:41:46.263296Z","end":"2026-08-24T20:41:46.402484Z","steps":["trace[623023111] 'agreement among raft nodes before linearized reading' (duration: 138.448298ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:00.869197Z","caller":"traceutil/trace.go:171","msg":"trace[72056112] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"118.333149ms","start":"2026-08-24T20:42:00.750844Z","end":"2026-08-24T20:42:00.869178Z","steps":["trace[72056112] 'process raft request' (duration: 104.820162ms)","trace[72056112] 'compare' (duration: 13.398534ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:42:37.574289Z","caller":"traceutil/trace.go:171","msg":"trace[770861487] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"227.141366ms","start":"2026-08-24T20:42:37.347117Z","end":"2026-08-24T20:42:37.574259Z","steps":["trace[770861487] 'process raft request' (duration: 225.976244ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:37.574529Z","caller":"traceutil/trace.go:171","msg":"trace[1453669789] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"194.632808ms","start":"2026-08-24T20:42:37.379863Z","end":"2026-08-24T20:42:37.574495Z","steps":["trace[1453669789] 'process raft request' (duration: 194.318773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:42:37.574713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.289046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:42:37.574865Z","caller":"traceutil/trace.go:171","msg":"trace[1378069960] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2168; }","duration":"140.51373ms","start":"2026-08-24T20:42:37.434319Z","end":"2026-08-24T20:42:37.574833Z","steps":["trace[1378069960] 'agreement among raft nodes before linearized reading' (duration: 140.234014ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:42:37.575157Z","caller":"traceutil/trace.go:171","msg":"trace[1776723931] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2243; }","duration":"140.127513ms","start":"2026-08-24T20:42:37.434377Z","end":"2026-08-24T20:42:37.574504Z","steps":["trace[1776723931] 'read index received' (duration: 138.668725ms)","trace[1776723931] 'applied index is now lower than readState.Index' (duration: 1.419737ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:43:25.863609Z","caller":"traceutil/trace.go:171","msg":"trace[601464508] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"113.207803ms","start":"2026-08-24T20:43:25.750369Z","end":"2026-08-24T20:43:25.863576Z","steps":["trace[601464508] 'process raft request' (duration: 112.916778ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:59.349162Z","caller":"traceutil/trace.go:171","msg":"trace[1595019987] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"125.418349ms","start":"2026-08-24T20:43:59.223685Z","end":"2026-08-24T20:43:59.349104Z","steps":["trace[1595019987] 'process raft request' (duration: 125.289336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:59.78409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.984727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:43:59.784238Z","caller":"traceutil/trace.go:171","msg":"trace[1528406639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2807; }","duration":"179.150951ms","start":"2026-08-24T20:43:59.605023Z","end":"2026-08-24T20:43:59.784174Z","steps":["trace[1528406639] 'range keys from in-memory index tree' (duration: 178.904566ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:46:19.722098Z","caller":"traceutil/trace.go:171","msg":"trace[2036455737] linearizableReadLoop","detail":"{readStateIndex:3885; appliedIndex:3884; }","duration":"117.03959ms","start":"2026-08-24T20:46:19.605034Z","end":"2026-08-24T20:46:19.722074Z","steps":["trace[2036455737] 'read index received' (duration: 116.778895ms)","trace[2036455737] 'applied index is now lower than readState.Index' (duration: 259.754µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:46:19.722212Z","caller":"traceutil/trace.go:171","msg":"trace[969716573] transaction","detail":"{read_only:false; response_revision:3747; number_of_response:1; }","duration":"187.609861ms","start":"2026-08-24T20:46:19.534581Z","end":"2026-08-24T20:46:19.722191Z","steps":["trace[969716573] 'process raft request' (duration: 187.315595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:46:19.722281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.187912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:46:19.722375Z","caller":"traceutil/trace.go:171","msg":"trace[443050648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3747; }","duration":"117.345555ms","start":"2026-08-24T20:46:19.604981Z","end":"2026-08-24T20:46:19.722326Z","steps":["trace[443050648] 'agreement among raft nodes before linearized reading' (duration: 117.200863ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:47:44.819721Z","caller":"traceutil/trace.go:171","msg":"trace[1755156562] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"179.837939ms","start":"2026-08-24T20:47:44.639862Z","end":"2026-08-24T20:47:44.8197Z","steps":["trace[1755156562] 'process raft request' (duration: 179.728087ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:47:44.820191Z","caller":"traceutil/trace.go:171","msg":"trace[1394250438] linearizableReadLoop","detail":"{readStateIndex:5122; appliedIndex:5122; }","duration":"154.418709ms","start":"2026-08-24T20:47:44.665748Z","end":"2026-08-24T20:47:44.820167Z","steps":["trace[1394250438] 'read index received' (duration: 154.406798ms)","trace[1394250438] 'applied index is now lower than readState.Index' (duration: 9.831µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:47:44.820592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.826017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786948"} {"level":"info","ts":"2026-08-24T20:47:44.820615Z","caller":"traceutil/trace.go:171","msg":"trace[1438407179] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4939; }","duration":"154.892659ms","start":"2026-08-24T20:47:44.665716Z","end":"2026-08-24T20:47:44.820609Z","steps":["trace[1438407179] 'agreement among raft nodes before linearized reading' (duration: 154.532481ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:47:55.216488Z","caller":"traceutil/trace.go:171","msg":"trace[608063886] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"158.384786ms","start":"2026-08-24T20:47:55.058071Z","end":"2026-08-24T20:47:55.216456Z","steps":["trace[608063886] 'process raft request' (duration: 158.301404ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:47:55.217099Z","caller":"traceutil/trace.go:171","msg":"trace[1755100008] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"171.616891ms","start":"2026-08-24T20:47:55.045432Z","end":"2026-08-24T20:47:55.217049Z","steps":["trace[1755100008] 'process raft request' (duration: 86.184924ms)","trace[1755100008] 'compare' (duration: 84.429658ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:47:55.657442Z","caller":"traceutil/trace.go:171","msg":"trace[90167373] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"114.356496ms","start":"2026-08-24T20:47:55.543064Z","end":"2026-08-24T20:47:55.657421Z","steps":["trace[90167373] 'process raft request' (duration: 114.038969ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:47:55.657633Z","caller":"traceutil/trace.go:171","msg":"trace[300337054] transaction","detail":"{read_only:false; response_revision:5171; number_of_response:1; }","duration":"114.427478ms","start":"2026-08-24T20:47:55.543195Z","end":"2026-08-24T20:47:55.657622Z","steps":["trace[300337054] 'process raft request' (duration: 114.004059ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:48:03.088095Z","caller":"traceutil/trace.go:171","msg":"trace[825258180] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"116.533799ms","start":"2026-08-24T20:48:02.971534Z","end":"2026-08-24T20:48:03.088068Z","steps":["trace[825258180] 'read index received' (duration: 32.082229ms)","trace[825258180] 'applied index is now lower than readState.Index' (duration: 84.45084ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:48:03.088159Z","caller":"traceutil/trace.go:171","msg":"trace[1730445312] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"134.696488ms","start":"2026-08-24T20:48:02.953411Z","end":"2026-08-24T20:48:03.088107Z","steps":["trace[1730445312] 'process raft request' (duration: 50.211788ms)","trace[1730445312] 'compare' (duration: 84.306917ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:48:03.08853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.972557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a1bda5a-32d2-44d8-b47a-37d50e55b233\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-24T20:48:03.088612Z","caller":"traceutil/trace.go:171","msg":"trace[740788884] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a1bda5a-32d2-44d8-b47a-37d50e55b233; range_end:; response_count:1; response_revision:5325; }","duration":"117.083889ms","start":"2026-08-24T20:48:02.971508Z","end":"2026-08-24T20:48:03.088592Z","steps":["trace[740788884] 'agreement among raft nodes before linearized reading' (duration: 116.656141ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:48:03.251201Z","caller":"traceutil/trace.go:171","msg":"trace[1173604825] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"119.303992ms","start":"2026-08-24T20:48:03.131443Z","end":"2026-08-24T20:48:03.250747Z","steps":["trace[1173604825] 'process raft request' (duration: 119.105618ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:48:52.517556Z","caller":"traceutil/trace.go:171","msg":"trace[1636175488] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"128.690081ms","start":"2026-08-24T20:48:52.388828Z","end":"2026-08-24T20:48:52.517518Z","steps":["trace[1636175488] 'process raft request' (duration: 128.414765ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:49:37.046724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-08-24T20:49:37.138705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"89.706284ms","hash":3459653847,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":32419840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T20:49:37.138781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3459653847,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-08-24T20:53:54.80536Z","caller":"traceutil/trace.go:171","msg":"trace[2085801283] linearizableReadLoop","detail":"{readStateIndex:8368; appliedIndex:8367; }","duration":"200.749748ms","start":"2026-08-24T20:53:54.60458Z","end":"2026-08-24T20:53:54.80533Z","steps":["trace[2085801283] 'read index received' (duration: 200.674207ms)","trace[2085801283] 'applied index is now lower than readState.Index' (duration: 74.121µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:53:54.805515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.918961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:53:54.805564Z","caller":"traceutil/trace.go:171","msg":"trace[409105817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8085; }","duration":"201.035753ms","start":"2026-08-24T20:53:54.604518Z","end":"2026-08-24T20:53:54.805554Z","steps":["trace[409105817] 'agreement among raft nodes before linearized reading' (duration: 200.921151ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:53:54.805481Z","caller":"traceutil/trace.go:171","msg":"trace[1602822313] transaction","detail":"{read_only:false; response_revision:8085; number_of_response:1; }","duration":"257.802114ms","start":"2026-08-24T20:53:54.54764Z","end":"2026-08-24T20:53:54.805442Z","steps":["trace[1602822313] 'process raft request' (duration: 257.515779ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:53:54.977794Z","caller":"traceutil/trace.go:171","msg":"trace[163557766] linearizableReadLoop","detail":"{readStateIndex:8369; appliedIndex:8368; }","duration":"170.938235ms","start":"2026-08-24T20:53:54.806822Z","end":"2026-08-24T20:53:54.97776Z","steps":["trace[163557766] 'read index received' (duration: 125.692966ms)","trace[163557766] 'applied index is now lower than readState.Index' (duration: 45.243919ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:53:54.977851Z","caller":"traceutil/trace.go:171","msg":"trace[2102816111] transaction","detail":"{read_only:false; response_revision:8086; number_of_response:1; }","duration":"315.332019ms","start":"2026-08-24T20:53:54.662486Z","end":"2026-08-24T20:53:54.977818Z","steps":["trace[2102816111] 'process raft request' (duration: 270.08452ms)","trace[2102816111] 'compare' (duration: 45.050076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:53:54.978084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:53:54.662466Z","time spent":"315.460622ms","remote":"127.0.0.1:48646","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-08-24T20:53:54.978149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.800693ms","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-08-24T20:53:54.97822Z","caller":"traceutil/trace.go:171","msg":"trace[529048816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8086; }","duration":"119.917534ms","start":"2026-08-24T20:53:54.858282Z","end":"2026-08-24T20:53:54.9782Z","steps":["trace[529048816] 'agreement among raft nodes before linearized reading' (duration: 119.68145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:53:54.978216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.820183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-08-24T20:53:54.978288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.468732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-24T20:53:54.978345Z","caller":"traceutil/trace.go:171","msg":"trace[989902494] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8086; }","duration":"112.543084ms","start":"2026-08-24T20:53:54.865782Z","end":"2026-08-24T20:53:54.978325Z","steps":["trace[989902494] 'agreement among raft nodes before linearized reading' (duration: 112.190577ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:53:54.978374Z","caller":"traceutil/trace.go:171","msg":"trace[844514378] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8086; }","duration":"119.991536ms","start":"2026-08-24T20:53:54.858333Z","end":"2026-08-24T20:53:54.978325Z","steps":["trace[844514378] 'agreement among raft nodes before linearized reading' (duration: 119.715881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:53:54.978503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.365864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:53:54.978589Z","caller":"traceutil/trace.go:171","msg":"trace[155163312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8086; }","duration":"171.808752ms","start":"2026-08-24T20:53:54.806759Z","end":"2026-08-24T20:53:54.978567Z","steps":["trace[155163312] 'agreement among raft nodes before linearized reading' (duration: 171.162929ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:54:07.67098Z","caller":"traceutil/trace.go:171","msg":"trace[1929292045] transaction","detail":"{read_only:false; response_revision:8153; number_of_response:1; }","duration":"175.044044ms","start":"2026-08-24T20:54:07.495891Z","end":"2026-08-24T20:54:07.670935Z","steps":["trace[1929292045] 'process raft request' (duration: 119.520507ms)","trace[1929292045] 'compare' (duration: 55.288203ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:54:34.793219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.991142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:54:34.793317Z","caller":"traceutil/trace.go:171","msg":"trace[82780301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8353; }","duration":"189.142404ms","start":"2026-08-24T20:54:34.604152Z","end":"2026-08-24T20:54:34.793294Z","steps":["trace[82780301] 'range keys from in-memory index tree' (duration: 188.90116ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:54:34.793387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.966176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-24T20:54:34.793432Z","caller":"traceutil/trace.go:171","msg":"trace[1788172492] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8353; }","duration":"247.040848ms","start":"2026-08-24T20:54:34.54638Z","end":"2026-08-24T20:54:34.793421Z","steps":["trace[1788172492] 'range keys from in-memory index tree' (duration: 246.605569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:54:34.793518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.514804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-24T20:54:34.79364Z","caller":"traceutil/trace.go:171","msg":"trace[1000948768] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8353; }","duration":"198.698468ms","start":"2026-08-24T20:54:34.594915Z","end":"2026-08-24T20:54:34.793614Z","steps":["trace[1000948768] 'range keys from in-memory index tree' (duration: 198.31476ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:54:37.054494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6180} {"level":"info","ts":"2026-08-24T20:54:37.15107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6180,"took":"94.721799ms","hash":2085963699,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":23195648,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-24T20:54:37.151141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2085963699,"revision":6180,"compact-revision":3013} {"level":"info","ts":"2026-08-24T20:54:41.021361Z","caller":"traceutil/trace.go:171","msg":"trace[997788588] linearizableReadLoop","detail":"{readStateIndex:8685; appliedIndex:8684; }","duration":"112.448162ms","start":"2026-08-24T20:54:40.908888Z","end":"2026-08-24T20:54:41.021336Z","steps":["trace[997788588] 'read index received' (duration: 112.370639ms)","trace[997788588] 'applied index is now lower than readState.Index' (duration: 76.573µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:54:41.021391Z","caller":"traceutil/trace.go:171","msg":"trace[354407681] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"124.253298ms","start":"2026-08-24T20:54:40.897103Z","end":"2026-08-24T20:54:41.021357Z","steps":["trace[354407681] 'process raft request' (duration: 124.087664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:54:41.021619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.660635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T20:54:41.021674Z","caller":"traceutil/trace.go:171","msg":"trace[300014187] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8388; }","duration":"112.806348ms","start":"2026-08-24T20:54:40.908846Z","end":"2026-08-24T20:54:41.021652Z","steps":["trace[300014187] 'agreement among raft nodes before linearized reading' (duration: 112.635255ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:54:47.365947Z","caller":"traceutil/trace.go:171","msg":"trace[2095809649] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"104.854705ms","start":"2026-08-24T20:54:47.261061Z","end":"2026-08-24T20:54:47.365915Z","steps":["trace[2095809649] 'process raft request' (duration: 79.523688ms)","trace[2095809649] 'compare' (duration: 24.784247ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:54:47.664984Z","caller":"traceutil/trace.go:171","msg":"trace[1125239066] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"226.953511ms","start":"2026-08-24T20:54:47.438Z","end":"2026-08-24T20:54:47.664954Z","steps":["trace[1125239066] 'process raft request' (duration: 149.423932ms)","trace[1125239066] 'compare' (duration: 77.267765ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:55:43.915061Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002474.wal"} {"level":"info","ts":"2026-08-24T20:56:05.597868Z","caller":"traceutil/trace.go:171","msg":"trace[683630873] transaction","detail":"{read_only:false; response_revision:9218; number_of_response:1; }","duration":"101.246088ms","start":"2026-08-24T20:56:05.496595Z","end":"2026-08-24T20:56:05.597841Z","steps":["trace[683630873] 'process raft request' (duration: 101.101624ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:57:08.541521Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T20:57:08.545997Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-24T20:57:08.546085Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-24T20:59:37.061168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8364} {"level":"info","ts":"2026-08-24T20:59:37.143618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8364,"took":"80.080702ms","hash":2666715455,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":26624000,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-24T20:59:37.143674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2666715455,"revision":8364,"compact-revision":6180} {"level":"warn","ts":"2026-08-24T20:59:41.480476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.24855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:59:41.480693Z","caller":"traceutil/trace.go:171","msg":"trace[1633498599] transaction","detail":"{read_only:false; response_revision:11026; number_of_response:1; }","duration":"130.17277ms","start":"2026-08-24T20:59:41.350497Z","end":"2026-08-24T20:59:41.48067Z","steps":["trace[1633498599] 'process raft request' (duration: 130.085708ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:59:41.480704Z","caller":"traceutil/trace.go:171","msg":"trace[1850161576] transaction","detail":"{read_only:false; response_revision:11025; number_of_response:1; }","duration":"130.634399ms","start":"2026-08-24T20:59:41.350052Z","end":"2026-08-24T20:59:41.480686Z","steps":["trace[1850161576] 'compare' (duration: 122.096157ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:59:41.611176Z","caller":"traceutil/trace.go:171","msg":"trace[1537013562] transaction","detail":"{read_only:false; response_revision:11027; number_of_response:1; }","duration":"183.288147ms","start":"2026-08-24T20:59:41.427846Z","end":"2026-08-24T20:59:41.611134Z","steps":["trace[1537013562] 'process raft request' (duration: 183.031732ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:04:37.06719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11002} {"level":"info","ts":"2026-08-24T21:04:37.149211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11002,"took":"79.97282ms","hash":3593369274,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":27824128,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-24T21:04:37.149331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593369274,"revision":11002,"compact-revision":8364} {"level":"info","ts":"2026-08-24T21:05:52.320451Z","caller":"traceutil/trace.go:171","msg":"trace[1601247972] transaction","detail":"{read_only:false; response_revision:13563; number_of_response:1; }","duration":"110.90258ms","start":"2026-08-24T21:05:52.209509Z","end":"2026-08-24T21:05:52.320412Z","steps":["trace[1601247972] 'process raft request' (duration: 88.94797ms)","trace[1601247972] 'compare' (duration: 21.841388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T21:06:08.995715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.593383ms","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-08-24T21:06:08.995828Z","caller":"traceutil/trace.go:171","msg":"trace[421846215] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13664; }","duration":"396.773846ms","start":"2026-08-24T21:06:08.599034Z","end":"2026-08-24T21:06:08.995808Z","steps":["trace[421846215] 'range keys from in-memory index tree' (duration: 396.376339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:08.995903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:06:08.599Z","time spent":"396.882498ms","remote":"127.0.0.1:48646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T21:06:08.996077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.267019ms","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-08-24T21:06:08.996145Z","caller":"traceutil/trace.go:171","msg":"trace[2002959927] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13664; }","duration":"428.38135ms","start":"2026-08-24T21:06:08.567748Z","end":"2026-08-24T21:06:08.996129Z","steps":["trace[2002959927] 'count revisions from in-memory index tree' (duration: 428.179906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:08.996495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:06:08.56773Z","time spent":"428.704137ms","remote":"127.0.0.1:59682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-24T21:06:08.996939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.712555ms","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-08-24T21:06:08.997048Z","caller":"traceutil/trace.go:171","msg":"trace[1340576581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13664; }","duration":"293.867959ms","start":"2026-08-24T21:06:08.703159Z","end":"2026-08-24T21:06:08.997027Z","steps":["trace[1340576581] 'range keys from in-memory index tree' (duration: 292.88705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:08.997139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.237419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930262"} {"level":"info","ts":"2026-08-24T21:06:08.997221Z","caller":"traceutil/trace.go:171","msg":"trace[1188621699] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13664; }","duration":"188.360062ms","start":"2026-08-24T21:06:08.808845Z","end":"2026-08-24T21:06:08.997205Z","steps":["trace[1188621699] 'range keys from in-memory index tree' (duration: 186.70784ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:06:09.223179Z","caller":"traceutil/trace.go:171","msg":"trace[2084869116] linearizableReadLoop","detail":"{readStateIndex:14134; appliedIndex:14133; }","duration":"163.935845ms","start":"2026-08-24T21:06:09.05922Z","end":"2026-08-24T21:06:09.223156Z","steps":["trace[2084869116] 'read index received' (duration: 163.726111ms)","trace[2084869116] 'applied index is now lower than readState.Index' (duration: 208.654µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:06:09.223303Z","caller":"traceutil/trace.go:171","msg":"trace[37309281] transaction","detail":"{read_only:false; response_revision:13668; number_of_response:1; }","duration":"210.739319ms","start":"2026-08-24T21:06:09.012553Z","end":"2026-08-24T21:06:09.223292Z","steps":["trace[37309281] 'process raft request' (duration: 210.451354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:09.223737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.507746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7911"} {"level":"info","ts":"2026-08-24T21:06:09.223783Z","caller":"traceutil/trace.go:171","msg":"trace[613884355] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:13668; }","duration":"164.567167ms","start":"2026-08-24T21:06:09.059206Z","end":"2026-08-24T21:06:09.223773Z","steps":["trace[613884355] 'agreement among raft nodes before linearized reading' (duration: 164.392614ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:07:49.896956Z","caller":"traceutil/trace.go:171","msg":"trace[845170122] linearizableReadLoop","detail":"{readStateIndex:15373; appliedIndex:15372; }","duration":"140.18507ms","start":"2026-08-24T21:07:49.756747Z","end":"2026-08-24T21:07:49.896932Z","steps":["trace[845170122] 'read index received' (duration: 140.071638ms)","trace[845170122] 'applied index is now lower than readState.Index' (duration: 112.302µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:07:49.897316Z","caller":"traceutil/trace.go:171","msg":"trace[1627634561] transaction","detail":"{read_only:false; response_revision:14871; number_of_response:1; }","duration":"180.183896ms","start":"2026-08-24T21:07:49.71712Z","end":"2026-08-24T21:07:49.897304Z","steps":["trace[1627634561] 'process raft request' (duration: 179.648385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:07:49.897611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.858683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-24T21:07:49.897658Z","caller":"traceutil/trace.go:171","msg":"trace[644814379] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14871; }","duration":"140.939195ms","start":"2026-08-24T21:07:49.756706Z","end":"2026-08-24T21:07:49.897645Z","steps":["trace[644814379] 'agreement among raft nodes before linearized reading' (duration: 140.803113ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:07:50.070993Z","caller":"traceutil/trace.go:171","msg":"trace[1680833854] transaction","detail":"{read_only:false; response_revision:14872; number_of_response:1; }","duration":"168.145094ms","start":"2026-08-24T21:07:49.902821Z","end":"2026-08-24T21:07:50.070966Z","steps":["trace[1680833854] 'process raft request' (duration: 75.177396ms)","trace[1680833854] 'compare' (duration: 92.717643ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:07:50.071442Z","caller":"traceutil/trace.go:171","msg":"trace[1666571649] transaction","detail":"{read_only:false; response_revision:14873; number_of_response:1; }","duration":"167.834539ms","start":"2026-08-24T21:07:49.903593Z","end":"2026-08-24T21:07:50.071428Z","steps":["trace[1666571649] 'process raft request' (duration: 167.289959ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:07:50.071476Z","caller":"traceutil/trace.go:171","msg":"trace[1459582339] linearizableReadLoop","detail":"{readStateIndex:15376; appliedIndex:15373; }","duration":"137.816444ms","start":"2026-08-24T21:07:49.933626Z","end":"2026-08-24T21:07:50.071442Z","steps":["trace[1459582339] 'read index received' (duration: 44.383717ms)","trace[1459582339] 'applied index is now lower than readState.Index' (duration: 93.431457ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:07:50.071531Z","caller":"traceutil/trace.go:171","msg":"trace[482470720] transaction","detail":"{read_only:false; response_revision:14874; number_of_response:1; }","duration":"166.396532ms","start":"2026-08-24T21:07:49.905095Z","end":"2026-08-24T21:07:50.071491Z","steps":["trace[482470720] 'process raft request' (duration: 166.118087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:07:50.071805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.61253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-08-24T21:07:50.071819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.784547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ggv2r\" ","response":"range_response_count:1 size:22124"} {"level":"info","ts":"2026-08-24T21:07:50.071868Z","caller":"traceutil/trace.go:171","msg":"trace[449324159] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14874; }","duration":"127.699931ms","start":"2026-08-24T21:07:49.944149Z","end":"2026-08-24T21:07:50.071849Z","steps":["trace[449324159] 'agreement among raft nodes before linearized reading' (duration: 127.482197ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:07:50.071879Z","caller":"traceutil/trace.go:171","msg":"trace[578499030] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ggv2r; range_end:; response_count:1; response_revision:14874; }","duration":"122.875019ms","start":"2026-08-24T21:07:49.948991Z","end":"2026-08-24T21:07:50.071866Z","steps":["trace[578499030] 'agreement among raft nodes before linearized reading' (duration: 122.718216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:07:50.072858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.57231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ggv2r\" ","response":"range_response_count:1 size:22124"} {"level":"info","ts":"2026-08-24T21:07:50.072963Z","caller":"traceutil/trace.go:171","msg":"trace[930416445] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ggv2r; range_end:; response_count:1; response_revision:14874; }","duration":"139.359314ms","start":"2026-08-24T21:07:49.93358Z","end":"2026-08-24T21:07:50.07294Z","steps":["trace[930416445] 'agreement among raft nodes before linearized reading' (duration: 137.966637ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:09:37.075112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13033} {"level":"info","ts":"2026-08-24T21:09:37.155663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13033,"took":"77.596953ms","hash":819664863,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":31592448,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T21:09:37.155897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":819664863,"revision":13033,"compact-revision":11002} {"level":"warn","ts":"2026-08-24T21:13:35.111571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.605738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-66c6g\" ","response":"range_response_count:1 size:9800"} {"level":"warn","ts":"2026-08-24T21:13:35.111604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.743484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/openstack/heat-api\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T21:13:35.111633Z","caller":"traceutil/trace.go:171","msg":"trace[293124523] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-66c6g; range_end:; response_count:1; response_revision:17495; }","duration":"464.722401ms","start":"2026-08-24T21:13:34.646899Z","end":"2026-08-24T21:13:35.111622Z","steps":["trace[293124523] 'range keys from in-memory index tree' (duration: 464.454996ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:13:35.111639Z","caller":"traceutil/trace.go:171","msg":"trace[551117289] range","detail":"{range_begin:/registry/poddisruptionbudgets/openstack/heat-api; range_end:; response_count:0; response_revision:17495; }","duration":"356.807376ms","start":"2026-08-24T21:13:34.754823Z","end":"2026-08-24T21:13:35.11163Z","steps":["trace[551117289] 'range keys from in-memory index tree' (duration: 356.687293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:13:35.11166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:13:34.754807Z","time spent":"356.847916ms","remote":"127.0.0.1:48706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/openstack/heat-api\" "} {"level":"warn","ts":"2026-08-24T21:13:35.11166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:13:34.646874Z","time spent":"464.779492ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-66c6g\" "} {"level":"info","ts":"2026-08-24T21:13:35.219929Z","caller":"traceutil/trace.go:171","msg":"trace[2126551645] transaction","detail":"{read_only:false; response_revision:17496; number_of_response:1; }","duration":"104.426149ms","start":"2026-08-24T21:13:35.115472Z","end":"2026-08-24T21:13:35.219898Z","steps":["trace[2126551645] 'process raft request' (duration: 104.269976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:13:35.247739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.904009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T21:13:35.247842Z","caller":"traceutil/trace.go:171","msg":"trace[1947768499] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:0; response_revision:17497; }","duration":"124.020101ms","start":"2026-08-24T21:13:35.1238Z","end":"2026-08-24T21:13:35.24782Z","steps":["trace[1947768499] 'agreement among raft nodes before linearized reading' (duration: 123.830838ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:13:35.248163Z","caller":"traceutil/trace.go:171","msg":"trace[1241511492] transaction","detail":"{read_only:false; response_revision:17497; number_of_response:1; }","duration":"123.992241ms","start":"2026-08-24T21:13:35.124159Z","end":"2026-08-24T21:13:35.248152Z","steps":["trace[1241511492] 'process raft request' (duration: 123.35913ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:14:37.084527Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15636} {"level":"info","ts":"2026-08-24T21:14:37.190857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15636,"took":"102.199506ms","hash":875277167,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":34631680,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-24T21:14:37.190922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":875277167,"revision":15636,"compact-revision":13033} {"level":"info","ts":"2026-08-24T21:17:06.055419Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T21:17:06.060438Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-24T21:17:06.060558Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-24T21:18:20.919478Z","caller":"traceutil/trace.go:171","msg":"trace[1700514586] linearizableReadLoop","detail":"{readStateIndex:20426; appliedIndex:20425; }","duration":"208.156345ms","start":"2026-08-24T21:18:20.7113Z","end":"2026-08-24T21:18:20.919457Z","steps":["trace[1700514586] 'read index received' (duration: 118.806383ms)","trace[1700514586] 'applied index is now lower than readState.Index' (duration: 89.348762ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:18:20.919542Z","caller":"traceutil/trace.go:171","msg":"trace[2056494222] transaction","detail":"{read_only:false; response_revision:19765; number_of_response:1; }","duration":"282.571142ms","start":"2026-08-24T21:18:20.636938Z","end":"2026-08-24T21:18:20.919509Z","steps":["trace[2056494222] 'process raft request' (duration: 193.170109ms)","trace[2056494222] 'compare' (duration: 89.147219ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T21:18:20.919662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.345459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-24T21:18:20.919703Z","caller":"traceutil/trace.go:171","msg":"trace[1938543501] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:19765; }","duration":"208.42323ms","start":"2026-08-24T21:18:20.711269Z","end":"2026-08-24T21:18:20.919692Z","steps":["trace[1938543501] 'agreement among raft nodes before linearized reading' (duration: 208.284597ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:18:21.076995Z","caller":"traceutil/trace.go:171","msg":"trace[755447649] linearizableReadLoop","detail":"{readStateIndex:20429; appliedIndex:20428; }","duration":"112.792827ms","start":"2026-08-24T21:18:20.964177Z","end":"2026-08-24T21:18:21.07697Z","steps":["trace[755447649] 'read index received' (duration: 104.897928ms)","trace[755447649] 'applied index is now lower than readState.Index' (duration: 7.893819ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:18:21.077035Z","caller":"traceutil/trace.go:171","msg":"trace[807671321] transaction","detail":"{read_only:false; response_revision:19767; number_of_response:1; }","duration":"121.711718ms","start":"2026-08-24T21:18:20.955264Z","end":"2026-08-24T21:18:21.076976Z","steps":["trace[807671321] 'process raft request' (duration: 113.86722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:18:21.077207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.000551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T21:18:21.077428Z","caller":"traceutil/trace.go:171","msg":"trace[1336529420] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:19767; }","duration":"113.267027ms","start":"2026-08-24T21:18:20.964131Z","end":"2026-08-24T21:18:21.077398Z","steps":["trace[1336529420] 'agreement among raft nodes before linearized reading' (duration: 112.94611ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:19:37.094105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18212} {"level":"info","ts":"2026-08-24T21:19:37.207287Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18212,"took":"108.835143ms","hash":141245250,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":31096832,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T21:19:37.207355Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":141245250,"revision":18212,"compact-revision":15636} {"level":"info","ts":"2026-08-24T21:21:51.630825Z","caller":"traceutil/trace.go:171","msg":"trace[1418057368] transaction","detail":"{read_only:false; response_revision:22087; number_of_response:1; }","duration":"210.893014ms","start":"2026-08-24T21:21:51.419906Z","end":"2026-08-24T21:21:51.630799Z","steps":["trace[1418057368] 'process raft request' (duration: 210.485085ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:24:37.10093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20480} {"level":"info","ts":"2026-08-24T21:24:37.21313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20480,"took":"106.985521ms","hash":1759104964,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":43171840,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-24T21:24:37.213193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1759104964,"revision":20480,"compact-revision":18212} {"level":"info","ts":"2026-08-24T21:29:37.107869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23604} {"level":"info","ts":"2026-08-24T21:29:37.240886Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23604,"took":"129.379288ms","hash":3091599617,"current-db-size-bytes":58929152,"current-db-size":"59 MB","current-db-size-in-use-bytes":30208000,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T21:29:37.240943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3091599617,"revision":23604,"compact-revision":20480}