{"level":"warn","ts":"2026-08-14T23:14:52.356888Z","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-14T23:14:52.356961Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-14T23:14:52.357018Z","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-14T23:14:52.357026Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-14T23:14:52.357042Z","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-14T23:14:52.358859Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-08-14T23:14:52.359127Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-14T23:14:52.363488Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.973557ms"} {"level":"info","ts":"2026-08-14T23:14:52.370689Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-08-14T23:14:52.370797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T23:14:52.370837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-08-14T23:14:52.37087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T23:14:52.370892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-08-14T23:14:52.370972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-08-14T23:14:52.37636Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T23:14:52.380261Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T23:14:52.381787Z","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-14T23:14:52.384091Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T23:14:52.38428Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T23:14:52.384399Z","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-14T23:14:52.384476Z","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-14T23:14:52.384493Z","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-14T23:14:52.385499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-08-14T23:14:52.385711Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-08-14T23:14:52.386811Z","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-14T23:14:52.38693Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-14T23:14:52.387015Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-08-14T23:14:52.387059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T23:14:52.387112Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T23:14:52.472108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T23:14:52.472168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T23:14:52.472195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-08-14T23:14:52.472214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-08-14T23:14:52.472225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-14T23:14:52.472241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-08-14T23:14:52.472261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-08-14T23:14:52.473278Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:52.474212Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T23:14:52.474228Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:14:52.474367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:14:52.474599Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T23:14:52.474669Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T23:14:52.474802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:52.474912Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:52.474968Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:14:52.477539Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-08-14T23:14:52.4779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T23:15:29.82697Z","caller":"traceutil/trace.go:171","msg":"trace[473192102] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"227.232234ms","start":"2026-08-14T23:15:29.599714Z","end":"2026-08-14T23:15:29.826946Z","steps":["trace[473192102] 'read index received' (duration: 226.668443ms)","trace[473192102] 'applied index is now lower than readState.Index' (duration: 562.721µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:15:29.827022Z","caller":"traceutil/trace.go:171","msg":"trace[72743885] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"232.739159ms","start":"2026-08-14T23:15:29.594242Z","end":"2026-08-14T23:15:29.826981Z","steps":["trace[72743885] 'process raft request' (duration: 232.27986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:15:29.827209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.467559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-08-14T23:15:29.827284Z","caller":"traceutil/trace.go:171","msg":"trace[1375351046] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:603; }","duration":"227.607521ms","start":"2026-08-14T23:15:29.599666Z","end":"2026-08-14T23:15:29.827273Z","steps":["trace[1375351046] 'agreement among raft nodes before linearized reading' (duration: 227.394937ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:15:35.010917Z","caller":"traceutil/trace.go:171","msg":"trace[1427135929] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"428.273781ms","start":"2026-08-14T23:15:34.582597Z","end":"2026-08-14T23:15:35.010871Z","steps":["trace[1427135929] 'process raft request' (duration: 422.731726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:15:35.011587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:15:34.582566Z","time spent":"428.458914ms","remote":"127.0.0.1:36318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:15:35.024276Z","caller":"traceutil/trace.go:171","msg":"trace[138082213] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"340.038586ms","start":"2026-08-14T23:15:34.684214Z","end":"2026-08-14T23:15:35.024253Z","steps":["trace[138082213] 'process raft request' (duration: 339.970635ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:15:35.024358Z","caller":"traceutil/trace.go:171","msg":"trace[1656007642] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"394.549531ms","start":"2026-08-14T23:15:34.629774Z","end":"2026-08-14T23:15:35.024323Z","steps":["trace[1656007642] 'process raft request' (duration: 394.272185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:15:35.024459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:15:34.629738Z","time spent":"394.674383ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T23:15:35.02447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:15:34.684183Z","time spent":"340.181419ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:16:16.785059Z","caller":"traceutil/trace.go:171","msg":"trace[106052073] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"153.557999ms","start":"2026-08-14T23:16:16.631476Z","end":"2026-08-14T23:16:16.785034Z","steps":["trace[106052073] 'process raft request' (duration: 153.390556ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:46.168769Z","caller":"traceutil/trace.go:171","msg":"trace[1420904393] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2217; }","duration":"115.969558ms","start":"2026-08-14T23:17:46.052772Z","end":"2026-08-14T23:17:46.168741Z","steps":["trace[1420904393] 'read index received' (duration: 72.500305ms)","trace[1420904393] 'applied index is now lower than readState.Index' (duration: 43.467503ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:17:46.168976Z","caller":"traceutil/trace.go:171","msg":"trace[106032852] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"166.208641ms","start":"2026-08-14T23:17:46.002705Z","end":"2026-08-14T23:17:46.168914Z","steps":["trace[106032852] 'process raft request' (duration: 122.584425ms)","trace[106032852] 'compare' (duration: 43.074896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:17:46.169136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.338815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-14T23:17:46.16916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.653772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-14T23:17:46.169233Z","caller":"traceutil/trace.go:171","msg":"trace[1875116661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2136; }","duration":"115.782715ms","start":"2026-08-14T23:17:46.053437Z","end":"2026-08-14T23:17:46.16922Z","steps":["trace[1875116661] 'agreement among raft nodes before linearized reading' (duration: 115.572251ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:46.169239Z","caller":"traceutil/trace.go:171","msg":"trace[288762923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2136; }","duration":"116.505508ms","start":"2026-08-14T23:17:46.052712Z","end":"2026-08-14T23:17:46.169218Z","steps":["trace[288762923] 'agreement among raft nodes before linearized reading' (duration: 116.279044ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:46.169314Z","caller":"traceutil/trace.go:171","msg":"trace[1999552626] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"165.512458ms","start":"2026-08-14T23:17:46.00374Z","end":"2026-08-14T23:17:46.169253Z","steps":["trace[1999552626] 'process raft request' (duration: 164.902546ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:35.552423Z","caller":"traceutil/trace.go:171","msg":"trace[1521151710] transaction","detail":"{read_only:false; response_revision:3753; number_of_response:1; }","duration":"268.235307ms","start":"2026-08-14T23:21:35.284149Z","end":"2026-08-14T23:21:35.552384Z","steps":["trace[1521151710] 'process raft request' (duration: 267.933961ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:35.552682Z","caller":"traceutil/trace.go:171","msg":"trace[961095410] linearizableReadLoop","detail":"{readStateIndex:3897; appliedIndex:3894; }","duration":"227.749194ms","start":"2026-08-14T23:21:35.324913Z","end":"2026-08-14T23:21:35.552663Z","steps":["trace[961095410] 'read index received' (duration: 227.0293ms)","trace[961095410] 'applied index is now lower than readState.Index' (duration: 719.394µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:21:35.552734Z","caller":"traceutil/trace.go:171","msg":"trace[1401870100] transaction","detail":"{read_only:false; response_revision:3754; number_of_response:1; }","duration":"267.520032ms","start":"2026-08-14T23:21:35.285188Z","end":"2026-08-14T23:21:35.552708Z","steps":["trace[1401870100] 'process raft request' (duration: 267.140815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:21:35.552806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.870026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T23:21:35.552752Z","caller":"traceutil/trace.go:171","msg":"trace[82106619] transaction","detail":"{read_only:false; response_revision:3755; number_of_response:1; }","duration":"267.298568ms","start":"2026-08-14T23:21:35.285417Z","end":"2026-08-14T23:21:35.552716Z","steps":["trace[82106619] 'process raft request' (duration: 267.153755ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:35.552845Z","caller":"traceutil/trace.go:171","msg":"trace[841382751] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:3755; }","duration":"227.954027ms","start":"2026-08-14T23:21:35.324885Z","end":"2026-08-14T23:21:35.552839Z","steps":["trace[841382751] 'agreement among raft nodes before linearized reading' (duration: 227.865065ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:54.284279Z","caller":"traceutil/trace.go:171","msg":"trace[330281186] linearizableReadLoop","detail":"{readStateIndex:4959; appliedIndex:4957; }","duration":"126.107615ms","start":"2026-08-14T23:22:54.158145Z","end":"2026-08-14T23:22:54.284252Z","steps":["trace[330281186] 'read index received' (duration: 126.001923ms)","trace[330281186] 'applied index is now lower than readState.Index' (duration: 104.372µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:22:54.284396Z","caller":"traceutil/trace.go:171","msg":"trace[1263805502] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"133.288652ms","start":"2026-08-14T23:22:54.151097Z","end":"2026-08-14T23:22:54.284386Z","steps":["trace[1263805502] 'process raft request' (duration: 132.894555ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:54.284334Z","caller":"traceutil/trace.go:171","msg":"trace[2766699] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"127.322309ms","start":"2026-08-14T23:22:54.156987Z","end":"2026-08-14T23:22:54.284309Z","steps":["trace[2766699] 'process raft request' (duration: 127.185656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:22:54.285548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.09212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210296"} {"level":"info","ts":"2026-08-14T23:22:54.285606Z","caller":"traceutil/trace.go:171","msg":"trace[172240561] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4779; }","duration":"131.194092ms","start":"2026-08-14T23:22:54.154398Z","end":"2026-08-14T23:22:54.285592Z","steps":["trace[172240561] 'agreement among raft nodes before linearized reading' (duration: 130.03672ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:54.288182Z","caller":"traceutil/trace.go:171","msg":"trace[1219770576] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4779; }","duration":"127.966481ms","start":"2026-08-14T23:22:54.160198Z","end":"2026-08-14T23:22:54.288164Z","steps":["trace[1219770576] 'process raft request' (duration: 127.834219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:22:54.288355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.636765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-14T23:22:54.288437Z","caller":"traceutil/trace.go:171","msg":"trace[1636464202] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4779; }","duration":"126.783018ms","start":"2026-08-14T23:22:54.161632Z","end":"2026-08-14T23:22:54.288415Z","steps":["trace[1636464202] 'agreement among raft nodes before linearized reading' (duration: 126.508733ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:22:58.031205Z","caller":"traceutil/trace.go:171","msg":"trace[360173099] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"223.334179ms","start":"2026-08-14T23:22:57.807846Z","end":"2026-08-14T23:22:58.03118Z","steps":["trace[360173099] 'read index received' (duration: 223.097995ms)","trace[360173099] 'applied index is now lower than readState.Index' (duration: 235.204µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:22:58.031258Z","caller":"traceutil/trace.go:171","msg":"trace[366539208] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"225.419309ms","start":"2026-08-14T23:22:57.805808Z","end":"2026-08-14T23:22:58.031227Z","steps":["trace[366539208] 'process raft request' (duration: 225.197935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:22:58.0315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.631114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-zzzmt.18cbcf741b9964cf\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-08-14T23:22:58.031538Z","caller":"traceutil/trace.go:171","msg":"trace[1355338320] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-zzzmt.18cbcf741b9964cf; range_end:; response_count:1; response_revision:4916; }","duration":"223.740526ms","start":"2026-08-14T23:22:57.807786Z","end":"2026-08-14T23:22:58.031526Z","steps":["trace[1355338320] 'agreement among raft nodes before linearized reading' (duration: 223.560083ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:11.598901Z","caller":"traceutil/trace.go:171","msg":"trace[384144666] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"239.218963ms","start":"2026-08-14T23:23:11.359654Z","end":"2026-08-14T23:23:11.598873Z","steps":["trace[384144666] 'process raft request' (duration: 239.031609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:18.668625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.218552ms","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-14T23:23:18.668746Z","caller":"traceutil/trace.go:171","msg":"trace[1107166634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5369; }","duration":"110.398525ms","start":"2026-08-14T23:23:18.558327Z","end":"2026-08-14T23:23:18.668726Z","steps":["trace[1107166634] 'range keys from in-memory index tree' (duration: 109.982477ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:19.362133Z","caller":"traceutil/trace.go:171","msg":"trace[365258141] linearizableReadLoop","detail":"{readStateIndex:5570; appliedIndex:5569; }","duration":"216.324195ms","start":"2026-08-14T23:23:19.145778Z","end":"2026-08-14T23:23:19.362102Z","steps":["trace[365258141] 'read index received' (duration: 211.32751ms)","trace[365258141] 'applied index is now lower than readState.Index' (duration: 4.995555ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:19.362205Z","caller":"traceutil/trace.go:171","msg":"trace[1635002393] transaction","detail":"{read_only:false; response_revision:5373; number_of_response:1; }","duration":"251.178181ms","start":"2026-08-14T23:23:19.111001Z","end":"2026-08-14T23:23:19.362179Z","steps":["trace[1635002393] 'process raft request' (duration: 246.092433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:19.362374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.580771ms","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-08-14T23:23:19.362417Z","caller":"traceutil/trace.go:171","msg":"trace[1875480860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5373; }","duration":"216.663222ms","start":"2026-08-14T23:23:19.145743Z","end":"2026-08-14T23:23:19.362406Z","steps":["trace[1875480860] 'agreement among raft nodes before linearized reading' (duration: 216.492739ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:19.387302Z","caller":"traceutil/trace.go:171","msg":"trace[1223654251] transaction","detail":"{read_only:false; response_revision:5374; number_of_response:1; }","duration":"155.056938ms","start":"2026-08-14T23:23:19.232212Z","end":"2026-08-14T23:23:19.387269Z","steps":["trace[1223654251] 'process raft request' (duration: 154.744842ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:30.401579Z","caller":"traceutil/trace.go:171","msg":"trace[1046480238] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"158.761478ms","start":"2026-08-14T23:23:30.242777Z","end":"2026-08-14T23:23:30.401539Z","steps":["trace[1046480238] 'process raft request' (duration: 102.943154ms)","trace[1046480238] 'compare' (duration: 55.62583ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:30.448932Z","caller":"traceutil/trace.go:171","msg":"trace[945324438] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"205.780875ms","start":"2026-08-14T23:23:30.243125Z","end":"2026-08-14T23:23:30.448905Z","steps":["trace[945324438] 'process raft request' (duration: 205.52401ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:30.449093Z","caller":"traceutil/trace.go:171","msg":"trace[166604720] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"205.719853ms","start":"2026-08-14T23:23:30.243359Z","end":"2026-08-14T23:23:30.449079Z","steps":["trace[166604720] 'process raft request' (duration: 205.427727ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:30.449205Z","caller":"traceutil/trace.go:171","msg":"trace[925267629] linearizableReadLoop","detail":"{readStateIndex:5699; appliedIndex:5695; }","duration":"153.745122ms","start":"2026-08-14T23:23:30.295453Z","end":"2026-08-14T23:23:30.449198Z","steps":["trace[925267629] 'read index received' (duration: 50.282199ms)","trace[925267629] 'applied index is now lower than readState.Index' (duration: 103.461913ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:23:30.449302Z","caller":"traceutil/trace.go:171","msg":"trace[1136410853] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"205.775805ms","start":"2026-08-14T23:23:30.243517Z","end":"2026-08-14T23:23:30.449293Z","steps":["trace[1136410853] 'process raft request' (duration: 205.502289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:30.449498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.037968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-14T23:23:30.449498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.186672ms","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-14T23:23:30.449535Z","caller":"traceutil/trace.go:171","msg":"trace[434539747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5500; }","duration":"154.137349ms","start":"2026-08-14T23:23:30.295389Z","end":"2026-08-14T23:23:30.449527Z","steps":["trace[434539747] 'agreement among raft nodes before linearized reading' (duration: 154.013217ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:23:30.449547Z","caller":"traceutil/trace.go:171","msg":"trace[1756126990] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5500; }","duration":"153.259563ms","start":"2026-08-14T23:23:30.296273Z","end":"2026-08-14T23:23:30.449533Z","steps":["trace[1756126990] 'agreement among raft nodes before linearized reading' (duration: 153.116161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:23:30.44981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.317684ms","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-14T23:23:30.450097Z","caller":"traceutil/trace.go:171","msg":"trace[49898566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5500; }","duration":"153.477227ms","start":"2026-08-14T23:23:30.296419Z","end":"2026-08-14T23:23:30.449896Z","steps":["trace[49898566] 'agreement among raft nodes before linearized reading' (duration: 153.217232ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:24:52.669384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3015} {"level":"info","ts":"2026-08-14T23:24:52.761023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3015,"took":"89.94294ms","hash":3182915794,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":32395264,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-14T23:24:52.76109Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3182915794,"revision":3015,"compact-revision":-1} {"level":"info","ts":"2026-08-14T23:29:19.669511Z","caller":"traceutil/trace.go:171","msg":"trace[532527777] transaction","detail":"{read_only:false; response_revision:8149; number_of_response:1; }","duration":"207.976227ms","start":"2026-08-14T23:29:19.461509Z","end":"2026-08-14T23:29:19.669486Z","steps":["trace[532527777] 'process raft request' (duration: 207.801744ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:19.854104Z","caller":"traceutil/trace.go:171","msg":"trace[575884837] transaction","detail":"{read_only:false; response_revision:8150; number_of_response:1; }","duration":"179.075663ms","start":"2026-08-14T23:29:19.67499Z","end":"2026-08-14T23:29:19.854065Z","steps":["trace[575884837] 'process raft request' (duration: 178.776187ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:19.854986Z","caller":"traceutil/trace.go:171","msg":"trace[806636226] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"107.427919ms","start":"2026-08-14T23:29:19.747535Z","end":"2026-08-14T23:29:19.854963Z","steps":["trace[806636226] 'process raft request' (duration: 107.290647ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:31.425385Z","caller":"traceutil/trace.go:171","msg":"trace[744324345] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"117.747384ms","start":"2026-08-14T23:29:31.307613Z","end":"2026-08-14T23:29:31.42536Z","steps":["trace[744324345] 'process raft request' (duration: 116.906858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:32.420509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.348383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:29:32.420604Z","caller":"traceutil/trace.go:171","msg":"trace[1530666200] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:8213; }","duration":"299.507326ms","start":"2026-08-14T23:29:32.121076Z","end":"2026-08-14T23:29:32.420583Z","steps":["trace[1530666200] 'count revisions from in-memory index tree' (duration: 299.208631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:32.420625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.331714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-d4fb59965-64wdp.18cbcfcbfb4f3be2\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-08-14T23:29:32.420708Z","caller":"traceutil/trace.go:171","msg":"trace[288055481] range","detail":"{range_begin:/registry/events/openstack/barbican-api-d4fb59965-64wdp.18cbcfcbfb4f3be2; range_end:; response_count:1; response_revision:8213; }","duration":"235.478987ms","start":"2026-08-14T23:29:32.185208Z","end":"2026-08-14T23:29:32.420687Z","steps":["trace[288055481] 'range keys from in-memory index tree' (duration: 235.150461ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:32.426753Z","caller":"traceutil/trace.go:171","msg":"trace[431688800] linearizableReadLoop","detail":"{readStateIndex:8498; appliedIndex:8497; }","duration":"136.175781ms","start":"2026-08-14T23:29:32.29056Z","end":"2026-08-14T23:29:32.426735Z","steps":["trace[431688800] 'read index received' (duration: 136.096809ms)","trace[431688800] 'applied index is now lower than readState.Index' (duration: 77.772µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:29:32.426848Z","caller":"traceutil/trace.go:171","msg":"trace[1483560810] transaction","detail":"{read_only:false; response_revision:8214; number_of_response:1; }","duration":"148.613847ms","start":"2026-08-14T23:29:32.27822Z","end":"2026-08-14T23:29:32.426834Z","steps":["trace[1483560810] 'process raft request' (duration: 148.388833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:32.427039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.468426ms","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-14T23:29:32.427103Z","caller":"traceutil/trace.go:171","msg":"trace[1937283914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8214; }","duration":"136.561609ms","start":"2026-08-14T23:29:32.290525Z","end":"2026-08-14T23:29:32.427087Z","steps":["trace[1937283914] 'agreement among raft nodes before linearized reading' (duration: 136.349805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:32.476597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.300926ms","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":"warn","ts":"2026-08-14T23:29:32.476607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.122988ms","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-14T23:29:32.476651Z","caller":"traceutil/trace.go:171","msg":"trace[1312118796] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8214; }","duration":"161.403718ms","start":"2026-08-14T23:29:32.315235Z","end":"2026-08-14T23:29:32.476638Z","steps":["trace[1312118796] 'agreement among raft nodes before linearized reading' (duration: 161.271685ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:32.476664Z","caller":"traceutil/trace.go:171","msg":"trace[71067105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8214; }","duration":"166.223449ms","start":"2026-08-14T23:29:32.310426Z","end":"2026-08-14T23:29:32.476649Z","steps":["trace[71067105] 'agreement among raft nodes before linearized reading' (duration: 166.086826ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:32.592696Z","caller":"traceutil/trace.go:171","msg":"trace[382584249] linearizableReadLoop","detail":"{readStateIndex:8500; appliedIndex:8499; }","duration":"116.182405ms","start":"2026-08-14T23:29:32.476486Z","end":"2026-08-14T23:29:32.592669Z","steps":["trace[382584249] 'read index received' (duration: 86.911731ms)","trace[382584249] 'applied index is now lower than readState.Index' (duration: 29.269774ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:29:32.592762Z","caller":"traceutil/trace.go:171","msg":"trace[24476532] transaction","detail":"{read_only:false; response_revision:8215; number_of_response:1; }","duration":"161.212116ms","start":"2026-08-14T23:29:32.431504Z","end":"2026-08-14T23:29:32.592716Z","steps":["trace[24476532] 'process raft request' (duration: 131.79681ms)","trace[24476532] 'compare' (duration: 29.239182ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:29:32.593004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.39405ms","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-14T23:29:32.593095Z","caller":"traceutil/trace.go:171","msg":"trace[2106340164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8215; }","duration":"123.526113ms","start":"2026-08-14T23:29:32.469548Z","end":"2026-08-14T23:29:32.593074Z","steps":["trace[2106340164] 'agreement among raft nodes before linearized reading' (duration: 123.218728ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:32.60623Z","caller":"traceutil/trace.go:171","msg":"trace[1446879296] transaction","detail":"{read_only:false; response_revision:8218; number_of_response:1; }","duration":"124.605923ms","start":"2026-08-14T23:29:32.4816Z","end":"2026-08-14T23:29:32.606206Z","steps":["trace[1446879296] 'process raft request' (duration: 124.554822ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:32.6063Z","caller":"traceutil/trace.go:171","msg":"trace[1072442650] transaction","detail":"{read_only:false; response_revision:8216; number_of_response:1; }","duration":"128.943585ms","start":"2026-08-14T23:29:32.477331Z","end":"2026-08-14T23:29:32.606275Z","steps":["trace[1072442650] 'process raft request' (duration: 128.625179ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:32.606553Z","caller":"traceutil/trace.go:171","msg":"trace[1537696171] transaction","detail":"{read_only:false; response_revision:8217; number_of_response:1; }","duration":"125.375458ms","start":"2026-08-14T23:29:32.481123Z","end":"2026-08-14T23:29:32.606498Z","steps":["trace[1537696171] 'process raft request' (duration: 124.98146ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:52.67987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6158} {"level":"info","ts":"2026-08-14T23:29:52.802514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6158,"took":"121.049066ms","hash":3768164110,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":22847488,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-14T23:29:52.802566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3768164110,"revision":6158,"compact-revision":3015} {"level":"info","ts":"2026-08-14T23:29:59.750768Z","caller":"traceutil/trace.go:171","msg":"trace[573186592] linearizableReadLoop","detail":"{readStateIndex:8712; appliedIndex:8711; }","duration":"113.527073ms","start":"2026-08-14T23:29:59.637212Z","end":"2026-08-14T23:29:59.750739Z","steps":["trace[573186592] 'read index received' (duration: 113.394851ms)","trace[573186592] 'applied index is now lower than readState.Index' (duration: 130.402µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:29:59.750835Z","caller":"traceutil/trace.go:171","msg":"trace[325511264] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"136.162541ms","start":"2026-08-14T23:29:59.614638Z","end":"2026-08-14T23:29:59.7508Z","steps":["trace[325511264] 'process raft request' (duration: 135.905447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:59.751007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.716537ms","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-08-14T23:29:59.751049Z","caller":"traceutil/trace.go:171","msg":"trace[1264315669] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8419; }","duration":"113.814449ms","start":"2026-08-14T23:29:59.63722Z","end":"2026-08-14T23:29:59.751035Z","steps":["trace[1264315669] 'agreement among raft nodes before linearized reading' (duration: 113.719007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:59.75113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.88994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9gd5z\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-14T23:29:59.751218Z","caller":"traceutil/trace.go:171","msg":"trace[1448637565] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9gd5z; range_end:; response_count:1; response_revision:8419; }","duration":"114.046443ms","start":"2026-08-14T23:29:59.637151Z","end":"2026-08-14T23:29:59.751197Z","steps":["trace[1448637565] 'agreement among raft nodes before linearized reading' (duration: 113.760398ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:30:12.440026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.285559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:30:12.440161Z","caller":"traceutil/trace.go:171","msg":"trace[674597882] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"223.385809ms","start":"2026-08-14T23:30:12.21676Z","end":"2026-08-14T23:30:12.440146Z","steps":["trace[674597882] 'process raft request' (duration: 117.809204ms)","trace[674597882] 'compare' (duration: 105.175398ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:30:12.584897Z","caller":"traceutil/trace.go:171","msg":"trace[1544243477] linearizableReadLoop","detail":"{readStateIndex:8827; appliedIndex:8826; }","duration":"198.891387ms","start":"2026-08-14T23:30:12.385979Z","end":"2026-08-14T23:30:12.584871Z","steps":["trace[1544243477] 'read index received' (duration: 162.928627ms)","trace[1544243477] 'applied index is now lower than readState.Index' (duration: 35.95857ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:30:12.585081Z","caller":"traceutil/trace.go:171","msg":"trace[1653496998] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"199.916346ms","start":"2026-08-14T23:30:12.385129Z","end":"2026-08-14T23:30:12.585045Z","steps":["trace[1653496998] 'process raft request' (duration: 163.864105ms)","trace[1653496998] 'compare' (duration: 35.723975ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:30:12.585144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.150341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-bkg4x\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-14T23:30:12.585183Z","caller":"traceutil/trace.go:171","msg":"trace[61758884] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-bkg4x; range_end:; response_count:1; response_revision:8529; }","duration":"199.258964ms","start":"2026-08-14T23:30:12.385911Z","end":"2026-08-14T23:30:12.58517Z","steps":["trace[61758884] 'agreement among raft nodes before linearized reading' (duration: 199.06728ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:30:40.840212Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023af.wal"} {"level":"info","ts":"2026-08-14T23:32:22.590439Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T23:32:22.595886Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-14T23:32:22.595991Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-14T23:34:52.685718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8329} {"level":"info","ts":"2026-08-14T23:34:52.88495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8329,"took":"197.198814ms","hash":4217152622,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":26673152,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-14T23:34:52.885029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4217152622,"revision":8329,"compact-revision":6158} {"level":"info","ts":"2026-08-14T23:39:52.692242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11023} {"level":"info","ts":"2026-08-14T23:39:52.771935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11023,"took":"76.973483ms","hash":1844379202,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":28364800,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-14T23:39:52.772088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1844379202,"revision":11023,"compact-revision":8329} {"level":"info","ts":"2026-08-14T23:41:20.361534Z","caller":"traceutil/trace.go:171","msg":"trace[1691501500] transaction","detail":"{read_only:false; response_revision:13625; number_of_response:1; }","duration":"113.241463ms","start":"2026-08-14T23:41:20.248264Z","end":"2026-08-14T23:41:20.361506Z","steps":["trace[1691501500] 'process raft request' (duration: 100.087683ms)","trace[1691501500] 'compare' (duration: 12.967626ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:41:20.361751Z","caller":"traceutil/trace.go:171","msg":"trace[175906346] transaction","detail":"{read_only:false; response_revision:13626; number_of_response:1; }","duration":"106.892252ms","start":"2026-08-14T23:41:20.254836Z","end":"2026-08-14T23:41:20.361728Z","steps":["trace[175906346] 'process raft request' (duration: 106.629637ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:41:28.67321Z","caller":"traceutil/trace.go:171","msg":"trace[1897394180] transaction","detail":"{read_only:false; response_revision:13683; number_of_response:1; }","duration":"387.63913ms","start":"2026-08-14T23:41:28.285538Z","end":"2026-08-14T23:41:28.673177Z","steps":["trace[1897394180] 'process raft request' (duration: 387.485777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:28.673356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:41:28.285526Z","time spent":"387.756442ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:41:28.696566Z","caller":"traceutil/trace.go:171","msg":"trace[1639528378] linearizableReadLoop","detail":"{readStateIndex:14144; appliedIndex:14142; }","duration":"305.60012ms","start":"2026-08-14T23:41:28.390936Z","end":"2026-08-14T23:41:28.696536Z","steps":["trace[1639528378] 'read index received' (duration: 282.020671ms)","trace[1639528378] 'applied index is now lower than readState.Index' (duration: 23.578619ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:41:28.696938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.976238ms","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-14T23:41:28.697003Z","caller":"traceutil/trace.go:171","msg":"trace[2115172191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13685; }","duration":"306.10771ms","start":"2026-08-14T23:41:28.390879Z","end":"2026-08-14T23:41:28.696986Z","steps":["trace[2115172191] 'agreement among raft nodes before linearized reading' (duration: 305.859756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:28.697034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:41:28.390856Z","time spent":"306.173032ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-14T23:41:28.69705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.538103ms","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-14T23:41:28.697122Z","caller":"traceutil/trace.go:171","msg":"trace[669637975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13685; }","duration":"301.698356ms","start":"2026-08-14T23:41:28.395381Z","end":"2026-08-14T23:41:28.697079Z","steps":["trace[669637975] 'agreement among raft nodes before linearized reading' (duration: 301.550483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:28.69716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:41:28.395354Z","time spent":"301.793838ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-14T23:41:28.697239Z","caller":"traceutil/trace.go:171","msg":"trace[428158415] transaction","detail":"{read_only:false; response_revision:13684; number_of_response:1; }","duration":"411.629207ms","start":"2026-08-14T23:41:28.285602Z","end":"2026-08-14T23:41:28.697231Z","steps":["trace[428158415] 'process raft request' (duration: 410.72769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:28.697306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:41:28.285585Z","time spent":"411.670917ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:41:28.697373Z","caller":"traceutil/trace.go:171","msg":"trace[1471827333] transaction","detail":"{read_only:false; response_revision:13685; number_of_response:1; }","duration":"174.240782ms","start":"2026-08-14T23:41:28.523126Z","end":"2026-08-14T23:41:28.697367Z","steps":["trace[1471827333] 'process raft request' (duration: 173.364176ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:41:51.559251Z","caller":"traceutil/trace.go:171","msg":"trace[833194271] linearizableReadLoop","detail":"{readStateIndex:14471; appliedIndex:14470; }","duration":"227.206709ms","start":"2026-08-14T23:41:51.332019Z","end":"2026-08-14T23:41:51.559226Z","steps":["trace[833194271] 'read index received' (duration: 227.015806ms)","trace[833194271] 'applied index is now lower than readState.Index' (duration: 189.623µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:41:51.559397Z","caller":"traceutil/trace.go:171","msg":"trace[2035834714] transaction","detail":"{read_only:false; response_revision:13997; number_of_response:1; }","duration":"251.961ms","start":"2026-08-14T23:41:51.307409Z","end":"2026-08-14T23:41:51.55937Z","steps":["trace[2035834714] 'process raft request' (duration: 251.646484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:51.559492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.470805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b65624ed-6059-45b3-ac25-e5cb0bff4086\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-08-14T23:41:51.55954Z","caller":"traceutil/trace.go:171","msg":"trace[1407520244] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b65624ed-6059-45b3-ac25-e5cb0bff4086; range_end:; response_count:1; response_revision:13997; }","duration":"227.582667ms","start":"2026-08-14T23:41:51.331944Z","end":"2026-08-14T23:41:51.559527Z","steps":["trace[1407520244] 'agreement among raft nodes before linearized reading' (duration: 227.408864ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:41:51.664012Z","caller":"traceutil/trace.go:171","msg":"trace[1896476624] linearizableReadLoop","detail":"{readStateIndex:14472; appliedIndex:14471; }","duration":"104.65471ms","start":"2026-08-14T23:41:51.55933Z","end":"2026-08-14T23:41:51.663985Z","steps":["trace[1896476624] 'read index received' (duration: 68.609235ms)","trace[1896476624] 'applied index is now lower than readState.Index' (duration: 36.044075ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:41:51.664103Z","caller":"traceutil/trace.go:171","msg":"trace[657377083] transaction","detail":"{read_only:false; response_revision:13998; number_of_response:1; }","duration":"140.629423ms","start":"2026-08-14T23:41:51.523431Z","end":"2026-08-14T23:41:51.66406Z","steps":["trace[657377083] 'process raft request' (duration: 104.518867ms)","trace[657377083] 'compare' (duration: 35.894872ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:41:51.664221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.981605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-14T23:41:51.664265Z","caller":"traceutil/trace.go:171","msg":"trace[1848269475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13998; }","duration":"318.081357ms","start":"2026-08-14T23:41:51.346173Z","end":"2026-08-14T23:41:51.664255Z","steps":["trace[1848269475] 'agreement among raft nodes before linearized reading' (duration: 317.936774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:51.664303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:41:51.346149Z","time spent":"318.145969ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T23:41:51.664275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.337436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-14T23:41:51.664415Z","caller":"traceutil/trace.go:171","msg":"trace[2071925626] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:13998; }","duration":"314.554911ms","start":"2026-08-14T23:41:51.349841Z","end":"2026-08-14T23:41:51.664396Z","steps":["trace[2071925626] 'agreement among raft nodes before linearized reading' (duration: 314.216084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:51.664515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:41:51.349822Z","time spent":"314.653282ms","remote":"127.0.0.1:36376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":38,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-08-14T23:41:51.675372Z","caller":"traceutil/trace.go:171","msg":"trace[1933806619] transaction","detail":"{read_only:false; response_revision:13999; number_of_response:1; }","duration":"106.387973ms","start":"2026-08-14T23:41:51.568966Z","end":"2026-08-14T23:41:51.675354Z","steps":["trace[1933806619] 'process raft request' (duration: 106.268261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:41:51.675533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.559767ms","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-08-14T23:41:51.675645Z","caller":"traceutil/trace.go:171","msg":"trace[402986583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13999; }","duration":"105.707579ms","start":"2026-08-14T23:41:51.569918Z","end":"2026-08-14T23:41:51.675626Z","steps":["trace[402986583] 'agreement among raft nodes before linearized reading' (duration: 105.436084ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:43:17.201137Z","caller":"traceutil/trace.go:171","msg":"trace[1346633584] transaction","detail":"{read_only:false; response_revision:14805; number_of_response:1; }","duration":"108.453642ms","start":"2026-08-14T23:43:17.092661Z","end":"2026-08-14T23:43:17.201115Z","steps":["trace[1346633584] 'process raft request' (duration: 108.318759ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:43:17.414878Z","caller":"traceutil/trace.go:171","msg":"trace[1180236690] transaction","detail":"{read_only:false; response_revision:14806; number_of_response:1; }","duration":"204.495478ms","start":"2026-08-14T23:43:17.210353Z","end":"2026-08-14T23:43:17.414848Z","steps":["trace[1180236690] 'process raft request' (duration: 135.949114ms)","trace[1180236690] 'compare' (duration: 68.430512ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:43:17.43224Z","caller":"traceutil/trace.go:171","msg":"trace[1911680290] linearizableReadLoop","detail":"{readStateIndex:15305; appliedIndex:15302; }","duration":"220.807058ms","start":"2026-08-14T23:43:17.211416Z","end":"2026-08-14T23:43:17.432223Z","steps":["trace[1911680290] 'read index received' (duration: 134.834873ms)","trace[1911680290] 'applied index is now lower than readState.Index' (duration: 85.971195ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:43:17.432316Z","caller":"traceutil/trace.go:171","msg":"trace[2122234226] transaction","detail":"{read_only:false; response_revision:14808; number_of_response:1; }","duration":"221.103074ms","start":"2026-08-14T23:43:17.211206Z","end":"2026-08-14T23:43:17.432309Z","steps":["trace[2122234226] 'process raft request' (duration: 220.964351ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:43:17.43239Z","caller":"traceutil/trace.go:171","msg":"trace[128730997] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"221.618543ms","start":"2026-08-14T23:43:17.210656Z","end":"2026-08-14T23:43:17.432275Z","steps":["trace[128730997] 'process raft request' (duration: 221.45369ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:43:17.432443Z","caller":"traceutil/trace.go:171","msg":"trace[1943553577] transaction","detail":"{read_only:false; response_revision:14809; number_of_response:1; }","duration":"220.572494ms","start":"2026-08-14T23:43:17.211863Z","end":"2026-08-14T23:43:17.432436Z","steps":["trace[1943553577] 'process raft request' (duration: 220.33424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:43:17.433099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.665054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737333"} {"level":"info","ts":"2026-08-14T23:43:17.433134Z","caller":"traceutil/trace.go:171","msg":"trace[2021755824] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14809; }","duration":"221.733266ms","start":"2026-08-14T23:43:17.211393Z","end":"2026-08-14T23:43:17.433126Z","steps":["trace[2021755824] 'agreement among raft nodes before linearized reading' (duration: 221.063704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:43:17.433446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.44257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:43:17.433803Z","caller":"traceutil/trace.go:171","msg":"trace[1374563353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:14809; }","duration":"171.827506ms","start":"2026-08-14T23:43:17.261954Z","end":"2026-08-14T23:43:17.433781Z","steps":["trace[1374563353] 'agreement among raft nodes before linearized reading' (duration: 171.427139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:43:17.434303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.070395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:43:17.434389Z","caller":"traceutil/trace.go:171","msg":"trace[867085873] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14809; }","duration":"160.180096ms","start":"2026-08-14T23:43:17.274192Z","end":"2026-08-14T23:43:17.434372Z","steps":["trace[867085873] 'agreement among raft nodes before linearized reading' (duration: 160.057594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:43:17.434334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.365631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-14T23:43:17.434498Z","caller":"traceutil/trace.go:171","msg":"trace[1855888376] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14809; }","duration":"158.549235ms","start":"2026-08-14T23:43:17.27594Z","end":"2026-08-14T23:43:17.434489Z","steps":["trace[1855888376] 'agreement among raft nodes before linearized reading' (duration: 158.30297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:43:17.434561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.175787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-14T23:43:17.434617Z","caller":"traceutil/trace.go:171","msg":"trace[2050920374] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14809; }","duration":"159.251358ms","start":"2026-08-14T23:43:17.275351Z","end":"2026-08-14T23:43:17.434603Z","steps":["trace[2050920374] 'agreement among raft nodes before linearized reading' (duration: 159.104845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:43:17.43458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.862517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-14T23:43:17.43471Z","caller":"traceutil/trace.go:171","msg":"trace[957035202] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14809; }","duration":"153.002119ms","start":"2026-08-14T23:43:17.281696Z","end":"2026-08-14T23:43:17.434698Z","steps":["trace[957035202] 'agreement among raft nodes before linearized reading' (duration: 152.661903ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:44:52.699539Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13064} {"level":"info","ts":"2026-08-14T23:44:52.776634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13064,"took":"74.286147ms","hash":272464275,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":30982144,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-14T23:44:52.776699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":272464275,"revision":13064,"compact-revision":11023} {"level":"info","ts":"2026-08-14T23:48:51.543824Z","caller":"traceutil/trace.go:171","msg":"trace[1329314016] transaction","detail":"{read_only:false; response_revision:17324; number_of_response:1; }","duration":"173.91649ms","start":"2026-08-14T23:48:51.36988Z","end":"2026-08-14T23:48:51.543796Z","steps":["trace[1329314016] 'process raft request' (duration: 173.755867ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:51.543895Z","caller":"traceutil/trace.go:171","msg":"trace[2098770765] linearizableReadLoop","detail":"{readStateIndex:17904; appliedIndex:17904; }","duration":"153.180466ms","start":"2026-08-14T23:48:51.390686Z","end":"2026-08-14T23:48:51.543866Z","steps":["trace[2098770765] 'read index received' (duration: 153.170946ms)","trace[2098770765] 'applied index is now lower than readState.Index' (duration: 7.39µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:48:51.544154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.442592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rz8sr\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-08-14T23:48:51.544233Z","caller":"traceutil/trace.go:171","msg":"trace[1209486992] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rz8sr; range_end:; response_count:1; response_revision:17324; }","duration":"153.571884ms","start":"2026-08-14T23:48:51.390647Z","end":"2026-08-14T23:48:51.544219Z","steps":["trace[1209486992] 'agreement among raft nodes before linearized reading' (duration: 153.318459ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.013703Z","caller":"traceutil/trace.go:171","msg":"trace[76227885] linearizableReadLoop","detail":"{readStateIndex:17912; appliedIndex:17911; }","duration":"330.908908ms","start":"2026-08-14T23:48:51.68277Z","end":"2026-08-14T23:48:52.013679Z","steps":["trace[76227885] 'read index received' (duration: 330.44289ms)","trace[76227885] 'applied index is now lower than readState.Index' (duration: 464.818µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:48:52.013751Z","caller":"traceutil/trace.go:171","msg":"trace[925414149] transaction","detail":"{read_only:false; response_revision:17332; number_of_response:1; }","duration":"367.747587ms","start":"2026-08-14T23:48:51.645964Z","end":"2026-08-14T23:48:52.013712Z","steps":["trace[925414149] 'process raft request' (duration: 367.40405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:48:52.014215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.438928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18621"} {"level":"warn","ts":"2026-08-14T23:48:52.014297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:48:51.645953Z","time spent":"368.269367ms","remote":"127.0.0.1:36194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":130205,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:48:52.01396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.234155ms","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-08-14T23:48:52.014398Z","caller":"traceutil/trace.go:171","msg":"trace[926530238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17332; }","duration":"301.704094ms","start":"2026-08-14T23:48:51.712678Z","end":"2026-08-14T23:48:52.014382Z","steps":["trace[926530238] 'agreement among raft nodes before linearized reading' (duration: 301.131794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:48:52.014478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:48:51.712668Z","time spent":"301.795766ms","remote":"127.0.0.1:36398","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-14T23:48:52.014052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.092017ms","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-08-14T23:48:52.014559Z","caller":"traceutil/trace.go:171","msg":"trace[969749138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17332; }","duration":"296.614146ms","start":"2026-08-14T23:48:51.717931Z","end":"2026-08-14T23:48:52.014545Z","steps":["trace[969749138] 'agreement among raft nodes before linearized reading' (duration: 295.929314ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.015023Z","caller":"traceutil/trace.go:171","msg":"trace[1667888050] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:17332; }","duration":"331.566861ms","start":"2026-08-14T23:48:51.682736Z","end":"2026-08-14T23:48:52.014303Z","steps":["trace[1667888050] 'agreement among raft nodes before linearized reading' (duration: 331.063081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:48:52.015115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:48:51.682722Z","time spent":"332.343616ms","remote":"127.0.0.1:36304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18643,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-14T23:48:52.281303Z","caller":"traceutil/trace.go:171","msg":"trace[1156387071] transaction","detail":"{read_only:false; response_revision:17336; number_of_response:1; }","duration":"250.985323ms","start":"2026-08-14T23:48:52.030267Z","end":"2026-08-14T23:48:52.281252Z","steps":["trace[1156387071] 'process raft request' (duration: 239.829811ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.281327Z","caller":"traceutil/trace.go:171","msg":"trace[101720256] transaction","detail":"{read_only:false; response_revision:17337; number_of_response:1; }","duration":"250.944382ms","start":"2026-08-14T23:48:52.030351Z","end":"2026-08-14T23:48:52.281296Z","steps":["trace[101720256] 'process raft request' (duration: 250.817189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:48:52.28143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.410076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T23:48:52.281464Z","caller":"traceutil/trace.go:171","msg":"trace[469599031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17339; }","duration":"246.506718ms","start":"2026-08-14T23:48:52.034951Z","end":"2026-08-14T23:48:52.281457Z","steps":["trace[469599031] 'agreement among raft nodes before linearized reading' (duration: 246.422136ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.281331Z","caller":"traceutil/trace.go:171","msg":"trace[1221546486] linearizableReadLoop","detail":"{readStateIndex:17919; appliedIndex:17915; }","duration":"179.992276ms","start":"2026-08-14T23:48:52.101317Z","end":"2026-08-14T23:48:52.281309Z","steps":["trace[1221546486] 'read index received' (duration: 168.769993ms)","trace[1221546486] 'applied index is now lower than readState.Index' (duration: 11.221323ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:48:52.281466Z","caller":"traceutil/trace.go:171","msg":"trace[687197318] transaction","detail":"{read_only:false; response_revision:17338; number_of_response:1; }","duration":"249.504724ms","start":"2026-08-14T23:48:52.031937Z","end":"2026-08-14T23:48:52.281442Z","steps":["trace[687197318] 'process raft request' (duration: 249.304381ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.281643Z","caller":"traceutil/trace.go:171","msg":"trace[1923631585] transaction","detail":"{read_only:false; response_revision:17339; number_of_response:1; }","duration":"249.082216ms","start":"2026-08-14T23:48:52.032554Z","end":"2026-08-14T23:48:52.281637Z","steps":["trace[1923631585] 'process raft request' (duration: 248.715299ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.572106Z","caller":"traceutil/trace.go:171","msg":"trace[688997485] transaction","detail":"{read_only:false; response_revision:17340; number_of_response:1; }","duration":"283.788804ms","start":"2026-08-14T23:48:52.288292Z","end":"2026-08-14T23:48:52.57208Z","steps":["trace[688997485] 'process raft request' (duration: 283.655021ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.578693Z","caller":"traceutil/trace.go:171","msg":"trace[914698788] linearizableReadLoop","detail":"{readStateIndex:17921; appliedIndex:17919; }","duration":"272.098673ms","start":"2026-08-14T23:48:52.306577Z","end":"2026-08-14T23:48:52.578675Z","steps":["trace[914698788] 'read index received' (duration: 265.328854ms)","trace[914698788] 'applied index is now lower than readState.Index' (duration: 6.769039ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:48:52.578876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.285216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-08-14T23:48:52.5789Z","caller":"traceutil/trace.go:171","msg":"trace[1871571433] transaction","detail":"{read_only:false; response_revision:17341; number_of_response:1; }","duration":"289.655936ms","start":"2026-08-14T23:48:52.289225Z","end":"2026-08-14T23:48:52.578881Z","steps":["trace[1871571433] 'process raft request' (duration: 289.295999ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.578916Z","caller":"traceutil/trace.go:171","msg":"trace[1938734190] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:17342; }","duration":"272.378698ms","start":"2026-08-14T23:48:52.306523Z","end":"2026-08-14T23:48:52.578901Z","steps":["trace[1938734190] 'agreement among raft nodes before linearized reading' (duration: 272.226745ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:48:52.579299Z","caller":"traceutil/trace.go:171","msg":"trace[1152018395] transaction","detail":"{read_only:false; response_revision:17342; number_of_response:1; }","duration":"133.30261ms","start":"2026-08-14T23:48:52.445943Z","end":"2026-08-14T23:48:52.579246Z","steps":["trace[1152018395] 'process raft request' (duration: 132.680658ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:49:52.706455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15429} {"level":"info","ts":"2026-08-14T23:49:52.810505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15429,"took":"101.295752ms","hash":930398211,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":33820672,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-14T23:49:52.810568Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":930398211,"revision":15429,"compact-revision":13064} {"level":"info","ts":"2026-08-14T23:52:51.956144Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T23:52:51.962837Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-14T23:52:51.963732Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-14T23:54:21.03718Z","caller":"traceutil/trace.go:171","msg":"trace[264420138] linearizableReadLoop","detail":"{readStateIndex:20580; appliedIndex:20579; }","duration":"126.114748ms","start":"2026-08-14T23:54:20.911043Z","end":"2026-08-14T23:54:21.037158Z","steps":["trace[264420138] 'read index received' (duration: 86.392µs)","trace[264420138] 'applied index is now lower than readState.Index' (duration: 126.027446ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:54:21.037309Z","caller":"traceutil/trace.go:171","msg":"trace[1087956224] transaction","detail":"{read_only:false; response_revision:19915; number_of_response:1; }","duration":"126.26513ms","start":"2026-08-14T23:54:20.911016Z","end":"2026-08-14T23:54:21.037282Z","steps":["trace[1087956224] 'process raft request' (duration: 125.987765ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:21.037385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.321102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-14T23:54:21.03743Z","caller":"traceutil/trace.go:171","msg":"trace[1512433777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19915; }","duration":"126.403543ms","start":"2026-08-14T23:54:20.911013Z","end":"2026-08-14T23:54:21.037417Z","steps":["trace[1512433777] 'agreement among raft nodes before linearized reading' (duration: 126.26941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:21.379551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.718112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T23:54:21.379783Z","caller":"traceutil/trace.go:171","msg":"trace[110520383] transaction","detail":"{read_only:false; response_revision:19917; number_of_response:1; }","duration":"338.161671ms","start":"2026-08-14T23:54:21.041603Z","end":"2026-08-14T23:54:21.379765Z","steps":["trace[110520383] 'process raft request' (duration: 338.071269ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:21.379763Z","caller":"traceutil/trace.go:171","msg":"trace[423322622] transaction","detail":"{read_only:false; response_revision:19916; number_of_response:1; }","duration":"466.166715ms","start":"2026-08-14T23:54:20.913545Z","end":"2026-08-14T23:54:21.379712Z","steps":["trace[423322622] 'process raft request' (duration: 189.197888ms)","trace[423322622] 'compare' (duration: 276.514318ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:54:21.379825Z","caller":"traceutil/trace.go:171","msg":"trace[273800320] linearizableReadLoop","detail":"{readStateIndex:20581; appliedIndex:20580; }","duration":"342.535214ms","start":"2026-08-14T23:54:21.037257Z","end":"2026-08-14T23:54:21.379792Z","steps":["trace[273800320] 'read index received' (duration: 65.644629ms)","trace[273800320] 'applied index is now lower than readState.Index' (duration: 276.887065ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:54:21.379897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:21.041567Z","time spent":"338.249983ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:54:21.379939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:20.913504Z","time spent":"466.327008ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:54:21.380077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.143664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T23:54:21.380114Z","caller":"traceutil/trace.go:171","msg":"trace[107119818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19917; }","duration":"344.178995ms","start":"2026-08-14T23:54:21.035925Z","end":"2026-08-14T23:54:21.380104Z","steps":["trace[107119818] 'agreement among raft nodes before linearized reading' (duration: 344.119014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:21.380131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.765784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-14T23:54:21.380147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:21.035854Z","time spent":"344.283757ms","remote":"127.0.0.1:36154","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-08-14T23:54:21.380182Z","caller":"traceutil/trace.go:171","msg":"trace[70833088] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19917; }","duration":"468.832785ms","start":"2026-08-14T23:54:20.911334Z","end":"2026-08-14T23:54:21.380167Z","steps":["trace[70833088] 'agreement among raft nodes before linearized reading' (duration: 468.597241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:21.38024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:20.91133Z","time spent":"468.902077ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-14T23:54:21.739694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.345251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T23:54:21.739884Z","caller":"traceutil/trace.go:171","msg":"trace[1317707716] transaction","detail":"{read_only:false; response_revision:19919; number_of_response:1; }","duration":"355.446479ms","start":"2026-08-14T23:54:21.38442Z","end":"2026-08-14T23:54:21.739866Z","steps":["trace[1317707716] 'process raft request' (duration: 355.385617ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:21.739923Z","caller":"traceutil/trace.go:171","msg":"trace[2031568047] linearizableReadLoop","detail":"{readStateIndex:20583; appliedIndex:20582; }","duration":"358.572198ms","start":"2026-08-14T23:54:21.381315Z","end":"2026-08-14T23:54:21.739887Z","steps":["trace[2031568047] 'read index received' (duration: 150.050513ms)","trace[2031568047] 'applied index is now lower than readState.Index' (duration: 208.519525ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:54:21.739956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:21.384397Z","time spent":"355.520951ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:54:21.740015Z","caller":"traceutil/trace.go:171","msg":"trace[144504450] transaction","detail":"{read_only:false; response_revision:19918; number_of_response:1; }","duration":"443.640176ms","start":"2026-08-14T23:54:21.296338Z","end":"2026-08-14T23:54:21.739978Z","steps":["trace[144504450] 'process raft request' (duration: 234.903347ms)","trace[144504450] 'compare' (duration: 208.197159ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:54:21.740196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.792593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T23:54:21.740282Z","caller":"traceutil/trace.go:171","msg":"trace[1806734625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19919; }","duration":"358.992746ms","start":"2026-08-14T23:54:21.381267Z","end":"2026-08-14T23:54:21.74026Z","steps":["trace[1806734625] 'agreement among raft nodes before linearized reading' (duration: 358.742641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:21.740267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:21.29631Z","time spent":"443.85022ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:54:21.740329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:21.381251Z","time spent":"359.068858ms","remote":"127.0.0.1:36160","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-08-14T23:54:21.772602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.190871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-14T23:54:21.772668Z","caller":"traceutil/trace.go:171","msg":"trace[2141286115] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:19920; }","duration":"298.302663ms","start":"2026-08-14T23:54:21.474347Z","end":"2026-08-14T23:54:21.77265Z","steps":["trace[2141286115] 'agreement among raft nodes before linearized reading' (duration: 298.175091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:22.046951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.118628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T23:54:22.047087Z","caller":"traceutil/trace.go:171","msg":"trace[153948927] linearizableReadLoop","detail":"{readStateIndex:20586; appliedIndex:20585; }","duration":"263.840306ms","start":"2026-08-14T23:54:21.783228Z","end":"2026-08-14T23:54:22.047068Z","steps":["trace[153948927] 'read index received' (duration: 116.447133ms)","trace[153948927] 'applied index is now lower than readState.Index' (duration: 147.391533ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:54:22.047148Z","caller":"traceutil/trace.go:171","msg":"trace[173356890] transaction","detail":"{read_only:false; response_revision:19921; number_of_response:1; }","duration":"305.01385ms","start":"2026-08-14T23:54:21.742107Z","end":"2026-08-14T23:54:22.04712Z","steps":["trace[173356890] 'process raft request' (duration: 157.572276ms)","trace[173356890] 'compare' (duration: 146.975356ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:54:22.047286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:21.74209Z","time spent":"305.119462ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:54:22.047308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.085462ms","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-08-14T23:54:22.047341Z","caller":"traceutil/trace.go:171","msg":"trace[1556104240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19921; }","duration":"264.153392ms","start":"2026-08-14T23:54:21.783178Z","end":"2026-08-14T23:54:22.047331Z","steps":["trace[1556104240] 'agreement among raft nodes before linearized reading' (duration: 263.987099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:22.047477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.083886ms","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-08-14T23:54:22.04751Z","caller":"traceutil/trace.go:171","msg":"trace[763441968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19921; }","duration":"260.140276ms","start":"2026-08-14T23:54:21.787361Z","end":"2026-08-14T23:54:22.047501Z","steps":["trace[763441968] 'agreement among raft nodes before linearized reading' (duration: 260.032424ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:22.432262Z","caller":"traceutil/trace.go:171","msg":"trace[792458635] transaction","detail":"{read_only:false; response_revision:19923; number_of_response:1; }","duration":"380.450374ms","start":"2026-08-14T23:54:22.051786Z","end":"2026-08-14T23:54:22.432237Z","steps":["trace[792458635] 'process raft request' (duration: 380.294981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:54:22.43244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:54:22.051772Z","time spent":"380.561956ms","remote":"127.0.0.1:36398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:54:22.456337Z","caller":"traceutil/trace.go:171","msg":"trace[625541495] transaction","detail":"{read_only:false; response_revision:19924; number_of_response:1; }","duration":"178.28722ms","start":"2026-08-14T23:54:22.278036Z","end":"2026-08-14T23:54:22.456323Z","steps":["trace[625541495] 'process raft request' (duration: 178.180378ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:22.45648Z","caller":"traceutil/trace.go:171","msg":"trace[663491167] transaction","detail":"{read_only:false; response_revision:19925; number_of_response:1; }","duration":"102.764665ms","start":"2026-08-14T23:54:22.353691Z","end":"2026-08-14T23:54:22.456456Z","steps":["trace[663491167] 'process raft request' (duration: 102.604642ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:52.711586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18049} {"level":"info","ts":"2026-08-14T23:54:52.828524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18049,"took":"113.502899ms","hash":886282515,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":29290496,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-14T23:54:52.828605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":886282515,"revision":18049,"compact-revision":15429} {"level":"info","ts":"2026-08-14T23:59:27.716506Z","caller":"traceutil/trace.go:171","msg":"trace[1673198948] transaction","detail":"{read_only:false; response_revision:23184; number_of_response:1; }","duration":"106.005035ms","start":"2026-08-14T23:59:27.610482Z","end":"2026-08-14T23:59:27.716487Z","steps":["trace[1673198948] 'process raft request' (duration: 105.796291ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:59:27.784091Z","caller":"traceutil/trace.go:171","msg":"trace[245155001] transaction","detail":"{read_only:false; response_revision:23187; number_of_response:1; }","duration":"111.47698ms","start":"2026-08-14T23:59:27.672588Z","end":"2026-08-14T23:59:27.784065Z","steps":["trace[245155001] 'process raft request' (duration: 111.237574ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:59:52.718123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20116} {"level":"info","ts":"2026-08-14T23:59:52.82769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20116,"took":"101.962498ms","hash":4260950836,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":42754048,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-14T23:59:52.827778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4260950836,"revision":20116,"compact-revision":18049} {"level":"info","ts":"2026-08-15T00:04:52.72352Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23383} {"level":"info","ts":"2026-08-15T00:04:52.8546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23383,"took":"126.547646ms","hash":3034568135,"current-db-size-bytes":59441152,"current-db-size":"59 MB","current-db-size-in-use-bytes":31932416,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-15T00:04:52.854714Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3034568135,"revision":23383,"compact-revision":20116}