{"level":"warn","ts":"2026-07-14T22:57:51.398548Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T22:57:51.39872Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-07-14T22:57:51.398862Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T22:57:51.398888Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-14T22:57:51.398928Z","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-07-14T22:57:51.400304Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-07-14T22:57:51.400471Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-07-14T22:57:51.406213Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.325369ms"} {"level":"info","ts":"2026-07-14T22:57:51.420047Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-07-14T22:57:51.420254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T22:57:51.420293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-07-14T22:57:51.420327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T22:57:51.420342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-07-14T22:57:51.420416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-07-14T22:57:51.428252Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T22:57:51.43037Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T22:57:51.432039Z","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-07-14T22:57:51.434556Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T22:57:51.434777Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T22:57:51.434952Z","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-07-14T22:57:51.435118Z","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-07-14T22:57:51.43514Z","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-07-14T22:57:51.435514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-07-14T22:57:51.435676Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-14T22:57:51.441156Z","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-07-14T22:57:51.44135Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-14T22:57:51.441443Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-14T22:57:51.441473Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T22:57:51.441526Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T22:57:52.021166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T22:57:52.021275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T22:57:52.021303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-07-14T22:57:52.021342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-07-14T22:57:52.021354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-14T22:57:52.021374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-07-14T22:57:52.021391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-14T22:57:52.022667Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T22:57:52.022724Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:57:52.02298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:57:52.02302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T22:57:52.023225Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T22:57:52.023544Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T22:57:52.027036Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T22:57:52.027326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-07-14T22:57:52.027802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:57:52.027921Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:57:52.027962Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T22:58:06.010351Z","caller":"traceutil/trace.go:171","msg":"trace[70434816] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"131.302941ms","start":"2026-07-14T22:58:05.879019Z","end":"2026-07-14T22:58:06.010321Z","steps":["trace[70434816] 'read index received' (duration: 131.100527ms)","trace[70434816] 'applied index is now lower than readState.Index' (duration: 201.404µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:06.010412Z","caller":"traceutil/trace.go:171","msg":"trace[1052881038] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"133.919419ms","start":"2026-07-14T22:58:05.876453Z","end":"2026-07-14T22:58:06.010372Z","steps":["trace[1052881038] 'process raft request' (duration: 133.694485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:06.010519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.469234ms","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-07-14T22:58:06.010588Z","caller":"traceutil/trace.go:171","msg":"trace[490148112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:363; }","duration":"131.583137ms","start":"2026-07-14T22:58:05.878991Z","end":"2026-07-14T22:58:06.010574Z","steps":["trace[490148112] 'agreement among raft nodes before linearized reading' (duration: 131.450264ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:26.920651Z","caller":"traceutil/trace.go:171","msg":"trace[1194901122] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"150.715761ms","start":"2026-07-14T22:58:26.769907Z","end":"2026-07-14T22:58:26.920623Z","steps":["trace[1194901122] 'process raft request' (duration: 130.863014ms)","trace[1194901122] 'compare' (duration: 19.730385ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T22:58:28.730569Z","caller":"traceutil/trace.go:171","msg":"trace[314972150] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"115.783182ms","start":"2026-07-14T22:58:28.614763Z","end":"2026-07-14T22:58:28.730546Z","steps":["trace[314972150] 'process raft request' (duration: 115.67011ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:28.736794Z","caller":"traceutil/trace.go:171","msg":"trace[220673496] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"116.1349ms","start":"2026-07-14T22:58:28.620639Z","end":"2026-07-14T22:58:28.736774Z","steps":["trace[220673496] 'process raft request' (duration: 114.687944ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:28.736958Z","caller":"traceutil/trace.go:171","msg":"trace[1919353632] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"115.57195ms","start":"2026-07-14T22:58:28.621375Z","end":"2026-07-14T22:58:28.736947Z","steps":["trace[1919353632] 'process raft request' (duration: 114.026181ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:28.737097Z","caller":"traceutil/trace.go:171","msg":"trace[936451846] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"108.412727ms","start":"2026-07-14T22:58:28.628675Z","end":"2026-07-14T22:58:28.737088Z","steps":["trace[936451846] 'process raft request' (duration: 106.761987ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:35.434796Z","caller":"traceutil/trace.go:171","msg":"trace[55862622] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"382.159285ms","start":"2026-07-14T22:58:35.05261Z","end":"2026-07-14T22:58:35.43477Z","steps":["trace[55862622] 'process raft request' (duration: 382.104554ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T22:58:35.435419Z","caller":"traceutil/trace.go:171","msg":"trace[1171879978] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"405.14816ms","start":"2026-07-14T22:58:35.030205Z","end":"2026-07-14T22:58:35.435354Z","steps":["trace[1171879978] 'read index received' (duration: 356.788746ms)","trace[1171879978] 'applied index is now lower than readState.Index' (duration: 48.354114ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:58:35.435667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:35.052589Z","time spent":"382.307828ms","remote":"127.0.0.1:42710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T22:58:35.435764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.534037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T22:58:35.435875Z","caller":"traceutil/trace.go:171","msg":"trace[276209818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"405.73177ms","start":"2026-07-14T22:58:35.03012Z","end":"2026-07-14T22:58:35.435852Z","steps":["trace[276209818] 'agreement among raft nodes before linearized reading' (duration: 405.333813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:35.435931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:35.030098Z","time spent":"405.823393ms","remote":"127.0.0.1:42482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T22:58:35.435983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.603451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T22:58:35.436099Z","caller":"traceutil/trace.go:171","msg":"trace[1727541478] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:582; }","duration":"263.757984ms","start":"2026-07-14T22:58:35.172307Z","end":"2026-07-14T22:58:35.436065Z","steps":["trace[1727541478] 'agreement among raft nodes before linearized reading' (duration: 263.541279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:35.436017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.990077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T22:58:35.436181Z","caller":"traceutil/trace.go:171","msg":"trace[426010530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"378.220402ms","start":"2026-07-14T22:58:35.057939Z","end":"2026-07-14T22:58:35.436159Z","steps":["trace[426010530] 'agreement among raft nodes before linearized reading' (duration: 377.859174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T22:58:35.436226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:35.057902Z","time spent":"378.313234ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T22:58:35.436947Z","caller":"traceutil/trace.go:171","msg":"trace[2051971000] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"426.494336ms","start":"2026-07-14T22:58:35.008632Z","end":"2026-07-14T22:58:35.435126Z","steps":["trace[2051971000] 'process raft request' (duration: 378.343915ms)","trace[2051971000] 'compare' (duration: 47.5322ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T22:58:35.438454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T22:58:35.008605Z","time spent":"428.465802ms","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T22:59:20.744151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.13365ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-14T23:01:58.920887Z","caller":"traceutil/trace.go:171","msg":"trace[1418102019] linearizableReadLoop","detail":"{readStateIndex:2757; appliedIndex:2756; }","duration":"104.379392ms","start":"2026-07-14T23:01:58.816487Z","end":"2026-07-14T23:01:58.920866Z","steps":["trace[1418102019] 'read index received' (duration: 104.220939ms)","trace[1418102019] 'applied index is now lower than readState.Index' (duration: 157.723µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:01:58.921089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.578546ms","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-07-14T23:01:58.92113Z","caller":"traceutil/trace.go:171","msg":"trace[1353897317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2656; }","duration":"104.655397ms","start":"2026-07-14T23:01:58.816465Z","end":"2026-07-14T23:01:58.92112Z","steps":["trace[1353897317] 'agreement among raft nodes before linearized reading' (duration: 104.500354ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:01:58.921238Z","caller":"traceutil/trace.go:171","msg":"trace[940320243] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"105.3396ms","start":"2026-07-14T23:01:58.815886Z","end":"2026-07-14T23:01:58.921226Z","steps":["trace[940320243] 'process raft request' (duration: 104.870381ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:33.0545Z","caller":"traceutil/trace.go:171","msg":"trace[851529127] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"163.989887ms","start":"2026-07-14T23:02:32.890487Z","end":"2026-07-14T23:02:33.054477Z","steps":["trace[851529127] 'process raft request' (duration: 163.584289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:33.327346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.405058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:02:33.327593Z","caller":"traceutil/trace.go:171","msg":"trace[1389353097] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"215.18854ms","start":"2026-07-14T23:02:33.11237Z","end":"2026-07-14T23:02:33.327559Z","steps":["trace[1389353097] 'process raft request' (duration: 42.482186ms)","trace[1389353097] 'compare' (duration: 172.282286ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:33.328346Z","caller":"traceutil/trace.go:171","msg":"trace[1661951835] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"214.863293ms","start":"2026-07-14T23:02:33.113447Z","end":"2026-07-14T23:02:33.328311Z","steps":["trace[1661951835] 'process raft request' (duration: 214.009717ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:05:09.071641Z","caller":"traceutil/trace.go:171","msg":"trace[944456210] transaction","detail":"{read_only:false; response_revision:3885; number_of_response:1; }","duration":"221.587794ms","start":"2026-07-14T23:05:08.850003Z","end":"2026-07-14T23:05:09.071591Z","steps":["trace[944456210] 'process raft request' (duration: 221.305198ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:41.743605Z","caller":"traceutil/trace.go:171","msg":"trace[289465985] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"130.349552ms","start":"2026-07-14T23:06:41.613228Z","end":"2026-07-14T23:06:41.743577Z","steps":["trace[289465985] 'process raft request' (duration: 130.168039ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:41.781041Z","caller":"traceutil/trace.go:171","msg":"trace[1384600475] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"143.45565ms","start":"2026-07-14T23:06:41.637559Z","end":"2026-07-14T23:06:41.781015Z","steps":["trace[1384600475] 'process raft request' (duration: 143.271936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:41.814226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.405189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-14T23:06:41.814305Z","caller":"traceutil/trace.go:171","msg":"trace[755050174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5124; }","duration":"116.538721ms","start":"2026-07-14T23:06:41.697748Z","end":"2026-07-14T23:06:41.814287Z","steps":["trace[755050174] 'agreement among raft nodes before linearized reading' (duration: 116.336518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:54.079402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.272536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:06:54.07977Z","caller":"traceutil/trace.go:171","msg":"trace[629547857] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"302.28079ms","start":"2026-07-14T23:06:53.777438Z","end":"2026-07-14T23:06:54.079719Z","steps":["trace[629547857] 'process raft request' (duration: 302.143837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:54.07984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:53.777431Z","time spent":"302.379522ms","remote":"127.0.0.1:42674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":682,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:06:54.080084Z","caller":"traceutil/trace.go:171","msg":"trace[846492441] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"302.88805ms","start":"2026-07-14T23:06:53.777185Z","end":"2026-07-14T23:06:54.080073Z","steps":["trace[846492441] 'process raft request' (duration: 302.33395ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:54.08007Z","caller":"traceutil/trace.go:171","msg":"trace[424722441] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"302.752038ms","start":"2026-07-14T23:06:53.777155Z","end":"2026-07-14T23:06:54.079907Z","steps":["trace[424722441] 'process raft request' (duration: 33.87867ms)","trace[424722441] 'compare' (duration: 268.139054ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:06:54.080128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:53.777152Z","time spent":"302.952192ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:06:54.080231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:53.777135Z","time spent":"303.005803ms","remote":"127.0.0.1:42674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:06:54.080484Z","caller":"traceutil/trace.go:171","msg":"trace[1170783817] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"301.350331ms","start":"2026-07-14T23:06:53.779106Z","end":"2026-07-14T23:06:54.080456Z","steps":["trace[1170783817] 'process raft request' (duration: 300.512836ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:06:54.080575Z","caller":"traceutil/trace.go:171","msg":"trace[587690596] linearizableReadLoop","detail":"{readStateIndex:5565; appliedIndex:5563; }","duration":"125.52924ms","start":"2026-07-14T23:06:53.955021Z","end":"2026-07-14T23:06:54.08055Z","steps":["trace[587690596] 'read index received' (duration: 125.097542ms)","trace[587690596] 'applied index is now lower than readState.Index' (duration: 430.478µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:06:54.080678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:06:53.779098Z","time spent":"301.471073ms","remote":"127.0.0.1:42792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:06:54.080664Z","caller":"traceutil/trace.go:171","msg":"trace[736122678] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"247.442673ms","start":"2026-07-14T23:06:53.833171Z","end":"2026-07-14T23:06:54.080614Z","steps":["trace[736122678] 'process raft request' (duration: 247.044146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:54.080769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.747524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-14T23:06:54.080793Z","caller":"traceutil/trace.go:171","msg":"trace[1249838977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5366; }","duration":"125.822825ms","start":"2026-07-14T23:06:53.954962Z","end":"2026-07-14T23:06:54.080785Z","steps":["trace[1249838977] 'agreement among raft nodes before linearized reading' (duration: 125.694253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:06:54.450218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.752574ms","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-07-14T23:06:54.450312Z","caller":"traceutil/trace.go:171","msg":"trace[1912404323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5367; }","duration":"216.933867ms","start":"2026-07-14T23:06:54.233355Z","end":"2026-07-14T23:06:54.450289Z","steps":["trace[1912404323] 'range keys from in-memory index tree' (duration: 216.557921ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:07:52.056115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-07-14T23:07:52.133303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"75.107469ms","hash":3736950219,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":33673216,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-14T23:07:52.133379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3736950219,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-07-14T23:12:52.084682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6009} {"level":"info","ts":"2026-07-14T23:12:52.228629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6009,"took":"141.266192ms","hash":1445168589,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":21819392,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-14T23:12:52.228697Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1445168589,"revision":6009,"compact-revision":2967} {"level":"warn","ts":"2026-07-14T23:12:59.114687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.97194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:12:59.114799Z","caller":"traceutil/trace.go:171","msg":"trace[316905732] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:8307; }","duration":"194.160123ms","start":"2026-07-14T23:12:58.920614Z","end":"2026-07-14T23:12:59.114774Z","steps":["trace[316905732] 'count revisions from in-memory index tree' (duration: 193.712044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:11.157472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.804456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:13:11.157593Z","caller":"traceutil/trace.go:171","msg":"trace[191331184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8369; }","duration":"125.9905ms","start":"2026-07-14T23:13:11.031576Z","end":"2026-07-14T23:13:11.157566Z","steps":["trace[191331184] 'range keys from in-memory index tree' (duration: 125.697874ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:12.026791Z","caller":"traceutil/trace.go:171","msg":"trace[938614620] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"253.807223ms","start":"2026-07-14T23:13:11.77293Z","end":"2026-07-14T23:13:12.026737Z","steps":["trace[938614620] 'process raft request' (duration: 253.618ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:38.918853Z","caller":"traceutil/trace.go:171","msg":"trace[2024065023] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"117.824573ms","start":"2026-07-14T23:13:38.800968Z","end":"2026-07-14T23:13:38.918793Z","steps":["trace[2024065023] 'process raft request' (duration: 117.596779ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:39.098448Z","caller":"traceutil/trace.go:171","msg":"trace[1523677592] linearizableReadLoop","detail":"{readStateIndex:8871; appliedIndex:8870; }","duration":"114.230234ms","start":"2026-07-14T23:13:38.984196Z","end":"2026-07-14T23:13:39.098426Z","steps":["trace[1523677592] 'read index received' (duration: 113.626523ms)","trace[1523677592] 'applied index is now lower than readState.Index' (duration: 602.841µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:13:39.098652Z","caller":"traceutil/trace.go:171","msg":"trace[1565278755] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"174.868484ms","start":"2026-07-14T23:13:38.923776Z","end":"2026-07-14T23:13:39.098645Z","steps":["trace[1565278755] 'process raft request' (duration: 174.070289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:39.098866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.659701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T23:13:39.098891Z","caller":"traceutil/trace.go:171","msg":"trace[27895317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8568; }","duration":"114.750114ms","start":"2026-07-14T23:13:38.984135Z","end":"2026-07-14T23:13:39.098885Z","steps":["trace[27895317] 'agreement among raft nodes before linearized reading' (duration: 114.648512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:39.098993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.93385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T23:13:39.099021Z","caller":"traceutil/trace.go:171","msg":"trace[1565927468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8568; }","duration":"113.001172ms","start":"2026-07-14T23:13:38.986007Z","end":"2026-07-14T23:13:39.099008Z","steps":["trace[1565927468] 'agreement among raft nodes before linearized reading' (duration: 112.954941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:39.099208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.92335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-14T23:13:39.099238Z","caller":"traceutil/trace.go:171","msg":"trace[136680778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8568; }","duration":"112.978041ms","start":"2026-07-14T23:13:38.986255Z","end":"2026-07-14T23:13:39.099233Z","steps":["trace[136680778] 'agreement among raft nodes before linearized reading' (duration: 112.911019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:39.099382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.975357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T23:13:39.09947Z","caller":"traceutil/trace.go:171","msg":"trace[1857936915] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8568; }","duration":"106.11504ms","start":"2026-07-14T23:13:38.993333Z","end":"2026-07-14T23:13:39.099448Z","steps":["trace[1857936915] 'agreement among raft nodes before linearized reading' (duration: 105.819754ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:41.21476Z","caller":"traceutil/trace.go:171","msg":"trace[133273569] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"101.165656ms","start":"2026-07-14T23:13:41.113542Z","end":"2026-07-14T23:13:41.214708Z","steps":["trace[133273569] 'process raft request' (duration: 84.823843ms)","trace[133273569] 'compare' (duration: 16.135078ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:13:41.214889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.496389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T23:13:41.214987Z","caller":"traceutil/trace.go:171","msg":"trace[1529149708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8580; }","duration":"104.644782ms","start":"2026-07-14T23:13:41.110319Z","end":"2026-07-14T23:13:41.214964Z","steps":["trace[1529149708] 'agreement among raft nodes before linearized reading' (duration: 104.362746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:41.21943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.55091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-chz9z\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-14T23:13:41.219476Z","caller":"traceutil/trace.go:171","msg":"trace[2022401378] range","detail":"{range_begin:/registry/pods/openstack/keepalived-chz9z; range_end:; response_count:1; response_revision:8581; }","duration":"104.647431ms","start":"2026-07-14T23:13:41.114816Z","end":"2026-07-14T23:13:41.219464Z","steps":["trace[2022401378] 'agreement among raft nodes before linearized reading' (duration: 104.464758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:41.21974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.629301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-14T23:13:41.219784Z","caller":"traceutil/trace.go:171","msg":"trace[1358867586] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8581; }","duration":"104.689722ms","start":"2026-07-14T23:13:41.115081Z","end":"2026-07-14T23:13:41.219771Z","steps":["trace[1358867586] 'agreement among raft nodes before linearized reading' (duration: 104.59028ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:51.732568Z","caller":"traceutil/trace.go:171","msg":"trace[1526658843] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8986; }","duration":"111.91437ms","start":"2026-07-14T23:13:51.620617Z","end":"2026-07-14T23:13:51.732531Z","steps":["trace[1526658843] 'read index received' (duration: 107.21148ms)","trace[1526658843] 'applied index is now lower than readState.Index' (duration: 4.7013ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:13:51.732632Z","caller":"traceutil/trace.go:171","msg":"trace[1619560434] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"145.295209ms","start":"2026-07-14T23:13:51.587294Z","end":"2026-07-14T23:13:51.732589Z","steps":["trace[1619560434] 'process raft request' (duration: 140.604269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:13:51.733021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.375898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rgrjx\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-07-14T23:13:51.733129Z","caller":"traceutil/trace.go:171","msg":"trace[781803601] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rgrjx; range_end:; response_count:1; response_revision:8678; }","duration":"112.544932ms","start":"2026-07-14T23:13:51.620557Z","end":"2026-07-14T23:13:51.733102Z","steps":["trace[781803601] 'agreement among raft nodes before linearized reading' (duration: 112.133444ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:51.745993Z","caller":"traceutil/trace.go:171","msg":"trace[111751250] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"124.512022ms","start":"2026-07-14T23:13:51.621465Z","end":"2026-07-14T23:13:51.745978Z","steps":["trace[111751250] 'process raft request' (duration: 124.256587ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:52.309809Z","caller":"traceutil/trace.go:171","msg":"trace[769647101] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"120.391023ms","start":"2026-07-14T23:13:52.189386Z","end":"2026-07-14T23:13:52.309777Z","steps":["trace[769647101] 'process raft request' (duration: 120.199859ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:52.334979Z","caller":"traceutil/trace.go:171","msg":"trace[1161440433] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"136.068072ms","start":"2026-07-14T23:13:52.198879Z","end":"2026-07-14T23:13:52.334947Z","steps":["trace[1161440433] 'process raft request' (duration: 135.858698ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:53.075361Z","caller":"traceutil/trace.go:171","msg":"trace[453402070] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"105.301745ms","start":"2026-07-14T23:13:52.97003Z","end":"2026-07-14T23:13:53.075331Z","steps":["trace[453402070] 'process raft request' (duration: 104.964068ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:13:53.506383Z","caller":"traceutil/trace.go:171","msg":"trace[272475364] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"103.395807ms","start":"2026-07-14T23:13:53.40296Z","end":"2026-07-14T23:13:53.506355Z","steps":["trace[272475364] 'process raft request' (duration: 103.199543ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:14:15.522962Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002439.wal"} {"level":"info","ts":"2026-07-14T23:15:37.507454Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:15:37.517987Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-14T23:15:37.51821Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-14T23:17:52.090853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8128} {"level":"info","ts":"2026-07-14T23:17:52.155967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8128,"took":"62.628069ms","hash":2273474124,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":29564928,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-14T23:17:52.156046Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2273474124,"revision":8128,"compact-revision":6009} {"level":"info","ts":"2026-07-14T23:18:35.637363Z","caller":"traceutil/trace.go:171","msg":"trace[1818320264] transaction","detail":"{read_only:false; response_revision:11230; number_of_response:1; }","duration":"114.506172ms","start":"2026-07-14T23:18:35.522796Z","end":"2026-07-14T23:18:35.637303Z","steps":["trace[1818320264] 'process raft request' (duration: 114.317788ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:18:35.646171Z","caller":"traceutil/trace.go:171","msg":"trace[639235638] transaction","detail":"{read_only:false; response_revision:11231; number_of_response:1; }","duration":"107.268504ms","start":"2026-07-14T23:18:35.538884Z","end":"2026-07-14T23:18:35.646152Z","steps":["trace[639235638] 'process raft request' (duration: 106.805555ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:18:36.328129Z","caller":"traceutil/trace.go:171","msg":"trace[1173816918] transaction","detail":"{read_only:false; response_revision:11233; number_of_response:1; }","duration":"100.442744ms","start":"2026-07-14T23:18:36.227657Z","end":"2026-07-14T23:18:36.328099Z","steps":["trace[1173816918] 'process raft request' (duration: 100.197739ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:22:52.098776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10796} {"level":"info","ts":"2026-07-14T23:22:52.190119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10796,"took":"87.592247ms","hash":2139306319,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":27987968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-14T23:22:52.190204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2139306319,"revision":10796,"compact-revision":8128} {"level":"warn","ts":"2026-07-14T23:25:25.820685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.272095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-234ee230-96b8-47c8-9f07-4976845fdf11\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-07-14T23:25:25.820823Z","caller":"traceutil/trace.go:171","msg":"trace[1047964638] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-234ee230-96b8-47c8-9f07-4976845fdf11; range_end:; response_count:1; response_revision:14149; }","duration":"277.50672ms","start":"2026-07-14T23:25:25.543288Z","end":"2026-07-14T23:25:25.820795Z","steps":["trace[1047964638] 'range keys from in-memory index tree' (duration: 277.017149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:26:42.60878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.9406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-14T23:26:42.608867Z","caller":"traceutil/trace.go:171","msg":"trace[105043820] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14910; }","duration":"108.091463ms","start":"2026-07-14T23:26:42.500756Z","end":"2026-07-14T23:26:42.608847Z","steps":["trace[105043820] 'range keys from in-memory index tree' (duration: 107.804067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:26:42.609085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.153664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:26:42.609114Z","caller":"traceutil/trace.go:171","msg":"trace[580145817] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14910; }","duration":"108.226186ms","start":"2026-07-14T23:26:42.500878Z","end":"2026-07-14T23:26:42.609105Z","steps":["trace[580145817] 'range keys from in-memory index tree' (duration: 107.982151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:26:42.609252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.294897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:26:42.60928Z","caller":"traceutil/trace.go:171","msg":"trace[1882028272] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14910; }","duration":"108.373289ms","start":"2026-07-14T23:26:42.500897Z","end":"2026-07-14T23:26:42.60927Z","steps":["trace[1882028272] 'range keys from in-memory index tree' (duration: 108.130864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:26:42.60978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.961843ms","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-07-14T23:26:42.609849Z","caller":"traceutil/trace.go:171","msg":"trace[908985108] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14910; }","duration":"107.051194ms","start":"2026-07-14T23:26:42.502783Z","end":"2026-07-14T23:26:42.609834Z","steps":["trace[908985108] 'range keys from in-memory index tree' (duration: 106.888041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:26:42.609906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.666485ms","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-07-14T23:26:42.60994Z","caller":"traceutil/trace.go:171","msg":"trace[1178441676] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14910; }","duration":"108.715006ms","start":"2026-07-14T23:26:42.501216Z","end":"2026-07-14T23:26:42.609931Z","steps":["trace[1178441676] 'range keys from in-memory index tree' (duration: 108.607814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:26:42.609984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.226928ms","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-07-14T23:26:42.610012Z","caller":"traceutil/trace.go:171","msg":"trace[785486953] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14910; }","duration":"107.291268ms","start":"2026-07-14T23:26:42.502715Z","end":"2026-07-14T23:26:42.610006Z","steps":["trace[785486953] 'range keys from in-memory index tree' (duration: 107.125825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:26:42.610073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.118013ms","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-07-14T23:26:42.610096Z","caller":"traceutil/trace.go:171","msg":"trace[1464698672] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14910; }","duration":"109.140624ms","start":"2026-07-14T23:26:42.50095Z","end":"2026-07-14T23:26:42.610091Z","steps":["trace[1464698672] 'range keys from in-memory index tree' (duration: 108.934249ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:27:52.114773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12902} {"level":"info","ts":"2026-07-14T23:27:52.191489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12902,"took":"73.726467ms","hash":2001695792,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-14T23:27:52.191547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2001695792,"revision":12902,"compact-revision":10796} {"level":"info","ts":"2026-07-14T23:32:24.369983Z","caller":"traceutil/trace.go:171","msg":"trace[1004251885] linearizableReadLoop","detail":"{readStateIndex:18057; appliedIndex:18056; }","duration":"204.236298ms","start":"2026-07-14T23:32:24.165722Z","end":"2026-07-14T23:32:24.369958Z","steps":["trace[1004251885] 'read index received' (duration: 203.962173ms)","trace[1004251885] 'applied index is now lower than readState.Index' (duration: 273.225µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:32:24.370151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.413411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:32:24.370122Z","caller":"traceutil/trace.go:171","msg":"trace[467297092] transaction","detail":"{read_only:false; response_revision:17462; number_of_response:1; }","duration":"223.292652ms","start":"2026-07-14T23:32:24.146783Z","end":"2026-07-14T23:32:24.370076Z","steps":["trace[467297092] 'process raft request' (duration: 222.997867ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:32:24.370193Z","caller":"traceutil/trace.go:171","msg":"trace[1642542989] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:17462; }","duration":"204.474332ms","start":"2026-07-14T23:32:24.165706Z","end":"2026-07-14T23:32:24.37018Z","steps":["trace[1642542989] 'agreement among raft nodes before linearized reading' (duration: 204.35575ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:32:24.95559Z","caller":"traceutil/trace.go:171","msg":"trace[1471842538] transaction","detail":"{read_only:false; response_revision:17463; number_of_response:1; }","duration":"134.409785ms","start":"2026-07-14T23:32:24.821152Z","end":"2026-07-14T23:32:24.955562Z","steps":["trace[1471842538] 'process raft request' (duration: 134.258772ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:32:52.126023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15365} {"level":"info","ts":"2026-07-14T23:32:52.227867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15365,"took":"97.871857ms","hash":3631495246,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":32731136,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-14T23:32:52.22795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3631495246,"revision":15365,"compact-revision":12902} {"level":"info","ts":"2026-07-14T23:36:12.012958Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:36:12.018001Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-14T23:36:12.018129Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-07-14T23:37:40.545706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.058102ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-14T23:37:40.546217Z","caller":"traceutil/trace.go:171","msg":"trace[1053708625] transaction","detail":"{read_only:false; response_revision:19988; number_of_response:1; }","duration":"162.542531ms","start":"2026-07-14T23:37:40.383647Z","end":"2026-07-14T23:37:40.546189Z","steps":["trace[1053708625] 'process raft request' (duration: 162.455479ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:40.546331Z","caller":"traceutil/trace.go:171","msg":"trace[1423276973] transaction","detail":"{read_only:false; response_revision:19987; number_of_response:1; }","duration":"179.819876ms","start":"2026-07-14T23:37:40.366464Z","end":"2026-07-14T23:37:40.546284Z","steps":["trace[1423276973] 'process raft request' (duration: 179.451628ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:52.135222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17773} {"level":"info","ts":"2026-07-14T23:37:52.238674Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17773,"took":"99.442532ms","hash":3494938806,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":33415168,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-14T23:37:52.238741Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3494938806,"revision":17773,"compact-revision":15365} {"level":"info","ts":"2026-07-14T23:37:56.967178Z","caller":"traceutil/trace.go:171","msg":"trace[1580462091] transaction","detail":"{read_only:false; response_revision:20072; number_of_response:1; }","duration":"116.412422ms","start":"2026-07-14T23:37:56.85072Z","end":"2026-07-14T23:37:56.967132Z","steps":["trace[1580462091] 'process raft request' (duration: 116.088125ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:57.54572Z","caller":"traceutil/trace.go:171","msg":"trace[2041907023] linearizableReadLoop","detail":"{readStateIndex:20755; appliedIndex:20754; }","duration":"224.525437ms","start":"2026-07-14T23:37:57.321159Z","end":"2026-07-14T23:37:57.545685Z","steps":["trace[2041907023] 'read index received' (duration: 224.061159ms)","trace[2041907023] 'applied index is now lower than readState.Index' (duration: 462.518µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:37:57.545786Z","caller":"traceutil/trace.go:171","msg":"trace[1643692378] transaction","detail":"{read_only:false; response_revision:20074; number_of_response:1; }","duration":"224.605559ms","start":"2026-07-14T23:37:57.321128Z","end":"2026-07-14T23:37:57.545734Z","steps":["trace[1643692378] 'process raft request' (duration: 224.335374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:57.546181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.971635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-14T23:37:57.546488Z","caller":"traceutil/trace.go:171","msg":"trace[1824902988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20074; }","duration":"225.367602ms","start":"2026-07-14T23:37:57.321102Z","end":"2026-07-14T23:37:57.54647Z","steps":["trace[1824902988] 'agreement among raft nodes before linearized reading' (duration: 224.782182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:57.546225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.134738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-14T23:37:57.546803Z","caller":"traceutil/trace.go:171","msg":"trace[1288915805] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20074; }","duration":"158.75394ms","start":"2026-07-14T23:37:57.388028Z","end":"2026-07-14T23:37:57.546782Z","steps":["trace[1288915805] 'agreement among raft nodes before linearized reading' (duration: 157.994555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:57.546203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.79731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:37:57.546972Z","caller":"traceutil/trace.go:171","msg":"trace[629529978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20074; }","duration":"159.648266ms","start":"2026-07-14T23:37:57.387309Z","end":"2026-07-14T23:37:57.546957Z","steps":["trace[629529978] 'agreement among raft nodes before linearized reading' (duration: 158.679188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:37:57.546293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.705419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:37:57.547199Z","caller":"traceutil/trace.go:171","msg":"trace[1960404488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20074; }","duration":"159.625896ms","start":"2026-07-14T23:37:57.387534Z","end":"2026-07-14T23:37:57.54716Z","steps":["trace[1960404488] 'agreement among raft nodes before linearized reading' (duration: 158.505615ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:57.700726Z","caller":"traceutil/trace.go:171","msg":"trace[95302601] transaction","detail":"{read_only:false; response_revision:20075; number_of_response:1; }","duration":"148.636349ms","start":"2026-07-14T23:37:57.552061Z","end":"2026-07-14T23:37:57.700697Z","steps":["trace[95302601] 'process raft request' (duration: 94.619032ms)","trace[95302601] 'compare' (duration: 53.883935ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:37:57.704448Z","caller":"traceutil/trace.go:171","msg":"trace[1891877952] transaction","detail":"{read_only:false; response_revision:20078; number_of_response:1; }","duration":"151.034374ms","start":"2026-07-14T23:37:57.553394Z","end":"2026-07-14T23:37:57.704429Z","steps":["trace[1891877952] 'process raft request' (duration: 150.993933ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:57.70449Z","caller":"traceutil/trace.go:171","msg":"trace[231910331] transaction","detail":"{read_only:false; response_revision:20076; number_of_response:1; }","duration":"151.95226ms","start":"2026-07-14T23:37:57.552503Z","end":"2026-07-14T23:37:57.704455Z","steps":["trace[231910331] 'process raft request' (duration: 151.714166ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:37:57.704548Z","caller":"traceutil/trace.go:171","msg":"trace[1741171031] transaction","detail":"{read_only:false; response_revision:20077; number_of_response:1; }","duration":"151.712956ms","start":"2026-07-14T23:37:57.552771Z","end":"2026-07-14T23:37:57.704484Z","steps":["trace[1741171031] 'process raft request' (duration: 151.575773ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:41:40.411445Z","caller":"traceutil/trace.go:171","msg":"trace[1127216522] transaction","detail":"{read_only:false; response_revision:22453; number_of_response:1; }","duration":"230.206115ms","start":"2026-07-14T23:41:40.181205Z","end":"2026-07-14T23:41:40.411411Z","steps":["trace[1127216522] 'process raft request' (duration: 229.96368ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:41:40.412985Z","caller":"traceutil/trace.go:171","msg":"trace[1719092385] linearizableReadLoop","detail":"{readStateIndex:23204; appliedIndex:23202; }","duration":"117.681256ms","start":"2026-07-14T23:41:40.295273Z","end":"2026-07-14T23:41:40.412954Z","steps":["trace[1719092385] 'read index received' (duration: 115.912972ms)","trace[1719092385] 'applied index is now lower than readState.Index' (duration: 1.767124ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:41:40.413036Z","caller":"traceutil/trace.go:171","msg":"trace[478692338] transaction","detail":"{read_only:false; response_revision:22454; number_of_response:1; }","duration":"205.442219ms","start":"2026-07-14T23:41:40.207572Z","end":"2026-07-14T23:41:40.413014Z","steps":["trace[478692338] 'process raft request' (duration: 205.239786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:41:40.413211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.91655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-14T23:41:40.413288Z","caller":"traceutil/trace.go:171","msg":"trace[975330778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22454; }","duration":"118.055043ms","start":"2026-07-14T23:41:40.295219Z","end":"2026-07-14T23:41:40.413274Z","steps":["trace[975330778] 'agreement among raft nodes before linearized reading' (duration: 117.8587ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:42:52.143072Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20048} {"level":"info","ts":"2026-07-14T23:42:52.268921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20048,"took":"120.601731ms","hash":2129704458,"current-db-size-bytes":58355712,"current-db-size":"58 MB","current-db-size-in-use-bytes":42270720,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-14T23:42:52.268998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2129704458,"revision":20048,"compact-revision":17773} {"level":"warn","ts":"2026-07-14T23:45:08.418977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.573025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:45:08.419171Z","caller":"traceutil/trace.go:171","msg":"trace[961240350] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"280.419496ms","start":"2026-07-14T23:45:08.138738Z","end":"2026-07-14T23:45:08.419157Z","steps":["trace[961240350] 'process raft request' (duration: 280.335455ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:08.419203Z","caller":"traceutil/trace.go:171","msg":"trace[365853255] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"280.782103ms","start":"2026-07-14T23:45:08.138386Z","end":"2026-07-14T23:45:08.419168Z","steps":["trace[365853255] 'process raft request' (duration: 140.938653ms)","trace[365853255] 'compare' (duration: 139.244759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:19.63713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.057893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:45:19.637298Z","caller":"traceutil/trace.go:171","msg":"trace[33566542] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"299.580363ms","start":"2026-07-14T23:45:19.337705Z","end":"2026-07-14T23:45:19.637286Z","steps":["trace[33566542] 'process raft request' (duration: 299.510382ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:19.637425Z","caller":"traceutil/trace.go:171","msg":"trace[2006294303] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"301.760895ms","start":"2026-07-14T23:45:19.335655Z","end":"2026-07-14T23:45:19.637416Z","steps":["trace[2006294303] 'process raft request' (duration: 147.338774ms)","trace[2006294303] 'compare' (duration: 153.961531ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:19.637468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:19.335619Z","time spent":"301.831397ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:45:19.63752Z","caller":"traceutil/trace.go:171","msg":"trace[409429315] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"152.126517ms","start":"2026-07-14T23:45:19.485349Z","end":"2026-07-14T23:45:19.637476Z","steps":["trace[409429315] 'process raft request' (duration: 151.906653ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:24.463133Z","caller":"traceutil/trace.go:171","msg":"trace[508135140] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"105.249761ms","start":"2026-07-14T23:45:24.357838Z","end":"2026-07-14T23:45:24.463088Z","steps":["trace[508135140] 'process raft request' (duration: 91.460247ms)","trace[508135140] 'compare' (duration: 13.382926ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:29.824996Z","caller":"traceutil/trace.go:171","msg":"trace[123213255] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"143.227085ms","start":"2026-07-14T23:45:29.681738Z","end":"2026-07-14T23:45:29.824965Z","steps":["trace[123213255] 'process raft request' (duration: 142.996291ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:29.874843Z","caller":"traceutil/trace.go:171","msg":"trace[311443302] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"191.781414ms","start":"2026-07-14T23:45:29.683031Z","end":"2026-07-14T23:45:29.874813Z","steps":["trace[311443302] 'process raft request' (duration: 191.55251ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:29.874912Z","caller":"traceutil/trace.go:171","msg":"trace[298869469] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"190.802274ms","start":"2026-07-14T23:45:29.684072Z","end":"2026-07-14T23:45:29.874875Z","steps":["trace[298869469] 'process raft request' (duration: 190.663092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:29.875337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.543613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-14T23:45:29.875359Z","caller":"traceutil/trace.go:171","msg":"trace[323762864] linearizableReadLoop","detail":"{readStateIndex:25537; appliedIndex:25534; }","duration":"187.057842ms","start":"2026-07-14T23:45:29.687723Z","end":"2026-07-14T23:45:29.87478Z","steps":["trace[323762864] 'read index received' (duration: 136.977996ms)","trace[323762864] 'applied index is now lower than readState.Index' (duration: 50.078826ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:29.875427Z","caller":"traceutil/trace.go:171","msg":"trace[907180249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24727; }","duration":"187.704696ms","start":"2026-07-14T23:45:29.687698Z","end":"2026-07-14T23:45:29.875403Z","steps":["trace[907180249] 'agreement among raft nodes before linearized reading' (duration: 187.3611ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.175817Z","caller":"traceutil/trace.go:171","msg":"trace[658556301] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"295.562566ms","start":"2026-07-14T23:45:29.880233Z","end":"2026-07-14T23:45:30.175795Z","steps":["trace[658556301] 'process raft request' (duration: 295.435354ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.287597Z","caller":"traceutil/trace.go:171","msg":"trace[1096418577] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"360.68178ms","start":"2026-07-14T23:45:29.926892Z","end":"2026-07-14T23:45:30.287573Z","steps":["trace[1096418577] 'process raft request' (duration: 358.1059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:30.287805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:29.926865Z","time spent":"360.815073ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:45:30.287994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.11048ms","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-07-14T23:45:30.288051Z","caller":"traceutil/trace.go:171","msg":"trace[928753293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24729; }","duration":"248.224043ms","start":"2026-07-14T23:45:30.039813Z","end":"2026-07-14T23:45:30.288037Z","steps":["trace[928753293] 'agreement among raft nodes before linearized reading' (duration: 248.031539ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.288191Z","caller":"traceutil/trace.go:171","msg":"trace[994238681] linearizableReadLoop","detail":"{readStateIndex:25539; appliedIndex:25537; }","duration":"247.646381ms","start":"2026-07-14T23:45:30.039864Z","end":"2026-07-14T23:45:30.287511Z","steps":["trace[994238681] 'read index received' (duration: 135.893606ms)","trace[994238681] 'applied index is now lower than readState.Index' (duration: 111.751225ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:30.288357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.022907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:30.288527Z","caller":"traceutil/trace.go:171","msg":"trace[396487482] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24729; }","duration":"194.219881ms","start":"2026-07-14T23:45:30.094289Z","end":"2026-07-14T23:45:30.288509Z","steps":["trace[396487482] 'agreement among raft nodes before linearized reading' (duration: 194.016797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:30.288536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.248821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:30.288625Z","caller":"traceutil/trace.go:171","msg":"trace[1582971816] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:24729; }","duration":"171.372334ms","start":"2026-07-14T23:45:30.117235Z","end":"2026-07-14T23:45:30.288607Z","steps":["trace[1582971816] 'agreement among raft nodes before linearized reading' (duration: 171.217621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:30.574503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.115813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:45:30.574784Z","caller":"traceutil/trace.go:171","msg":"trace[1596213472] linearizableReadLoop","detail":"{readStateIndex:25541; appliedIndex:25539; }","duration":"180.756043ms","start":"2026-07-14T23:45:30.394005Z","end":"2026-07-14T23:45:30.574761Z","steps":["trace[1596213472] 'read index received' (duration: 46.365845ms)","trace[1596213472] 'applied index is now lower than readState.Index' (duration: 134.388628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:30.574981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.982568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-14T23:45:30.574967Z","caller":"traceutil/trace.go:171","msg":"trace[577132891] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"236.100229ms","start":"2026-07-14T23:45:30.338743Z","end":"2026-07-14T23:45:30.574843Z","steps":["trace[577132891] 'process raft request' (duration: 235.905706ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.57488Z","caller":"traceutil/trace.go:171","msg":"trace[1811943195] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"281.61312ms","start":"2026-07-14T23:45:30.293218Z","end":"2026-07-14T23:45:30.574831Z","steps":["trace[1811943195] 'process raft request' (duration: 147.041539ms)","trace[1811943195] 'compare' (duration: 133.784207ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:30.575025Z","caller":"traceutil/trace.go:171","msg":"trace[134916545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24731; }","duration":"181.08034ms","start":"2026-07-14T23:45:30.393933Z","end":"2026-07-14T23:45:30.575013Z","steps":["trace[134916545] 'agreement among raft nodes before linearized reading' (duration: 180.925137ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.765105Z","caller":"traceutil/trace.go:171","msg":"trace[2012481341] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"223.226134ms","start":"2026-07-14T23:45:30.541847Z","end":"2026-07-14T23:45:30.765073Z","steps":["trace[2012481341] 'process raft request' (duration: 222.904038ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.765758Z","caller":"traceutil/trace.go:171","msg":"trace[633447142] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"205.933774ms","start":"2026-07-14T23:45:30.559794Z","end":"2026-07-14T23:45:30.765728Z","steps":["trace[633447142] 'process raft request' (duration: 205.121768ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.766002Z","caller":"traceutil/trace.go:171","msg":"trace[593375] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"186.559224ms","start":"2026-07-14T23:45:30.579413Z","end":"2026-07-14T23:45:30.765972Z","steps":["trace[593375] 'process raft request' (duration: 186.270519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:30.766243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.209549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:45:30.76634Z","caller":"traceutil/trace.go:171","msg":"trace[2028819637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24734; }","duration":"219.344291ms","start":"2026-07-14T23:45:30.546974Z","end":"2026-07-14T23:45:30.766319Z","steps":["trace[2028819637] 'agreement among raft nodes before linearized reading' (duration: 218.77512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:30.766382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.546026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-14T23:45:30.76647Z","caller":"traceutil/trace.go:171","msg":"trace[426320211] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24734; }","duration":"272.68168ms","start":"2026-07-14T23:45:30.493763Z","end":"2026-07-14T23:45:30.766444Z","steps":["trace[426320211] 'agreement among raft nodes before linearized reading' (duration: 271.971417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:30.766486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.238971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:45:30.766528Z","caller":"traceutil/trace.go:171","msg":"trace[1869575258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24734; }","duration":"226.343843ms","start":"2026-07-14T23:45:30.540173Z","end":"2026-07-14T23:45:30.766517Z","steps":["trace[1869575258] 'agreement among raft nodes before linearized reading' (duration: 225.586719ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.766606Z","caller":"traceutil/trace.go:171","msg":"trace[60336326] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25541; }","duration":"190.905968ms","start":"2026-07-14T23:45:30.574823Z","end":"2026-07-14T23:45:30.765729Z","steps":["trace[60336326] 'read index received' (duration: 175.596065ms)","trace[60336326] 'applied index is now lower than readState.Index' (duration: 15.308713ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:30.929089Z","caller":"traceutil/trace.go:171","msg":"trace[20760837] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"156.675833ms","start":"2026-07-14T23:45:30.77237Z","end":"2026-07-14T23:45:30.929046Z","steps":["trace[20760837] 'process raft request' (duration: 156.289456ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.929397Z","caller":"traceutil/trace.go:171","msg":"trace[434496376] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"157.424358ms","start":"2026-07-14T23:45:30.771942Z","end":"2026-07-14T23:45:30.929367Z","steps":["trace[434496376] 'process raft request' (duration: 153.265778ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:30.929227Z","caller":"traceutil/trace.go:171","msg":"trace[1369302316] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"140.420483ms","start":"2026-07-14T23:45:30.78878Z","end":"2026-07-14T23:45:30.9292Z","steps":["trace[1369302316] 'process raft request' (duration: 139.986305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:31.214634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.383531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:31.214735Z","caller":"traceutil/trace.go:171","msg":"trace[1313243506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24738; }","duration":"185.563824ms","start":"2026-07-14T23:45:31.029146Z","end":"2026-07-14T23:45:31.21471Z","steps":["trace[1313243506] 'range keys from in-memory index tree' (duration: 185.224628ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:31.690408Z","caller":"traceutil/trace.go:171","msg":"trace[661866076] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"274.231979ms","start":"2026-07-14T23:45:31.41614Z","end":"2026-07-14T23:45:31.690372Z","steps":["trace[661866076] 'process raft request' (duration: 274.012185ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:32.317723Z","caller":"traceutil/trace.go:171","msg":"trace[130553377] transaction","detail":"{read_only:false; response_revision:24743; number_of_response:1; }","duration":"126.464516ms","start":"2026-07-14T23:45:32.191229Z","end":"2026-07-14T23:45:32.317693Z","steps":["trace[130553377] 'process raft request' (duration: 126.278072ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:32.588752Z","caller":"traceutil/trace.go:171","msg":"trace[1043398690] transaction","detail":"{read_only:false; response_revision:24744; number_of_response:1; }","duration":"376.051695ms","start":"2026-07-14T23:45:32.212666Z","end":"2026-07-14T23:45:32.588718Z","steps":["trace[1043398690] 'process raft request' (duration: 375.506234ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:32.588705Z","caller":"traceutil/trace.go:171","msg":"trace[824496054] linearizableReadLoop","detail":"{readStateIndex:25555; appliedIndex:25553; }","duration":"327.458226ms","start":"2026-07-14T23:45:32.26121Z","end":"2026-07-14T23:45:32.588669Z","steps":["trace[824496054] 'read index received' (duration: 56.214574ms)","trace[824496054] 'applied index is now lower than readState.Index' (duration: 271.241992ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:32.588907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.664079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:32.58898Z","caller":"traceutil/trace.go:171","msg":"trace[1408807709] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24744; }","duration":"327.812432ms","start":"2026-07-14T23:45:32.261151Z","end":"2026-07-14T23:45:32.588963Z","steps":["trace[1408807709] 'agreement among raft nodes before linearized reading' (duration: 327.661639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:32.588978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:32.212642Z","time spent":"376.208767ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:45:32.589035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:32.261106Z","time spent":"327.917534ms","remote":"127.0.0.1:34912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-14T23:45:32.589136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.744825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-14T23:45:32.589198Z","caller":"traceutil/trace.go:171","msg":"trace[1832471191] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24744; }","duration":"166.853067ms","start":"2026-07-14T23:45:32.422331Z","end":"2026-07-14T23:45:32.589184Z","steps":["trace[1832471191] 'agreement among raft nodes before linearized reading' (duration: 166.649633ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:33.041481Z","caller":"traceutil/trace.go:171","msg":"trace[1817133691] transaction","detail":"{read_only:false; response_revision:24748; number_of_response:1; }","duration":"177.697705ms","start":"2026-07-14T23:45:32.863759Z","end":"2026-07-14T23:45:33.041457Z","steps":["trace[1817133691] 'process raft request' (duration: 129.950123ms)","trace[1817133691] 'compare' (duration: 47.325185ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:33.292547Z","caller":"traceutil/trace.go:171","msg":"trace[424554358] linearizableReadLoop","detail":"{readStateIndex:25561; appliedIndex:25558; }","duration":"357.774454ms","start":"2026-07-14T23:45:32.934752Z","end":"2026-07-14T23:45:33.292526Z","steps":["trace[424554358] 'read index received' (duration: 58.982377ms)","trace[424554358] 'applied index is now lower than readState.Index' (duration: 298.790967ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:33.292591Z","caller":"traceutil/trace.go:171","msg":"trace[1258556433] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"427.989546ms","start":"2026-07-14T23:45:32.864565Z","end":"2026-07-14T23:45:33.292555Z","steps":["trace[1258556433] 'process raft request' (duration: 420.665666ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:33.29267Z","caller":"traceutil/trace.go:171","msg":"trace[1717356523] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"358.404916ms","start":"2026-07-14T23:45:32.934255Z","end":"2026-07-14T23:45:33.29266Z","steps":["trace[1717356523] 'process raft request' (duration: 358.219262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:33.292767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:32.864536Z","time spent":"428.131988ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:45:33.292782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:32.934206Z","time spent":"358.488309ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:45:33.293015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.272554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:45:33.293036Z","caller":"traceutil/trace.go:171","msg":"trace[1686297337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24750; }","duration":"358.328805ms","start":"2026-07-14T23:45:32.934702Z","end":"2026-07-14T23:45:33.29303Z","steps":["trace[1686297337] 'agreement among raft nodes before linearized reading' (duration: 358.196203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:33.293057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:32.934691Z","time spent":"358.360286ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:45:33.293276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.500989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:45:33.293339Z","caller":"traceutil/trace.go:171","msg":"trace[803781418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24750; }","duration":"358.5896ms","start":"2026-07-14T23:45:32.934707Z","end":"2026-07-14T23:45:33.293297Z","steps":["trace[803781418] 'agreement among raft nodes before linearized reading' (duration: 358.468068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:33.293375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:32.934686Z","time spent":"358.681792ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:45:33.293452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.111816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:33.293479Z","caller":"traceutil/trace.go:171","msg":"trace[1252803769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24750; }","duration":"263.166237ms","start":"2026-07-14T23:45:33.030307Z","end":"2026-07-14T23:45:33.293473Z","steps":["trace[1252803769] 'agreement among raft nodes before linearized reading' (duration: 263.121257ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:33.47934Z","caller":"traceutil/trace.go:171","msg":"trace[1312597481] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"177.848347ms","start":"2026-07-14T23:45:33.301467Z","end":"2026-07-14T23:45:33.479315Z","steps":["trace[1312597481] 'process raft request' (duration: 177.780246ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:33.479437Z","caller":"traceutil/trace.go:171","msg":"trace[622399434] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"180.365026ms","start":"2026-07-14T23:45:33.299024Z","end":"2026-07-14T23:45:33.479389Z","steps":["trace[622399434] 'process raft request' (duration: 138.013067ms)","trace[622399434] 'compare' (duration: 42.033083ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:33.79888Z","caller":"traceutil/trace.go:171","msg":"trace[1695323441] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"102.532949ms","start":"2026-07-14T23:45:33.696299Z","end":"2026-07-14T23:45:33.798832Z","steps":["trace[1695323441] 'process raft request' (duration: 100.827807ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:34.197852Z","caller":"traceutil/trace.go:171","msg":"trace[1203166637] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25564; }","duration":"273.516445ms","start":"2026-07-14T23:45:33.924309Z","end":"2026-07-14T23:45:34.197825Z","steps":["trace[1203166637] 'read index received' (duration: 22.774475ms)","trace[1203166637] 'applied index is now lower than readState.Index' (duration: 250.74086ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:34.198104Z","caller":"traceutil/trace.go:171","msg":"trace[1196231013] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"300.883718ms","start":"2026-07-14T23:45:33.897206Z","end":"2026-07-14T23:45:34.198089Z","steps":["trace[1196231013] 'process raft request' (duration: 205.445715ms)","trace[1196231013] 'compare' (duration: 94.838832ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:34.198225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:33.897192Z","time spent":"300.951679ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:45:34.198428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.115856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:34.198471Z","caller":"traceutil/trace.go:171","msg":"trace[1478972180] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24755; }","duration":"274.195548ms","start":"2026-07-14T23:45:33.924264Z","end":"2026-07-14T23:45:34.198459Z","steps":["trace[1478972180] 'agreement among raft nodes before linearized reading' (duration: 274.127767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:34.199831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.941774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:34.199884Z","caller":"traceutil/trace.go:171","msg":"trace[2106915124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24756; }","duration":"172.031216ms","start":"2026-07-14T23:45:34.027841Z","end":"2026-07-14T23:45:34.199873Z","steps":["trace[2106915124] 'agreement among raft nodes before linearized reading' (duration: 171.929384ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:34.200119Z","caller":"traceutil/trace.go:171","msg":"trace[1175132518] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"236.142341ms","start":"2026-07-14T23:45:33.963965Z","end":"2026-07-14T23:45:34.200108Z","steps":["trace[1175132518] 'process raft request' (duration: 235.720363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:34.804589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.494562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-14T23:45:34.804665Z","caller":"traceutil/trace.go:171","msg":"trace[802821545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24757; }","duration":"165.624944ms","start":"2026-07-14T23:45:34.639022Z","end":"2026-07-14T23:45:34.804647Z","steps":["trace[802821545] 'range keys from in-memory index tree' (duration: 165.329098ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:35.029704Z","caller":"traceutil/trace.go:171","msg":"trace[996929257] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"166.055173ms","start":"2026-07-14T23:45:34.863444Z","end":"2026-07-14T23:45:35.0295Z","steps":["trace[996929257] 'process raft request' (duration: 98.354439ms)","trace[996929257] 'compare' (duration: 66.936759ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:35.088585Z","caller":"traceutil/trace.go:171","msg":"trace[1286498451] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"224.303474ms","start":"2026-07-14T23:45:34.864248Z","end":"2026-07-14T23:45:35.088552Z","steps":["trace[1286498451] 'process raft request' (duration: 224.08385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:35.421554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.863303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:45:35.422167Z","caller":"traceutil/trace.go:171","msg":"trace[1371919286] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"328.444105ms","start":"2026-07-14T23:45:35.093238Z","end":"2026-07-14T23:45:35.421682Z","steps":["trace[1371919286] 'process raft request' (duration: 121.325348ms)","trace[1371919286] 'compare' (duration: 206.561586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:35.422388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:35.093212Z","time spent":"329.032556ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:45:35.444144Z","caller":"traceutil/trace.go:171","msg":"trace[1597588756] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"150.305431ms","start":"2026-07-14T23:45:35.293812Z","end":"2026-07-14T23:45:35.444117Z","steps":["trace[1597588756] 'process raft request' (duration: 150.157048ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:35.465611Z","caller":"traceutil/trace.go:171","msg":"trace[2084594168] linearizableReadLoop","detail":"{readStateIndex:25574; appliedIndex:25573; }","duration":"166.599353ms","start":"2026-07-14T23:45:35.298978Z","end":"2026-07-14T23:45:35.465577Z","steps":["trace[2084594168] 'read index received' (duration: 145.163093ms)","trace[2084594168] 'applied index is now lower than readState.Index' (duration: 21.43487ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:35.465757Z","caller":"traceutil/trace.go:171","msg":"trace[117644610] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"166.9449ms","start":"2026-07-14T23:45:35.2988Z","end":"2026-07-14T23:45:35.465745Z","steps":["trace[117644610] 'process raft request' (duration: 166.504481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:35.466157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.202928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-14T23:45:35.466329Z","caller":"traceutil/trace.go:171","msg":"trace[129444015] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:24763; }","duration":"130.434951ms","start":"2026-07-14T23:45:35.335876Z","end":"2026-07-14T23:45:35.466311Z","steps":["trace[129444015] 'agreement among raft nodes before linearized reading' (duration: 130.120426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:35.466707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.742554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-14T23:45:35.466825Z","caller":"traceutil/trace.go:171","msg":"trace[948806308] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24763; }","duration":"167.925459ms","start":"2026-07-14T23:45:35.298887Z","end":"2026-07-14T23:45:35.466812Z","steps":["trace[948806308] 'agreement among raft nodes before linearized reading' (duration: 167.745145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:35.467197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.9601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-14T23:45:35.467302Z","caller":"traceutil/trace.go:171","msg":"trace[109292816] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24763; }","duration":"111.114993ms","start":"2026-07-14T23:45:35.356174Z","end":"2026-07-14T23:45:35.467289Z","steps":["trace[109292816] 'agreement among raft nodes before linearized reading' (duration: 110.792136ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:43.301569Z","caller":"traceutil/trace.go:171","msg":"trace[1295333733] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"246.999469ms","start":"2026-07-14T23:45:43.054543Z","end":"2026-07-14T23:45:43.301542Z","steps":["trace[1295333733] 'process raft request' (duration: 246.845396ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:43.844301Z","caller":"traceutil/trace.go:171","msg":"trace[1202793795] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"720.039756ms","start":"2026-07-14T23:45:43.124196Z","end":"2026-07-14T23:45:43.844236Z","steps":["trace[1202793795] 'process raft request' (duration: 700.793288ms)","trace[1202793795] 'compare' (duration: 18.520174ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:43.844371Z","caller":"traceutil/trace.go:171","msg":"trace[1743020587] linearizableReadLoop","detail":"{readStateIndex:25688; appliedIndex:25686; }","duration":"562.523826ms","start":"2026-07-14T23:45:43.281813Z","end":"2026-07-14T23:45:43.844337Z","steps":["trace[1743020587] 'read index received' (duration: 19.608105ms)","trace[1743020587] 'applied index is now lower than readState.Index' (duration: 542.911761ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:43.844406Z","caller":"traceutil/trace.go:171","msg":"trace[1360760096] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"347.743014ms","start":"2026-07-14T23:45:43.496637Z","end":"2026-07-14T23:45:43.84438Z","steps":["trace[1360760096] 'process raft request' (duration: 347.589941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.844499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:43.124098Z","time spent":"720.28844ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:45:43.844518Z","caller":"traceutil/trace.go:171","msg":"trace[1802719047] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"208.058085ms","start":"2026-07-14T23:45:43.636434Z","end":"2026-07-14T23:45:43.844492Z","steps":["trace[1802719047] 'process raft request' (duration: 207.908112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.844566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.729511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:45:43.844568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:43.496609Z","time spent":"347.868896ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:45:43.844602Z","caller":"traceutil/trace.go:171","msg":"trace[1213124515] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24877; }","duration":"562.860913ms","start":"2026-07-14T23:45:43.281728Z","end":"2026-07-14T23:45:43.844589Z","steps":["trace[1213124515] 'agreement among raft nodes before linearized reading' (duration: 562.727881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.84463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:43.281691Z","time spent":"562.931835ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"warn","ts":"2026-07-14T23:45:43.851076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.386641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28d928a1-44a5-4f36-9e79-39f9cb14126f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-14T23:45:43.851112Z","caller":"traceutil/trace.go:171","msg":"trace[1063402639] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-28d928a1-44a5-4f36-9e79-39f9cb14126f; range_end:; response_count:1; response_revision:24878; }","duration":"486.480034ms","start":"2026-07-14T23:45:43.364622Z","end":"2026-07-14T23:45:43.851103Z","steps":["trace[1063402639] 'agreement among raft nodes before linearized reading' (duration: 486.33222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.85113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:43.364599Z","time spent":"486.527415ms","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28d928a1-44a5-4f36-9e79-39f9cb14126f\" "} {"level":"warn","ts":"2026-07-14T23:45:43.851396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.6691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:45:43.851418Z","caller":"traceutil/trace.go:171","msg":"trace[607554393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24878; }","duration":"294.71033ms","start":"2026-07-14T23:45:43.556702Z","end":"2026-07-14T23:45:43.851412Z","steps":["trace[607554393] 'agreement among raft nodes before linearized reading' (duration: 294.651309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.851519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.090557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:45:43.851538Z","caller":"traceutil/trace.go:171","msg":"trace[449522966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24878; }","duration":"307.122088ms","start":"2026-07-14T23:45:43.544411Z","end":"2026-07-14T23:45:43.851533Z","steps":["trace[449522966] 'agreement among raft nodes before linearized reading' (duration: 307.063326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.851552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:43.544404Z","time spent":"307.143708ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:45:43.851636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.361269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-14T23:45:43.851652Z","caller":"traceutil/trace.go:171","msg":"trace[857050474] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24878; }","duration":"309.397991ms","start":"2026-07-14T23:45:43.542247Z","end":"2026-07-14T23:45:43.851645Z","steps":["trace[857050474] 'agreement among raft nodes before linearized reading' (duration: 309.35619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.851665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:43.542235Z","time spent":"309.425691ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T23:45:43.851753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.923345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-14T23:45:43.851766Z","caller":"traceutil/trace.go:171","msg":"trace[976862956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24878; }","duration":"393.980726ms","start":"2026-07-14T23:45:43.457781Z","end":"2026-07-14T23:45:43.851761Z","steps":["trace[976862956] 'agreement among raft nodes before linearized reading' (duration: 393.932525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:43.851781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:43.457757Z","time spent":"394.018467ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T23:45:44.151752Z","caller":"traceutil/trace.go:171","msg":"trace[810644431] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"295.572806ms","start":"2026-07-14T23:45:43.856148Z","end":"2026-07-14T23:45:44.151721Z","steps":["trace[810644431] 'process raft request' (duration: 257.496449ms)","trace[810644431] 'compare' (duration: 37.815542ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:44.152076Z","caller":"traceutil/trace.go:171","msg":"trace[293966099] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"295.528206ms","start":"2026-07-14T23:45:43.856515Z","end":"2026-07-14T23:45:44.152043Z","steps":["trace[293966099] 'process raft request' (duration: 295.153329ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.152358Z","caller":"traceutil/trace.go:171","msg":"trace[1907422160] linearizableReadLoop","detail":"{readStateIndex:25695; appliedIndex:25691; }","duration":"236.664531ms","start":"2026-07-14T23:45:43.915682Z","end":"2026-07-14T23:45:44.152346Z","steps":["trace[1907422160] 'read index received' (duration: 4.097879ms)","trace[1907422160] 'applied index is now lower than readState.Index' (duration: 232.565642ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:44.152395Z","caller":"traceutil/trace.go:171","msg":"trace[788812400] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"295.771481ms","start":"2026-07-14T23:45:43.856588Z","end":"2026-07-14T23:45:44.152359Z","steps":["trace[788812400] 'process raft request' (duration: 295.357312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.152649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.344778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:45:44.152656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.970927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:44.152692Z","caller":"traceutil/trace.go:171","msg":"trace[1087354554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24883; }","duration":"122.401309ms","start":"2026-07-14T23:45:44.030278Z","end":"2026-07-14T23:45:44.152679Z","steps":["trace[1087354554] 'agreement among raft nodes before linearized reading' (duration: 122.290166ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.152698Z","caller":"traceutil/trace.go:171","msg":"trace[866468570] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24883; }","duration":"237.078949ms","start":"2026-07-14T23:45:43.915605Z","end":"2026-07-14T23:45:44.152683Z","steps":["trace[866468570] 'agreement among raft nodes before linearized reading' (duration: 236.819514ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.152741Z","caller":"traceutil/trace.go:171","msg":"trace[2125842684] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"167.774995ms","start":"2026-07-14T23:45:43.984936Z","end":"2026-07-14T23:45:44.152711Z","steps":["trace[2125842684] 'process raft request' (duration: 167.367698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.1534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.762188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-14T23:45:44.15351Z","caller":"traceutil/trace.go:171","msg":"trace[1664368872] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:24883; }","duration":"118.91764ms","start":"2026-07-14T23:45:44.034568Z","end":"2026-07-14T23:45:44.153486Z","steps":["trace[1664368872] 'agreement among raft nodes before linearized reading' (duration: 118.532443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.705164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.88498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:45:44.705354Z","caller":"traceutil/trace.go:171","msg":"trace[976886044] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"542.79584ms","start":"2026-07-14T23:45:44.16253Z","end":"2026-07-14T23:45:44.705326Z","steps":["trace[976886044] 'process raft request' (duration: 255.618714ms)","trace[976886044] 'compare' (duration: 285.959072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:44.705434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:44.162499Z","time spent":"542.898762ms","remote":"127.0.0.1:42804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:45:44.752243Z","caller":"traceutil/trace.go:171","msg":"trace[247855230] linearizableReadLoop","detail":"{readStateIndex:25700; appliedIndex:25696; }","duration":"363.349361ms","start":"2026-07-14T23:45:44.388873Z","end":"2026-07-14T23:45:44.752223Z","steps":["trace[247855230] 'read index received' (duration: 29.142626ms)","trace[247855230] 'applied index is now lower than readState.Index' (duration: 334.205465ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:44.752387Z","caller":"traceutil/trace.go:171","msg":"trace[922942147] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"519.668518ms","start":"2026-07-14T23:45:44.232641Z","end":"2026-07-14T23:45:44.75231Z","steps":["trace[922942147] 'process raft request' (duration: 519.447984ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.752445Z","caller":"traceutil/trace.go:171","msg":"trace[1781142221] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"517.647278ms","start":"2026-07-14T23:45:44.234746Z","end":"2026-07-14T23:45:44.752394Z","steps":["trace[1781142221] 'process raft request' (duration: 517.411554ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.75244Z","caller":"traceutil/trace.go:171","msg":"trace[146050003] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"589.500842ms","start":"2026-07-14T23:45:44.162723Z","end":"2026-07-14T23:45:44.752224Z","steps":["trace[146050003] 'process raft request' (duration: 589.211967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.752623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.427723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-14T23:45:44.752643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:44.234734Z","time spent":"517.793261ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:45:44.75267Z","caller":"traceutil/trace.go:171","msg":"trace[1710282516] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24887; }","duration":"150.505075ms","start":"2026-07-14T23:45:44.602149Z","end":"2026-07-14T23:45:44.752654Z","steps":["trace[1710282516] 'agreement among raft nodes before linearized reading' (duration: 150.436364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.752717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.133379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-07-14T23:45:44.752787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:44.232625Z","time spent":"519.877051ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:45:44.752812Z","caller":"traceutil/trace.go:171","msg":"trace[1013085675] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24887; }","duration":"271.276512ms","start":"2026-07-14T23:45:44.48151Z","end":"2026-07-14T23:45:44.752787Z","steps":["trace[1013085675] 'agreement among raft nodes before linearized reading' (duration: 271.010486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.752908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.507063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:45:44.752944Z","caller":"traceutil/trace.go:171","msg":"trace[1194724744] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24887; }","duration":"220.577935ms","start":"2026-07-14T23:45:44.532355Z","end":"2026-07-14T23:45:44.752933Z","steps":["trace[1194724744] 'agreement among raft nodes before linearized reading' (duration: 220.498624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.752895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.478502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-14T23:45:44.752927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:44.162695Z","time spent":"589.934169ms","remote":"127.0.0.1:42804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":924,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:45:44.752544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.666767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-07-14T23:45:44.753026Z","caller":"traceutil/trace.go:171","msg":"trace[2145228726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24887; }","duration":"209.665586ms","start":"2026-07-14T23:45:44.543328Z","end":"2026-07-14T23:45:44.752994Z","steps":["trace[2145228726] 'agreement among raft nodes before linearized reading' (duration: 209.280428ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.753216Z","caller":"traceutil/trace.go:171","msg":"trace[672371867] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24887; }","duration":"364.380661ms","start":"2026-07-14T23:45:44.388821Z","end":"2026-07-14T23:45:44.753202Z","steps":["trace[672371867] 'agreement among raft nodes before linearized reading' (duration: 363.512244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:45:44.75325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:45:44.388798Z","time spent":"364.443822ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-07-14T23:45:44.954759Z","caller":"traceutil/trace.go:171","msg":"trace[218163774] linearizableReadLoop","detail":"{readStateIndex:25703; appliedIndex:25700; }","duration":"194.100178ms","start":"2026-07-14T23:45:44.760627Z","end":"2026-07-14T23:45:44.954727Z","steps":["trace[218163774] 'read index received' (duration: 128.942203ms)","trace[218163774] 'applied index is now lower than readState.Index' (duration: 65.156505ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:45:44.954797Z","caller":"traceutil/trace.go:171","msg":"trace[190588070] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"196.451444ms","start":"2026-07-14T23:45:44.75829Z","end":"2026-07-14T23:45:44.954741Z","steps":["trace[190588070] 'process raft request' (duration: 196.2255ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.954931Z","caller":"traceutil/trace.go:171","msg":"trace[1458071553] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"195.452484ms","start":"2026-07-14T23:45:44.759465Z","end":"2026-07-14T23:45:44.954917Z","steps":["trace[1458071553] 'process raft request' (duration: 195.184609ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:45:44.954953Z","caller":"traceutil/trace.go:171","msg":"trace[1551026003] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"198.92191ms","start":"2026-07-14T23:45:44.756001Z","end":"2026-07-14T23:45:44.954923Z","steps":["trace[1551026003] 'process raft request' (duration: 133.558511ms)","trace[1551026003] 'compare' (duration: 64.417511ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:45:44.955255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.600478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-07-14T23:45:44.955321Z","caller":"traceutil/trace.go:171","msg":"trace[359066475] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24890; }","duration":"194.71354ms","start":"2026-07-14T23:45:44.76059Z","end":"2026-07-14T23:45:44.955303Z","steps":["trace[359066475] 'agreement among raft nodes before linearized reading' (duration: 194.434034ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:21.005159Z","caller":"traceutil/trace.go:171","msg":"trace[1816136180] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"108.84537ms","start":"2026-07-14T23:46:20.896268Z","end":"2026-07-14T23:46:21.005114Z","steps":["trace[1816136180] 'process raft request' (duration: 108.553313ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:21.225348Z","caller":"traceutil/trace.go:171","msg":"trace[700348399] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"328.338363ms","start":"2026-07-14T23:46:20.896922Z","end":"2026-07-14T23:46:21.22526Z","steps":["trace[700348399] 'process raft request' (duration: 264.275229ms)","trace[700348399] 'compare' (duration: 63.81872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:21.225546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:20.896887Z","time spent":"328.551987ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:46:21.474449Z","caller":"traceutil/trace.go:171","msg":"trace[1844253580] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"238.623649ms","start":"2026-07-14T23:46:21.235795Z","end":"2026-07-14T23:46:21.474418Z","steps":["trace[1844253580] 'process raft request' (duration: 224.759224ms)","trace[1844253580] 'compare' (duration: 13.520459ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:21.477035Z","caller":"traceutil/trace.go:171","msg":"trace[1518938306] linearizableReadLoop","detail":"{readStateIndex:26027; appliedIndex:26025; }","duration":"117.077416ms","start":"2026-07-14T23:46:21.35992Z","end":"2026-07-14T23:46:21.476997Z","steps":["trace[1518938306] 'read index received' (duration: 100.654812ms)","trace[1518938306] 'applied index is now lower than readState.Index' (duration: 16.421754ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:21.477081Z","caller":"traceutil/trace.go:171","msg":"trace[100614515] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"241.178808ms","start":"2026-07-14T23:46:21.235862Z","end":"2026-07-14T23:46:21.47704Z","steps":["trace[100614515] 'process raft request' (duration: 240.951923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:21.477186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:21.477226Z","caller":"traceutil/trace.go:171","msg":"trace[701037448] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25204; }","duration":"117.332061ms","start":"2026-07-14T23:46:21.359881Z","end":"2026-07-14T23:46:21.477213Z","steps":["trace[701037448] 'agreement among raft nodes before linearized reading' (duration: 117.206109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:22.197766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.053994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274195"} {"level":"info","ts":"2026-07-14T23:46:22.197857Z","caller":"traceutil/trace.go:171","msg":"trace[692087375] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25205; }","duration":"520.203267ms","start":"2026-07-14T23:46:21.677635Z","end":"2026-07-14T23:46:22.197839Z","steps":["trace[692087375] 'range keys from bolt db' (duration: 519.882641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:22.197892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:21.677617Z","time spent":"520.268498ms","remote":"127.0.0.1:42962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":32,"response size":274217,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-07-14T23:46:22.54577Z","caller":"traceutil/trace.go:171","msg":"trace[891519216] linearizableReadLoop","detail":"{readStateIndex:26033; appliedIndex:26032; }","duration":"183.036977ms","start":"2026-07-14T23:46:22.362701Z","end":"2026-07-14T23:46:22.545738Z","steps":["trace[891519216] 'read index received' (duration: 182.832163ms)","trace[891519216] 'applied index is now lower than readState.Index' (duration: 203.724µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:22.545892Z","caller":"traceutil/trace.go:171","msg":"trace[555318789] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"250.653178ms","start":"2026-07-14T23:46:22.295188Z","end":"2026-07-14T23:46:22.545841Z","steps":["trace[555318789] 'process raft request' (duration: 250.265791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:22.546212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.469985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T23:46:22.547074Z","caller":"traceutil/trace.go:171","msg":"trace[1009019825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25210; }","duration":"184.412773ms","start":"2026-07-14T23:46:22.362637Z","end":"2026-07-14T23:46:22.54705Z","steps":["trace[1009019825] 'agreement among raft nodes before linearized reading' (duration: 183.349423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:22.548003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.048406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-14T23:46:22.548074Z","caller":"traceutil/trace.go:171","msg":"trace[654668686] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25210; }","duration":"185.177589ms","start":"2026-07-14T23:46:22.362875Z","end":"2026-07-14T23:46:22.548053Z","steps":["trace[654668686] 'agreement among raft nodes before linearized reading' (duration: 184.944063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:22.548036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.197349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:46:22.548549Z","caller":"traceutil/trace.go:171","msg":"trace[1129043999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25210; }","duration":"185.733718ms","start":"2026-07-14T23:46:22.362794Z","end":"2026-07-14T23:46:22.548528Z","steps":["trace[1129043999] 'agreement among raft nodes before linearized reading' (duration: 184.995454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:22.845445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.957242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:22.845862Z","caller":"traceutil/trace.go:171","msg":"trace[628651191] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"292.699141ms","start":"2026-07-14T23:46:22.553119Z","end":"2026-07-14T23:46:22.845818Z","steps":["trace[628651191] 'process raft request' (duration: 72.293612ms)","trace[628651191] 'compare' (duration: 219.524193ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:22.845989Z","caller":"traceutil/trace.go:171","msg":"trace[164819282] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"289.088332ms","start":"2026-07-14T23:46:22.556858Z","end":"2026-07-14T23:46:22.845946Z","steps":["trace[164819282] 'process raft request' (duration: 288.748276ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:22.845912Z","caller":"traceutil/trace.go:171","msg":"trace[1216645572] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"292.530718ms","start":"2026-07-14T23:46:22.553365Z","end":"2026-07-14T23:46:22.845896Z","steps":["trace[1216645572] 'process raft request' (duration: 292.193962ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:23.118153Z","caller":"traceutil/trace.go:171","msg":"trace[625046388] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"105.897764ms","start":"2026-07-14T23:46:23.012223Z","end":"2026-07-14T23:46:23.11812Z","steps":["trace[625046388] 'process raft request' (duration: 105.069628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:23.549005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.561399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:23.549144Z","caller":"traceutil/trace.go:171","msg":"trace[354148554] linearizableReadLoop","detail":"{readStateIndex:26038; appliedIndex:26037; }","duration":"236.133322ms","start":"2026-07-14T23:46:23.312988Z","end":"2026-07-14T23:46:23.549121Z","steps":["trace[354148554] 'read index received' (duration: 209.024µs)","trace[354148554] 'applied index is now lower than readState.Index' (duration: 235.922368ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:23.549235Z","caller":"traceutil/trace.go:171","msg":"trace[2016104065] transaction","detail":"{read_only:false; response_revision:25215; number_of_response:1; }","duration":"315.548809ms","start":"2026-07-14T23:46:23.233674Z","end":"2026-07-14T23:46:23.549223Z","steps":["trace[2016104065] 'process raft request' (duration: 22.615432ms)","trace[2016104065] 'compare' (duration: 292.344255ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:23.549602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:23.233649Z","time spent":"315.904125ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:46:23.549673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.653251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:23.549754Z","caller":"traceutil/trace.go:171","msg":"trace[252383152] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25215; }","duration":"236.760653ms","start":"2026-07-14T23:46:23.312973Z","end":"2026-07-14T23:46:23.549734Z","steps":["trace[252383152] 'agreement among raft nodes before linearized reading' (duration: 236.549839ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:23.782886Z","caller":"traceutil/trace.go:171","msg":"trace[554891973] linearizableReadLoop","detail":"{readStateIndex:26039; appliedIndex:26038; }","duration":"233.660833ms","start":"2026-07-14T23:46:23.549175Z","end":"2026-07-14T23:46:23.782836Z","steps":["trace[554891973] 'read index received' (duration: 225.566349ms)","trace[554891973] 'applied index is now lower than readState.Index' (duration: 8.092154ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:23.782886Z","caller":"traceutil/trace.go:171","msg":"trace[1815876230] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"299.172374ms","start":"2026-07-14T23:46:23.483684Z","end":"2026-07-14T23:46:23.782856Z","steps":["trace[1815876230] 'process raft request' (duration: 290.818046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:23.78314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.917476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-14T23:46:23.783174Z","caller":"traceutil/trace.go:171","msg":"trace[1124505465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25216; }","duration":"304.036588ms","start":"2026-07-14T23:46:23.479131Z","end":"2026-07-14T23:46:23.783167Z","steps":["trace[1124505465] 'agreement among raft nodes before linearized reading' (duration: 303.863565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:23.783161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.966539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-14T23:46:23.783199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:23.479107Z","time spent":"304.085909ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T23:46:23.783225Z","caller":"traceutil/trace.go:171","msg":"trace[615243316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25216; }","duration":"302.07312ms","start":"2026-07-14T23:46:23.481136Z","end":"2026-07-14T23:46:23.783209Z","steps":["trace[615243316] 'agreement among raft nodes before linearized reading' (duration: 301.832106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:23.78326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:23.481118Z","time spent":"302.136212ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T23:46:24.048663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.303004ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:24.048768Z","caller":"traceutil/trace.go:171","msg":"trace[1082688137] linearizableReadLoop","detail":"{readStateIndex:26041; appliedIndex:26039; }","duration":"248.626329ms","start":"2026-07-14T23:46:23.800125Z","end":"2026-07-14T23:46:24.048752Z","steps":["trace[1082688137] 'read index received' (duration: 44.361886ms)","trace[1082688137] 'applied index is now lower than readState.Index' (duration: 204.263293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:24.048929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.815373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-14T23:46:24.048953Z","caller":"traceutil/trace.go:171","msg":"trace[295295352] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25218; }","duration":"248.871124ms","start":"2026-07-14T23:46:23.800075Z","end":"2026-07-14T23:46:24.048947Z","steps":["trace[295295352] 'agreement among raft nodes before linearized reading' (duration: 248.714181ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:24.049254Z","caller":"traceutil/trace.go:171","msg":"trace[1250078115] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"261.488435ms","start":"2026-07-14T23:46:23.787726Z","end":"2026-07-14T23:46:24.049215Z","steps":["trace[1250078115] 'process raft request' (duration: 125.511878ms)","trace[1250078115] 'compare' (duration: 135.141301ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:24.255959Z","caller":"traceutil/trace.go:171","msg":"trace[572058678] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"193.417955ms","start":"2026-07-14T23:46:24.062507Z","end":"2026-07-14T23:46:24.255925Z","steps":["trace[572058678] 'process raft request' (duration: 187.073824ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:24.255984Z","caller":"traceutil/trace.go:171","msg":"trace[322323220] linearizableReadLoop","detail":"{readStateIndex:26044; appliedIndex:26041; }","duration":"149.619237ms","start":"2026-07-14T23:46:24.10634Z","end":"2026-07-14T23:46:24.255959Z","steps":["trace[322323220] 'read index received' (duration: 17.325661ms)","trace[322323220] 'applied index is now lower than readState.Index' (duration: 132.292496ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:24.256011Z","caller":"traceutil/trace.go:171","msg":"trace[2011282118] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"173.25786ms","start":"2026-07-14T23:46:24.082715Z","end":"2026-07-14T23:46:24.255973Z","steps":["trace[2011282118] 'process raft request' (duration: 173.130057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:24.256194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.834332ms","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-07-14T23:46:24.256247Z","caller":"traceutil/trace.go:171","msg":"trace[936889030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25221; }","duration":"149.921375ms","start":"2026-07-14T23:46:24.106299Z","end":"2026-07-14T23:46:24.256221Z","steps":["trace[936889030] 'agreement among raft nodes before linearized reading' (duration: 149.772692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:24.260421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.995693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:46:24.260505Z","caller":"traceutil/trace.go:171","msg":"trace[1256689153] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:25221; }","duration":"120.160546ms","start":"2026-07-14T23:46:24.140329Z","end":"2026-07-14T23:46:24.26049Z","steps":["trace[1256689153] 'agreement among raft nodes before linearized reading' (duration: 115.842253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:24.596547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.530705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:24.596669Z","caller":"traceutil/trace.go:171","msg":"trace[632866869] linearizableReadLoop","detail":"{readStateIndex:26045; appliedIndex:26044; }","duration":"330.916372ms","start":"2026-07-14T23:46:24.265735Z","end":"2026-07-14T23:46:24.596651Z","steps":["trace[632866869] 'read index received' (duration: 133.092883ms)","trace[632866869] 'applied index is now lower than readState.Index' (duration: 197.822349ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:24.596899Z","caller":"traceutil/trace.go:171","msg":"trace[1921154575] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"335.219435ms","start":"2026-07-14T23:46:24.261665Z","end":"2026-07-14T23:46:24.596884Z","steps":["trace[1921154575] 'process raft request' (duration: 137.258652ms)","trace[1921154575] 'compare' (duration: 197.31884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:24.596972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:24.261638Z","time spent":"335.299846ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:46:24.597365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.622505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-14T23:46:24.5974Z","caller":"traceutil/trace.go:171","msg":"trace[199581832] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25222; }","duration":"331.670226ms","start":"2026-07-14T23:46:24.265719Z","end":"2026-07-14T23:46:24.597389Z","steps":["trace[199581832] 'agreement among raft nodes before linearized reading' (duration: 331.423021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:24.597431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:24.265711Z","time spent":"331.712727ms","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-14T23:46:24.615185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.150992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:24.615245Z","caller":"traceutil/trace.go:171","msg":"trace[596496931] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25223; }","duration":"347.221033ms","start":"2026-07-14T23:46:24.268009Z","end":"2026-07-14T23:46:24.61523Z","steps":["trace[596496931] 'agreement among raft nodes before linearized reading' (duration: 347.05423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:24.615282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:46:24.268002Z","time spent":"347.269684ms","remote":"127.0.0.1:34934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":28,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:46:24.988446Z","caller":"traceutil/trace.go:171","msg":"trace[1813666833] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"131.85723ms","start":"2026-07-14T23:46:24.856558Z","end":"2026-07-14T23:46:24.988415Z","steps":["trace[1813666833] 'process raft request' (duration: 131.577514ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:24.988484Z","caller":"traceutil/trace.go:171","msg":"trace[1935731735] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"132.045463ms","start":"2026-07-14T23:46:24.856414Z","end":"2026-07-14T23:46:24.98846Z","steps":["trace[1935731735] 'process raft request' (duration: 104.359904ms)","trace[1935731735] 'compare' (duration: 27.054976ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:25.329847Z","caller":"traceutil/trace.go:171","msg":"trace[683237387] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"177.402279ms","start":"2026-07-14T23:46:25.152402Z","end":"2026-07-14T23:46:25.329805Z","steps":["trace[683237387] 'process raft request' (duration: 96.457993ms)","trace[683237387] 'compare' (duration: 80.756062ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:25.759497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.910443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:25.75963Z","caller":"traceutil/trace.go:171","msg":"trace[271721963] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"203.10312ms","start":"2026-07-14T23:46:25.556497Z","end":"2026-07-14T23:46:25.7596Z","steps":["trace[271721963] 'process raft request' (duration: 85.992363ms)","trace[271721963] 'compare' (duration: 116.73471ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:26.024506Z","caller":"traceutil/trace.go:171","msg":"trace[84720286] linearizableReadLoop","detail":"{readStateIndex:26057; appliedIndex:26056; }","duration":"132.027503ms","start":"2026-07-14T23:46:25.892449Z","end":"2026-07-14T23:46:26.024476Z","steps":["trace[84720286] 'read index received' (duration: 131.845209ms)","trace[84720286] 'applied index is now lower than readState.Index' (duration: 180.953µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:26.024622Z","caller":"traceutil/trace.go:171","msg":"trace[998789503] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"169.056819ms","start":"2026-07-14T23:46:25.855524Z","end":"2026-07-14T23:46:26.024581Z","steps":["trace[998789503] 'process raft request' (duration: 168.775543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:26.024811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.320669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-14T23:46:26.024923Z","caller":"traceutil/trace.go:171","msg":"trace[270283863] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:25233; }","duration":"132.521442ms","start":"2026-07-14T23:46:25.892377Z","end":"2026-07-14T23:46:26.024899Z","steps":["trace[270283863] 'agreement among raft nodes before linearized reading' (duration: 132.235577ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:26.201416Z","caller":"traceutil/trace.go:171","msg":"trace[147114926] linearizableReadLoop","detail":"{readStateIndex:26058; appliedIndex:26057; }","duration":"147.877966ms","start":"2026-07-14T23:46:26.0535Z","end":"2026-07-14T23:46:26.201378Z","steps":["trace[147114926] 'read index received' (duration: 117.22738ms)","trace[147114926] 'applied index is now lower than readState.Index' (duration: 30.649146ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:26.201456Z","caller":"traceutil/trace.go:171","msg":"trace[1265477981] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"150.109567ms","start":"2026-07-14T23:46:26.051306Z","end":"2026-07-14T23:46:26.201415Z","steps":["trace[1265477981] 'process raft request' (duration: 119.403651ms)","trace[1265477981] 'compare' (duration: 30.288649ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:26.201679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.148021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-14T23:46:26.201769Z","caller":"traceutil/trace.go:171","msg":"trace[175247222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25234; }","duration":"148.260913ms","start":"2026-07-14T23:46:26.053486Z","end":"2026-07-14T23:46:26.201747Z","steps":["trace[175247222] 'agreement among raft nodes before linearized reading' (duration: 148.009238ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:26.451396Z","caller":"traceutil/trace.go:171","msg":"trace[379075679] linearizableReadLoop","detail":"{readStateIndex:26062; appliedIndex:26060; }","duration":"151.845511ms","start":"2026-07-14T23:46:26.299529Z","end":"2026-07-14T23:46:26.451375Z","steps":["trace[379075679] 'read index received' (duration: 151.753679ms)","trace[379075679] 'applied index is now lower than readState.Index' (duration: 90.892µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:26.451437Z","caller":"traceutil/trace.go:171","msg":"trace[234380903] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"189.357877ms","start":"2026-07-14T23:46:26.262031Z","end":"2026-07-14T23:46:26.451388Z","steps":["trace[234380903] 'process raft request' (duration: 189.136623ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:26.451513Z","caller":"traceutil/trace.go:171","msg":"trace[84940900] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"189.111482ms","start":"2026-07-14T23:46:26.262394Z","end":"2026-07-14T23:46:26.451505Z","steps":["trace[84940900] 'process raft request' (duration: 188.939229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:26.451678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.120477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-14T23:46:26.451713Z","caller":"traceutil/trace.go:171","msg":"trace[1175799577] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25238; }","duration":"152.209218ms","start":"2026-07-14T23:46:26.299493Z","end":"2026-07-14T23:46:26.451702Z","steps":["trace[1175799577] 'agreement among raft nodes before linearized reading' (duration: 152.129207ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:26.853186Z","caller":"traceutil/trace.go:171","msg":"trace[1109407699] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"231.90996ms","start":"2026-07-14T23:46:26.621251Z","end":"2026-07-14T23:46:26.853161Z","steps":["trace[1109407699] 'process raft request' (duration: 144.609962ms)","trace[1109407699] 'compare' (duration: 87.032453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:27.078249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.129525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:27.078592Z","caller":"traceutil/trace.go:171","msg":"trace[537140104] transaction","detail":"{read_only:false; response_revision:25242; number_of_response:1; }","duration":"164.093954ms","start":"2026-07-14T23:46:26.91444Z","end":"2026-07-14T23:46:27.078534Z","steps":["trace[537140104] 'process raft request' (duration: 55.4952ms)","trace[537140104] 'compare' (duration: 106.969883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:27.336117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.931148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:27.336306Z","caller":"traceutil/trace.go:171","msg":"trace[1052654316] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"120.023342ms","start":"2026-07-14T23:46:27.216265Z","end":"2026-07-14T23:46:27.336288Z","steps":["trace[1052654316] 'process raft request' (duration: 119.972081ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:27.33637Z","caller":"traceutil/trace.go:171","msg":"trace[1288354358] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"246.0643ms","start":"2026-07-14T23:46:27.090249Z","end":"2026-07-14T23:46:27.336313Z","steps":["trace[1288354358] 'process raft request' (duration: 98.817987ms)","trace[1288354358] 'compare' (duration: 146.756524ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:27.972214Z","caller":"traceutil/trace.go:171","msg":"trace[63061567] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"147.518059ms","start":"2026-07-14T23:46:27.824659Z","end":"2026-07-14T23:46:27.972177Z","steps":["trace[63061567] 'process raft request' (duration: 62.995324ms)","trace[63061567] 'compare' (duration: 84.354221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:28.556994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.301609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:28.557239Z","caller":"traceutil/trace.go:171","msg":"trace[43432460] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"251.956603ms","start":"2026-07-14T23:46:28.305211Z","end":"2026-07-14T23:46:28.557168Z","steps":["trace[43432460] 'process raft request' (duration: 121.387119ms)","trace[43432460] 'compare' (duration: 129.951993ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:28.639306Z","caller":"traceutil/trace.go:171","msg":"trace[228974162] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"178.578382ms","start":"2026-07-14T23:46:28.4607Z","end":"2026-07-14T23:46:28.639279Z","steps":["trace[228974162] 'process raft request' (duration: 178.433809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:46:29.007617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.970777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-14T23:46:29.00786Z","caller":"traceutil/trace.go:171","msg":"trace[1512645655] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"147.688332ms","start":"2026-07-14T23:46:28.860116Z","end":"2026-07-14T23:46:29.007804Z","steps":["trace[1512645655] 'process raft request' (duration: 11.379278ms)","trace[1512645655] 'compare' (duration: 135.511439ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:29.243837Z","caller":"traceutil/trace.go:171","msg":"trace[147925151] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"143.775198ms","start":"2026-07-14T23:46:29.10003Z","end":"2026-07-14T23:46:29.243805Z","steps":["trace[147925151] 'process raft request' (duration: 128.313462ms)","trace[147925151] 'compare' (duration: 15.072898ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:29.469178Z","caller":"traceutil/trace.go:171","msg":"trace[1472107908] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"127.737791ms","start":"2026-07-14T23:46:29.341383Z","end":"2026-07-14T23:46:29.469121Z","steps":["trace[1472107908] 'process raft request' (duration: 41.597305ms)","trace[1472107908] 'compare' (duration: 85.974683ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:29.477601Z","caller":"traceutil/trace.go:171","msg":"trace[359732682] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"131.986572ms","start":"2026-07-14T23:46:29.345567Z","end":"2026-07-14T23:46:29.477553Z","steps":["trace[359732682] 'process raft request' (duration: 131.826579ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:33.622526Z","caller":"traceutil/trace.go:171","msg":"trace[1466283619] linearizableReadLoop","detail":"{readStateIndex:26113; appliedIndex:26112; }","duration":"133.877627ms","start":"2026-07-14T23:46:33.488617Z","end":"2026-07-14T23:46:33.622494Z","steps":["trace[1466283619] 'read index received' (duration: 35.229293ms)","trace[1466283619] 'applied index is now lower than readState.Index' (duration: 98.646624ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:46:33.62259Z","caller":"traceutil/trace.go:171","msg":"trace[675959899] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"137.419455ms","start":"2026-07-14T23:46:33.485138Z","end":"2026-07-14T23:46:33.622557Z","steps":["trace[675959899] 'process raft request' (duration: 38.695009ms)","trace[675959899] 'compare' (duration: 98.467141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:46:33.622823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.155452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-14T23:46:33.622874Z","caller":"traceutil/trace.go:171","msg":"trace[471974415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25288; }","duration":"134.312205ms","start":"2026-07-14T23:46:33.488549Z","end":"2026-07-14T23:46:33.622861Z","steps":["trace[471974415] 'agreement among raft nodes before linearized reading' (duration: 134.060101ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:46:59.668239Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066d2.wal"} {"level":"info","ts":"2026-07-14T23:47:52.149962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23128} {"level":"info","ts":"2026-07-14T23:47:52.290359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23128,"took":"134.761474ms","hash":3229411259,"current-db-size-bytes":60051456,"current-db-size":"60 MB","current-db-size-in-use-bytes":36216832,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-14T23:47:52.290498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3229411259,"revision":23128,"compact-revision":20048} {"level":"info","ts":"2026-07-14T23:52:52.1567Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26093} {"level":"info","ts":"2026-07-14T23:52:52.31944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26093,"took":"156.650898ms","hash":3707710726,"current-db-size-bytes":60051456,"current-db-size":"60 MB","current-db-size-in-use-bytes":33935360,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-14T23:52:52.319543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3707710726,"revision":26093,"compact-revision":23128} {"level":"info","ts":"2026-07-14T23:53:38.694002Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-14T23:53:38.701064Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-14T23:53:38.701196Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-14T23:57:52.165571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28738} {"level":"info","ts":"2026-07-14T23:57:52.31038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28738,"took":"138.542202ms","hash":488310373,"current-db-size-bytes":60051456,"current-db-size":"60 MB","current-db-size-in-use-bytes":30253056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-14T23:57:52.310455Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":488310373,"revision":28738,"compact-revision":26093} {"level":"info","ts":"2026-07-15T00:02:52.172471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30955} {"level":"info","ts":"2026-07-15T00:02:52.298918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30955,"took":"120.808227ms","hash":637419128,"current-db-size-bytes":60051456,"current-db-size":"60 MB","current-db-size-in-use-bytes":31678464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T00:02:52.299152Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":637419128,"revision":30955,"compact-revision":28738} {"level":"info","ts":"2026-07-15T00:07:52.183891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33317} {"level":"info","ts":"2026-07-15T00:07:52.357402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33317,"took":"167.802979ms","hash":2394843055,"current-db-size-bytes":61095936,"current-db-size":"61 MB","current-db-size-in-use-bytes":32108544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T00:07:52.357467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2394843055,"revision":33317,"compact-revision":30955} {"level":"info","ts":"2026-07-15T00:12:52.191045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35643} {"level":"info","ts":"2026-07-15T00:12:52.338626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35643,"took":"142.073056ms","hash":2928155081,"current-db-size-bytes":61095936,"current-db-size":"61 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T00:12:52.338695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2928155081,"revision":35643,"compact-revision":33317} {"level":"info","ts":"2026-07-15T00:14:52.900163Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T00:14:52.904816Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T00:14:52.904927Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-15T00:17:52.197268Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37866} {"level":"info","ts":"2026-07-15T00:17:52.318043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37866,"took":"116.931142ms","hash":559394610,"current-db-size-bytes":61095936,"current-db-size":"61 MB","current-db-size-in-use-bytes":30834688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T00:17:52.318103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":559394610,"revision":37866,"compact-revision":35643}