{"level":"warn","ts":"2026-09-01T13:23:28.773133Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T13:23:28.77323Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-01T13:23:28.773317Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T13:23:28.773338Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-09-01T13:23:28.77336Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T13:23:28.774184Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-09-01T13:23:28.774307Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-01T13:23:28.778697Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.083991ms"} {"level":"info","ts":"2026-09-01T13:23:28.802199Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-09-01T13:23:28.802333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T13:23:28.802374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-09-01T13:23:28.8024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T13:23:28.802413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-09-01T13:23:28.802466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-09-01T13:23:28.809096Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T13:23:28.812161Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T13:23:28.814594Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-01T13:23:28.820005Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T13:23:28.820368Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T13:23:28.820366Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T13:23:28.82048Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T13:23:28.82052Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T13:23:28.82137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-09-01T13:23:28.82151Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-09-01T13:23:28.828114Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T13:23:28.828441Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T13:23:28.828477Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-09-01T13:23:28.828536Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T13:23:28.828578Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-09-01T13:23:28.903282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T13:23:28.903369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T13:23:28.903392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-09-01T13:23:28.903485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-09-01T13:23:28.9035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-09-01T13:23:28.903511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-09-01T13:23:28.903523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-09-01T13:23:28.904433Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:28.905559Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T13:23:28.905621Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T13:23:28.90556Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T13:23:28.905925Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T13:23:28.905982Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T13:23:28.906044Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:28.906109Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:28.90613Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:28.90794Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-09-01T13:23:28.908955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T13:23:45.729411Z","caller":"traceutil/trace.go:171","msg":"trace[1915265038] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"153.787478ms","start":"2026-09-01T13:23:45.575585Z","end":"2026-09-01T13:23:45.729373Z","steps":["trace[1915265038] 'process raft request' (duration: 153.552231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:23:51.040883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.246539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T13:23:51.04101Z","caller":"traceutil/trace.go:171","msg":"trace[1909681123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"104.461544ms","start":"2026-09-01T13:23:50.936524Z","end":"2026-09-01T13:23:51.040986Z","steps":["trace[1909681123] 'range keys from in-memory index tree' (duration: 104.116325ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:06.791578Z","caller":"traceutil/trace.go:171","msg":"trace[582017275] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"104.550027ms","start":"2026-09-01T13:24:06.687009Z","end":"2026-09-01T13:24:06.791559Z","steps":["trace[582017275] 'process raft request' (duration: 104.451564ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:06.800833Z","caller":"traceutil/trace.go:171","msg":"trace[1939251471] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"105.448371ms","start":"2026-09-01T13:24:06.695362Z","end":"2026-09-01T13:24:06.80081Z","steps":["trace[1939251471] 'process raft request' (duration: 101.252137ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:06.800975Z","caller":"traceutil/trace.go:171","msg":"trace[1275392678] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"100.051724ms","start":"2026-09-01T13:24:06.700871Z","end":"2026-09-01T13:24:06.800923Z","steps":["trace[1275392678] 'process raft request' (duration: 99.882661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:13.452793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.572436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:24:13.453079Z","caller":"traceutil/trace.go:171","msg":"trace[885259203] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"289.453695ms","start":"2026-09-01T13:24:13.163607Z","end":"2026-09-01T13:24:13.45306Z","steps":["trace[885259203] 'read index received' (duration: 35.084148ms)","trace[885259203] 'applied index is now lower than readState.Index' (duration: 254.368537ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:24:13.453271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.669711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T13:24:13.453312Z","caller":"traceutil/trace.go:171","msg":"trace[713280430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"289.773464ms","start":"2026-09-01T13:24:13.16352Z","end":"2026-09-01T13:24:13.453294Z","steps":["trace[713280430] 'agreement among raft nodes before linearized reading' (duration: 289.602269ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:13.453401Z","caller":"traceutil/trace.go:171","msg":"trace[1665326179] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"388.962596ms","start":"2026-09-01T13:24:13.064398Z","end":"2026-09-01T13:24:13.45336Z","steps":["trace[1665326179] 'process raft request' (duration: 388.573125ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:13.453469Z","caller":"traceutil/trace.go:171","msg":"trace[543917062] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"405.000739ms","start":"2026-09-01T13:24:13.048394Z","end":"2026-09-01T13:24:13.453395Z","steps":["trace[543917062] 'process raft request' (duration: 150.284692ms)","trace[543917062] 'compare' (duration: 253.006761ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:24:13.453596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:13.048349Z","time spent":"405.184724ms","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T13:24:13.453823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:13.064359Z","time spent":"389.13048ms","remote":"127.0.0.1:40620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:24:14.082871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.565638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.083029Z","caller":"traceutil/trace.go:171","msg":"trace[831022605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"414.775693ms","start":"2026-09-01T13:24:13.668227Z","end":"2026-09-01T13:24:14.083002Z","steps":["trace[831022605] 'range keys from in-memory index tree' (duration: 414.438524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.083199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:13.668205Z","time spent":"414.974299ms","remote":"127.0.0.1:40436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T13:24:14.089765Z","caller":"traceutil/trace.go:171","msg":"trace[1760421492] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"177.018496ms","start":"2026-09-01T13:24:13.912727Z","end":"2026-09-01T13:24:14.089745Z","steps":["trace[1760421492] 'process raft request' (duration: 176.393089ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.854328Z","caller":"traceutil/trace.go:171","msg":"trace[1715643009] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"118.37055ms","start":"2026-09-01T13:24:14.735915Z","end":"2026-09-01T13:24:14.854286Z","steps":["trace[1715643009] 'process raft request' (duration: 97.501885ms)","trace[1715643009] 'compare' (duration: 20.667019ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:24:58.655365Z","caller":"traceutil/trace.go:171","msg":"trace[1182370667] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"173.718816ms","start":"2026-09-01T13:24:58.481618Z","end":"2026-09-01T13:24:58.655337Z","steps":["trace[1182370667] 'process raft request' (duration: 173.516831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:25:48.191308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.88533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T13:25:48.191405Z","caller":"traceutil/trace.go:171","msg":"trace[1395600957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1885; }","duration":"197.044234ms","start":"2026-09-01T13:25:47.994338Z","end":"2026-09-01T13:25:48.191382Z","steps":["trace[1395600957] 'range keys from in-memory index tree' (duration: 196.691985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:25:48.191393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.955082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2cb284a-4d1c-4d43-aba0-a99466efe456\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T13:25:48.191491Z","caller":"traceutil/trace.go:171","msg":"trace[610068212] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2cb284a-4d1c-4d43-aba0-a99466efe456; range_end:; response_count:1; response_revision:1885; }","duration":"241.099886ms","start":"2026-09-01T13:25:47.950365Z","end":"2026-09-01T13:25:48.191465Z","steps":["trace[610068212] 'range keys from in-memory index tree' (duration: 240.724196ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:25:48.383008Z","caller":"traceutil/trace.go:171","msg":"trace[607834992] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"325.522058ms","start":"2026-09-01T13:25:48.057455Z","end":"2026-09-01T13:25:48.382977Z","steps":["trace[607834992] 'process raft request' (duration: 325.307233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:25:48.383341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:25:48.057434Z","time spent":"325.756374ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:25:48.42759Z","caller":"traceutil/trace.go:171","msg":"trace[1348524858] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"227.077779ms","start":"2026-09-01T13:25:48.200494Z","end":"2026-09-01T13:25:48.427571Z","steps":["trace[1348524858] 'process raft request' (duration: 196.824378ms)","trace[1348524858] 'compare' (duration: 30.108407ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:25:48.427752Z","caller":"traceutil/trace.go:171","msg":"trace[1447817915] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1961; }","duration":"144.029539ms","start":"2026-09-01T13:25:48.283715Z","end":"2026-09-01T13:25:48.427744Z","steps":["trace[1447817915] 'read index received' (duration: 99.134206ms)","trace[1447817915] 'applied index is now lower than readState.Index' (duration: 44.894693ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:25:48.427848Z","caller":"traceutil/trace.go:171","msg":"trace[442716274] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"202.659093ms","start":"2026-09-01T13:25:48.225183Z","end":"2026-09-01T13:25:48.427842Z","steps":["trace[442716274] 'process raft request' (duration: 202.354586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:25:48.428149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.408169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-01T13:25:48.428241Z","caller":"traceutil/trace.go:171","msg":"trace[1323989160] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1888; }","duration":"144.533382ms","start":"2026-09-01T13:25:48.283681Z","end":"2026-09-01T13:25:48.428214Z","steps":["trace[1323989160] 'agreement among raft nodes before linearized reading' (duration: 144.284436ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:25:48.737306Z","caller":"traceutil/trace.go:171","msg":"trace[1617416108] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1965; }","duration":"162.256021ms","start":"2026-09-01T13:25:48.575027Z","end":"2026-09-01T13:25:48.737283Z","steps":["trace[1617416108] 'read index received' (duration: 124.596308ms)","trace[1617416108] 'applied index is now lower than readState.Index' (duration: 37.658373ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:25:48.737647Z","caller":"traceutil/trace.go:171","msg":"trace[1763302898] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"206.082029ms","start":"2026-09-01T13:25:48.531544Z","end":"2026-09-01T13:25:48.737626Z","steps":["trace[1763302898] 'process raft request' (duration: 168.054766ms)","trace[1763302898] 'compare' (duration: 37.188082ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:25:48.737674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.197151ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:25:48.737757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.708873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2cb284a-4d1c-4d43-aba0-a99466efe456\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T13:25:48.737817Z","caller":"traceutil/trace.go:171","msg":"trace[527619330] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1889; }","duration":"160.768895ms","start":"2026-09-01T13:25:48.577009Z","end":"2026-09-01T13:25:48.737778Z","steps":["trace[527619330] 'range keys from in-memory index tree' (duration: 160.18243ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:25:48.737848Z","caller":"traceutil/trace.go:171","msg":"trace[1815048292] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2cb284a-4d1c-4d43-aba0-a99466efe456; range_end:; response_count:1; response_revision:1890; }","duration":"162.845196ms","start":"2026-09-01T13:25:48.574981Z","end":"2026-09-01T13:25:48.737826Z","steps":["trace[1815048292] 'agreement among raft nodes before linearized reading' (duration: 162.60118ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:02.548275Z","caller":"traceutil/trace.go:171","msg":"trace[1344746494] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"159.300048ms","start":"2026-09-01T13:26:02.388949Z","end":"2026-09-01T13:26:02.548249Z","steps":["trace[1344746494] 'process raft request' (duration: 159.097333ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:02.898246Z","caller":"traceutil/trace.go:171","msg":"trace[1807478379] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"103.741081ms","start":"2026-09-01T13:26:02.794468Z","end":"2026-09-01T13:26:02.89821Z","steps":["trace[1807478379] 'process raft request' (duration: 103.651109ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:02.898343Z","caller":"traceutil/trace.go:171","msg":"trace[1213012604] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"344.220772ms","start":"2026-09-01T13:26:02.554093Z","end":"2026-09-01T13:26:02.898314Z","steps":["trace[1213012604] 'process raft request' (duration: 283.711962ms)","trace[1213012604] 'compare' (duration: 59.745521ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:26:02.898506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:26:02.554069Z","time spent":"344.342336ms","remote":"127.0.0.1:40756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1712,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:26:02.898532Z","caller":"traceutil/trace.go:171","msg":"trace[1346051547] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"343.186136ms","start":"2026-09-01T13:26:02.555322Z","end":"2026-09-01T13:26:02.898508Z","steps":["trace[1346051547] 'process raft request' (duration: 342.601432ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:02.898316Z","caller":"traceutil/trace.go:171","msg":"trace[1281990689] linearizableReadLoop","detail":"{readStateIndex:2080; appliedIndex:2078; }","duration":"329.194801ms","start":"2026-09-01T13:26:02.569064Z","end":"2026-09-01T13:26:02.898259Z","steps":["trace[1281990689] 'read index received' (duration: 268.757362ms)","trace[1281990689] 'applied index is now lower than readState.Index' (duration: 60.433589ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:26:02.898626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.316124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-01T13:26:02.898652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.510285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T13:26:02.898699Z","caller":"traceutil/trace.go:171","msg":"trace[2123491884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2002; }","duration":"342.420447ms","start":"2026-09-01T13:26:02.55626Z","end":"2026-09-01T13:26:02.898681Z","steps":["trace[2123491884] 'agreement among raft nodes before linearized reading' (duration: 342.133761ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:02.898699Z","caller":"traceutil/trace.go:171","msg":"trace[87683282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2002; }","duration":"341.556526ms","start":"2026-09-01T13:26:02.557127Z","end":"2026-09-01T13:26:02.898683Z","steps":["trace[87683282] 'agreement among raft nodes before linearized reading' (duration: 341.424833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:02.898754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:26:02.557112Z","time spent":"341.629418ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T13:26:02.898754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:26:02.556252Z","time spent":"342.489249ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T13:26:02.898632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:26:02.555304Z","time spent":"343.273569ms","remote":"127.0.0.1:40932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5636,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:26:03.101963Z","caller":"traceutil/trace.go:171","msg":"trace[197834337] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"195.529906ms","start":"2026-09-01T13:26:02.906403Z","end":"2026-09-01T13:26:03.101933Z","steps":["trace[197834337] 'process raft request' (duration: 115.341938ms)","trace[197834337] 'compare' (duration: 79.961832ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:26:03.102353Z","caller":"traceutil/trace.go:171","msg":"trace[722889192] linearizableReadLoop","detail":"{readStateIndex:2084; appliedIndex:2082; }","duration":"127.323267ms","start":"2026-09-01T13:26:02.974986Z","end":"2026-09-01T13:26:03.10231Z","steps":["trace[722889192] 'read index received' (duration: 46.752399ms)","trace[722889192] 'applied index is now lower than readState.Index' (duration: 80.567558ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:26:03.102534Z","caller":"traceutil/trace.go:171","msg":"trace[37489244] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"193.973029ms","start":"2026-09-01T13:26:02.908507Z","end":"2026-09-01T13:26:03.10248Z","steps":["trace[37489244] 'process raft request' (duration: 193.323453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:03.102746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.748287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-sfnb5\" ","response":"range_response_count:1 size:6143"} {"level":"info","ts":"2026-09-01T13:26:03.103246Z","caller":"traceutil/trace.go:171","msg":"trace[1668921008] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-6f465c979f-sfnb5; range_end:; response_count:1; response_revision:2006; }","duration":"128.33021ms","start":"2026-09-01T13:26:02.974887Z","end":"2026-09-01T13:26:03.103218Z","steps":["trace[1668921008] 'agreement among raft nodes before linearized reading' (duration: 127.648074ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:07.383913Z","caller":"traceutil/trace.go:171","msg":"trace[1806083440] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"214.63203ms","start":"2026-09-01T13:26:07.169236Z","end":"2026-09-01T13:26:07.383868Z","steps":["trace[1806083440] 'process raft request' (duration: 212.522888ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:07.38396Z","caller":"traceutil/trace.go:171","msg":"trace[1036333972] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"151.258189ms","start":"2026-09-01T13:26:07.232642Z","end":"2026-09-01T13:26:07.3839Z","steps":["trace[1036333972] 'process raft request' (duration: 151.085004ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:39.240191Z","caller":"traceutil/trace.go:171","msg":"trace[697415004] transaction","detail":"{read_only:false; response_revision:2212; number_of_response:1; }","duration":"135.116759ms","start":"2026-09-01T13:26:39.105025Z","end":"2026-09-01T13:26:39.240141Z","steps":["trace[697415004] 'process raft request' (duration: 100.707986ms)","trace[697415004] 'compare' (duration: 34.138566ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:26:44.976402Z","caller":"traceutil/trace.go:171","msg":"trace[1971732674] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"192.687616ms","start":"2026-09-01T13:26:44.783693Z","end":"2026-09-01T13:26:44.97638Z","steps":["trace[1971732674] 'process raft request' (duration: 192.300626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:45.577675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.216544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T13:26:45.577792Z","caller":"traceutil/trace.go:171","msg":"trace[1868757226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2242; }","duration":"313.380618ms","start":"2026-09-01T13:26:45.264361Z","end":"2026-09-01T13:26:45.577742Z","steps":["trace[1868757226] 'range keys from in-memory index tree' (duration: 312.987279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:45.578012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:26:45.264329Z","time spent":"313.673375ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T13:26:45.578028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.693183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:26:45.578175Z","caller":"traceutil/trace.go:171","msg":"trace[759220099] linearizableReadLoop","detail":"{readStateIndex:2331; appliedIndex:2330; }","duration":"258.097207ms","start":"2026-09-01T13:26:45.320049Z","end":"2026-09-01T13:26:45.578146Z","steps":["trace[759220099] 'read index received' (duration: 67.561µs)","trace[759220099] 'applied index is now lower than readState.Index' (duration: 258.027086ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:26:45.578206Z","caller":"traceutil/trace.go:171","msg":"trace[1012893747] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"286.87ms","start":"2026-09-01T13:26:45.291284Z","end":"2026-09-01T13:26:45.578154Z","steps":["trace[1012893747] 'process raft request' (duration: 14.735326ms)","trace[1012893747] 'compare' (duration: 271.466488ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:26:45.578499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.430715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T13:26:45.578597Z","caller":"traceutil/trace.go:171","msg":"trace[775236002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2243; }","duration":"258.555679ms","start":"2026-09-01T13:26:45.320023Z","end":"2026-09-01T13:26:45.578579Z","steps":["trace[775236002] 'agreement among raft nodes before linearized reading' (duration: 258.262472ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:45.720396Z","caller":"traceutil/trace.go:171","msg":"trace[93399674] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"142.137484ms","start":"2026-09-01T13:26:45.578233Z","end":"2026-09-01T13:26:45.720371Z","steps":["trace[93399674] 'read index received' (duration: 102.551363ms)","trace[93399674] 'applied index is now lower than readState.Index' (duration: 39.585031ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:26:45.720515Z","caller":"traceutil/trace.go:171","msg":"trace[67667110] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"177.650443ms","start":"2026-09-01T13:26:45.542798Z","end":"2026-09-01T13:26:45.720449Z","steps":["trace[67667110] 'process raft request' (duration: 137.96395ms)","trace[67667110] 'compare' (duration: 39.472809ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:26:45.720592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.20249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:26:45.720593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.114697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:26:45.720648Z","caller":"traceutil/trace.go:171","msg":"trace[1189438046] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2244; }","duration":"208.306213ms","start":"2026-09-01T13:26:45.51232Z","end":"2026-09-01T13:26:45.720626Z","steps":["trace[1189438046] 'agreement among raft nodes before linearized reading' (duration: 208.168629ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:45.720662Z","caller":"traceutil/trace.go:171","msg":"trace[728805625] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2244; }","duration":"398.22405ms","start":"2026-09-01T13:26:45.322414Z","end":"2026-09-01T13:26:45.720638Z","steps":["trace[728805625] 'agreement among raft nodes before linearized reading' (duration: 398.099667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:45.720696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:26:45.322396Z","time spent":"398.290052ms","remote":"127.0.0.1:40764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":23,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-09-01T13:26:45.725229Z","caller":"traceutil/trace.go:171","msg":"trace[2007697040] transaction","detail":"{read_only:false; response_revision:2245; number_of_response:1; }","duration":"142.524063ms","start":"2026-09-01T13:26:45.582598Z","end":"2026-09-01T13:26:45.725122Z","steps":["trace[2007697040] 'process raft request' (duration: 142.310047ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:45.725386Z","caller":"traceutil/trace.go:171","msg":"trace[6859172] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"140.715998ms","start":"2026-09-01T13:26:45.584652Z","end":"2026-09-01T13:26:45.725368Z","steps":["trace[6859172] 'process raft request' (duration: 140.41236ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:28:31.789303Z","caller":"traceutil/trace.go:171","msg":"trace[2141485287] transaction","detail":"{read_only:false; response_revision:3003; number_of_response:1; }","duration":"343.6828ms","start":"2026-09-01T13:28:31.445581Z","end":"2026-09-01T13:28:31.789263Z","steps":["trace[2141485287] 'process raft request' (duration: 343.510216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:28:31.789582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:28:31.445552Z","time spent":"343.858795ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:28:31.796445Z","caller":"traceutil/trace.go:171","msg":"trace[1415994619] linearizableReadLoop","detail":"{readStateIndex:3121; appliedIndex:3119; }","duration":"177.532337ms","start":"2026-09-01T13:28:31.618881Z","end":"2026-09-01T13:28:31.796413Z","steps":["trace[1415994619] 'read index received' (duration: 170.182951ms)","trace[1415994619] 'applied index is now lower than readState.Index' (duration: 7.348126ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:28:31.796528Z","caller":"traceutil/trace.go:171","msg":"trace[2039068211] transaction","detail":"{read_only:false; response_revision:3004; number_of_response:1; }","duration":"262.262793ms","start":"2026-09-01T13:28:31.534236Z","end":"2026-09-01T13:28:31.796499Z","steps":["trace[2039068211] 'process raft request' (duration: 262.048318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:28:31.796754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.29887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:28:31.796841Z","caller":"traceutil/trace.go:171","msg":"trace[648851542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3004; }","duration":"128.438534ms","start":"2026-09-01T13:28:31.668384Z","end":"2026-09-01T13:28:31.796822Z","steps":["trace[648851542] 'agreement among raft nodes before linearized reading' (duration: 128.251069ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:28:31.796774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.879446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18d1357231ec1d24\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-09-01T13:28:31.796963Z","caller":"traceutil/trace.go:171","msg":"trace[636806823] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18d1357231ec1d24; range_end:; response_count:1; response_revision:3004; }","duration":"178.102912ms","start":"2026-09-01T13:28:31.618835Z","end":"2026-09-01T13:28:31.796938Z","steps":["trace[636806823] 'agreement among raft nodes before linearized reading' (duration: 177.741522ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:31:49.730448Z","caller":"traceutil/trace.go:171","msg":"trace[1661473548] linearizableReadLoop","detail":"{readStateIndex:4415; appliedIndex:4414; }","duration":"116.14069ms","start":"2026-09-01T13:31:49.614277Z","end":"2026-09-01T13:31:49.730418Z","steps":["trace[1661473548] 'read index received' (duration: 114.931419ms)","trace[1661473548] 'applied index is now lower than readState.Index' (duration: 1.208101ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:31:49.730538Z","caller":"traceutil/trace.go:171","msg":"trace[1642017252] transaction","detail":"{read_only:false; response_revision:4251; number_of_response:1; }","duration":"234.896552ms","start":"2026-09-01T13:31:49.495616Z","end":"2026-09-01T13:31:49.730513Z","steps":["trace[1642017252] 'process raft request' (duration: 233.65878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:31:49.730656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.328465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:31:49.73073Z","caller":"traceutil/trace.go:171","msg":"trace[1872193327] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4251; }","duration":"116.489189ms","start":"2026-09-01T13:31:49.614222Z","end":"2026-09-01T13:31:49.730711Z","steps":["trace[1872193327] 'agreement among raft nodes before linearized reading' (duration: 116.303604ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.206049Z","caller":"traceutil/trace.go:171","msg":"trace[218214168] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"183.751142ms","start":"2026-09-01T13:33:16.022251Z","end":"2026-09-01T13:33:16.206002Z","steps":["trace[218214168] 'read index received' (duration: 125.177569ms)","trace[218214168] 'applied index is now lower than readState.Index' (duration: 58.571883ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:16.206091Z","caller":"traceutil/trace.go:171","msg":"trace[1674584385] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"184.558064ms","start":"2026-09-01T13:33:16.021492Z","end":"2026-09-01T13:33:16.20605Z","steps":["trace[1674584385] 'process raft request' (duration: 125.915819ms)","trace[1674584385] 'compare' (duration: 58.288996ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:33:16.206325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.053501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-memcached-results-cache.18d135b910802136\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-09-01T13:33:16.206369Z","caller":"traceutil/trace.go:171","msg":"trace[1984287409] range","detail":"{range_begin:/registry/events/monitoring/loki-memcached-results-cache.18d135b910802136; range_end:; response_count:1; response_revision:5329; }","duration":"184.151424ms","start":"2026-09-01T13:33:16.022204Z","end":"2026-09-01T13:33:16.206355Z","steps":["trace[1984287409] 'agreement among raft nodes before linearized reading' (duration: 183.955588ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.230823Z","caller":"traceutil/trace.go:171","msg":"trace[834004405] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"204.742962ms","start":"2026-09-01T13:33:16.026053Z","end":"2026-09-01T13:33:16.230796Z","steps":["trace[834004405] 'process raft request' (duration: 204.607399ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.230902Z","caller":"traceutil/trace.go:171","msg":"trace[1314928414] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"204.145786ms","start":"2026-09-01T13:33:16.026743Z","end":"2026-09-01T13:33:16.230889Z","steps":["trace[1314928414] 'process raft request' (duration: 204.119696ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.230991Z","caller":"traceutil/trace.go:171","msg":"trace[1602198854] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"204.559088ms","start":"2026-09-01T13:33:16.026424Z","end":"2026-09-01T13:33:16.230983Z","steps":["trace[1602198854] 'process raft request' (duration: 204.399524ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.230989Z","caller":"traceutil/trace.go:171","msg":"trace[912201182] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"204.905436ms","start":"2026-09-01T13:33:16.026063Z","end":"2026-09-01T13:33:16.230968Z","steps":["trace[912201182] 'process raft request' (duration: 204.67052ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.231041Z","caller":"traceutil/trace.go:171","msg":"trace[1186336212] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"204.592478ms","start":"2026-09-01T13:33:16.026442Z","end":"2026-09-01T13:33:16.231035Z","steps":["trace[1186336212] 'process raft request' (duration: 204.402733ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.231044Z","caller":"traceutil/trace.go:171","msg":"trace[227513795] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"204.746031ms","start":"2026-09-01T13:33:16.026282Z","end":"2026-09-01T13:33:16.231028Z","steps":["trace[227513795] 'process raft request' (duration: 204.524375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:16.231161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.391536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T13:33:16.231206Z","caller":"traceutil/trace.go:171","msg":"trace[1371147773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5337; }","duration":"203.479439ms","start":"2026-09-01T13:33:16.027715Z","end":"2026-09-01T13:33:16.231195Z","steps":["trace[1371147773] 'agreement among raft nodes before linearized reading' (duration: 203.280173ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.231181Z","caller":"traceutil/trace.go:171","msg":"trace[1773651539] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"205.03659ms","start":"2026-09-01T13:33:16.026121Z","end":"2026-09-01T13:33:16.231157Z","steps":["trace[1773651539] 'process raft request' (duration: 204.643889ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.230934Z","caller":"traceutil/trace.go:171","msg":"trace[1556886397] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"204.666069ms","start":"2026-09-01T13:33:16.026221Z","end":"2026-09-01T13:33:16.230887Z","steps":["trace[1556886397] 'process raft request' (duration: 204.566497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:16.231276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.386625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-01T13:33:16.231297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.302821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T13:33:16.231399Z","caller":"traceutil/trace.go:171","msg":"trace[1887755432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5337; }","duration":"194.56956ms","start":"2026-09-01T13:33:16.0368Z","end":"2026-09-01T13:33:16.23137Z","steps":["trace[1887755432] 'agreement among raft nodes before linearized reading' (duration: 194.318504ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:16.231518Z","caller":"traceutil/trace.go:171","msg":"trace[37891846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5337; }","duration":"201.444385ms","start":"2026-09-01T13:33:16.029936Z","end":"2026-09-01T13:33:16.23138Z","steps":["trace[37891846] 'agreement among raft nodes before linearized reading' (duration: 201.064174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:19.466667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.662536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:33:19.466874Z","caller":"traceutil/trace.go:171","msg":"trace[2003443783] linearizableReadLoop","detail":"{readStateIndex:5657; appliedIndex:5655; }","duration":"163.783241ms","start":"2026-09-01T13:33:19.303075Z","end":"2026-09-01T13:33:19.466858Z","steps":["trace[2003443783] 'read index received' (duration: 38.846507ms)","trace[2003443783] 'applied index is now lower than readState.Index' (duration: 124.935853ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:33:19.467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.916764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:33:19.467027Z","caller":"traceutil/trace.go:171","msg":"trace[1651525920] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5447; }","duration":"163.982956ms","start":"2026-09-01T13:33:19.303035Z","end":"2026-09-01T13:33:19.467018Z","steps":["trace[1651525920] 'agreement among raft nodes before linearized reading' (duration: 163.866743ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:19.46708Z","caller":"traceutil/trace.go:171","msg":"trace[801254446] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"296.214753ms","start":"2026-09-01T13:33:19.170833Z","end":"2026-09-01T13:33:19.467048Z","steps":["trace[801254446] 'process raft request' (duration: 171.070024ms)","trace[801254446] 'compare' (duration: 124.459001ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:19.467156Z","caller":"traceutil/trace.go:171","msg":"trace[1697546737] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"235.178115ms","start":"2026-09-01T13:33:19.231953Z","end":"2026-09-01T13:33:19.467131Z","steps":["trace[1697546737] 'process raft request' (duration: 234.823776ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:22.079023Z","caller":"traceutil/trace.go:171","msg":"trace[131965143] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"119.536799ms","start":"2026-09-01T13:33:21.95945Z","end":"2026-09-01T13:33:22.078987Z","steps":["trace[131965143] 'process raft request' (duration: 100.153641ms)","trace[131965143] 'compare' (duration: 19.222134ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:27.619888Z","caller":"traceutil/trace.go:171","msg":"trace[1381405629] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"105.812243ms","start":"2026-09-01T13:33:27.514047Z","end":"2026-09-01T13:33:27.619859Z","steps":["trace[1381405629] 'process raft request' (duration: 102.495135ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:27.619997Z","caller":"traceutil/trace.go:171","msg":"trace[1533020637] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"104.359843ms","start":"2026-09-01T13:33:27.515526Z","end":"2026-09-01T13:33:27.619885Z","steps":["trace[1533020637] 'process raft request' (duration: 104.24174ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:27.679985Z","caller":"traceutil/trace.go:171","msg":"trace[428132559] transaction","detail":"{read_only:false; response_revision:5631; number_of_response:1; }","duration":"125.978551ms","start":"2026-09-01T13:33:27.553978Z","end":"2026-09-01T13:33:27.679956Z","steps":["trace[428132559] 'process raft request' (duration: 125.735154ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:29.108983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-09-01T13:33:29.183706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"73.418429ms","hash":3771887093,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":30834688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T13:33:29.183776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3771887093,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-09-01T13:33:32.782739Z","caller":"traceutil/trace.go:171","msg":"trace[464775735] linearizableReadLoop","detail":"{readStateIndex:5920; appliedIndex:5918; }","duration":"116.858037ms","start":"2026-09-01T13:33:32.665847Z","end":"2026-09-01T13:33:32.782705Z","steps":["trace[464775735] 'read index received' (duration: 2.026043ms)","trace[464775735] 'applied index is now lower than readState.Index' (duration: 114.831114ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:33:32.782911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.043562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:33:32.78298Z","caller":"traceutil/trace.go:171","msg":"trace[1916482758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5702; }","duration":"117.126024ms","start":"2026-09-01T13:33:32.665818Z","end":"2026-09-01T13:33:32.782944Z","steps":["trace[1916482758] 'agreement among raft nodes before linearized reading' (duration: 116.9804ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:32.783283Z","caller":"traceutil/trace.go:171","msg":"trace[509554454] transaction","detail":"{read_only:false; response_revision:5702; number_of_response:1; }","duration":"135.569237ms","start":"2026-09-01T13:33:32.6477Z","end":"2026-09-01T13:33:32.783269Z","steps":["trace[509554454] 'process raft request' (duration: 95.20653ms)","trace[509554454] 'compare' (duration: 39.666508ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:33.212582Z","caller":"traceutil/trace.go:171","msg":"trace[2006109992] transaction","detail":"{read_only:false; response_revision:5703; number_of_response:1; }","duration":"155.973832ms","start":"2026-09-01T13:33:33.056579Z","end":"2026-09-01T13:33:33.212553Z","steps":["trace[2006109992] 'process raft request' (duration: 155.715895ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:33.213342Z","caller":"traceutil/trace.go:171","msg":"trace[601888776] transaction","detail":"{read_only:false; response_revision:5704; number_of_response:1; }","duration":"147.655519ms","start":"2026-09-01T13:33:33.065669Z","end":"2026-09-01T13:33:33.213324Z","steps":["trace[601888776] 'process raft request' (duration: 147.563076ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:41.336921Z","caller":"traceutil/trace.go:171","msg":"trace[262256049] transaction","detail":"{read_only:false; response_revision:5865; number_of_response:1; }","duration":"147.001353ms","start":"2026-09-01T13:33:41.189893Z","end":"2026-09-01T13:33:41.336894Z","steps":["trace[262256049] 'process raft request' (duration: 146.805777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:41.520913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.246809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:33:41.521141Z","caller":"traceutil/trace.go:171","msg":"trace[537408030] transaction","detail":"{read_only:false; response_revision:5867; number_of_response:1; }","duration":"134.252142ms","start":"2026-09-01T13:33:41.386863Z","end":"2026-09-01T13:33:41.521116Z","steps":["trace[537408030] 'process raft request' (duration: 31.724756ms)","trace[537408030] 'compare' (duration: 102.145926ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:41.72205Z","caller":"traceutil/trace.go:171","msg":"trace[258140851] transaction","detail":"{read_only:false; response_revision:5868; number_of_response:1; }","duration":"139.006279ms","start":"2026-09-01T13:33:41.583015Z","end":"2026-09-01T13:33:41.722022Z","steps":["trace[258140851] 'process raft request' (duration: 95.417716ms)","trace[258140851] 'compare' (duration: 43.447449ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:38:29.116192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5645} {"level":"info","ts":"2026-09-01T13:38:29.199444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5645,"took":"81.274067ms","hash":1644490616,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":29118464,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-01T13:38:29.199509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1644490616,"revision":5645,"compact-revision":2991} {"level":"info","ts":"2026-09-01T13:40:02.617957Z","caller":"traceutil/trace.go:171","msg":"trace[1282503171] linearizableReadLoop","detail":"{readStateIndex:9084; appliedIndex:9083; }","duration":"263.470543ms","start":"2026-09-01T13:40:02.354457Z","end":"2026-09-01T13:40:02.617928Z","steps":["trace[1282503171] 'read index received' (duration: 263.33333ms)","trace[1282503171] 'applied index is now lower than readState.Index' (duration: 135.463µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:40:02.617974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:40:02.285143Z","time spent":"332.824843ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-01T13:40:02.618212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.744121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-01T13:40:02.618279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.008099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-01T13:40:02.618459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.956901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l6xj5\" ","response":"range_response_count:1 size:6441"} {"level":"info","ts":"2026-09-01T13:40:02.618279Z","caller":"traceutil/trace.go:171","msg":"trace[2059018149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8768; }","duration":"263.859194ms","start":"2026-09-01T13:40:02.354399Z","end":"2026-09-01T13:40:02.618258Z","steps":["trace[2059018149] 'agreement among raft nodes before linearized reading' (duration: 263.657609ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.618551Z","caller":"traceutil/trace.go:171","msg":"trace[1354815563] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l6xj5; range_end:; response_count:1; response_revision:8768; }","duration":"107.089715ms","start":"2026-09-01T13:40:02.511451Z","end":"2026-09-01T13:40:02.618541Z","steps":["trace[1354815563] 'agreement among raft nodes before linearized reading' (duration: 106.877979ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.618559Z","caller":"traceutil/trace.go:171","msg":"trace[238166935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8768; }","duration":"210.241055ms","start":"2026-09-01T13:40:02.408205Z","end":"2026-09-01T13:40:02.618446Z","steps":["trace[238166935] 'agreement among raft nodes before linearized reading' (duration: 209.914586ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.816059Z","caller":"traceutil/trace.go:171","msg":"trace[1089611369] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"195.892284ms","start":"2026-09-01T13:40:02.620145Z","end":"2026-09-01T13:40:02.816037Z","steps":["trace[1089611369] 'process raft request' (duration: 195.75489ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.823739Z","caller":"traceutil/trace.go:171","msg":"trace[1160983651] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"201.225644ms","start":"2026-09-01T13:40:02.622487Z","end":"2026-09-01T13:40:02.823713Z","steps":["trace[1160983651] 'process raft request' (duration: 201.007148ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.824095Z","caller":"traceutil/trace.go:171","msg":"trace[955417714] transaction","detail":"{read_only:false; response_revision:8772; number_of_response:1; }","duration":"200.934807ms","start":"2026-09-01T13:40:02.623143Z","end":"2026-09-01T13:40:02.824078Z","steps":["trace[955417714] 'process raft request' (duration: 200.749582ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.824224Z","caller":"traceutil/trace.go:171","msg":"trace[1304281641] transaction","detail":"{read_only:false; response_revision:8773; number_of_response:1; }","duration":"200.711391ms","start":"2026-09-01T13:40:02.6235Z","end":"2026-09-01T13:40:02.824211Z","steps":["trace[1304281641] 'process raft request' (duration: 200.521186ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.824258Z","caller":"traceutil/trace.go:171","msg":"trace[677779103] linearizableReadLoop","detail":"{readStateIndex:9090; appliedIndex:9085; }","duration":"200.396552ms","start":"2026-09-01T13:40:02.623847Z","end":"2026-09-01T13:40:02.824244Z","steps":["trace[677779103] 'read index received' (duration: 192.176763ms)","trace[677779103] 'applied index is now lower than readState.Index' (duration: 8.218759ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:40:02.824286Z","caller":"traceutil/trace.go:171","msg":"trace[1404367367] transaction","detail":"{read_only:false; response_revision:8774; number_of_response:1; }","duration":"200.525066ms","start":"2026-09-01T13:40:02.623736Z","end":"2026-09-01T13:40:02.824261Z","steps":["trace[1404367367] 'process raft request' (duration: 200.408243ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:02.824034Z","caller":"traceutil/trace.go:171","msg":"trace[2055687696] transaction","detail":"{read_only:false; response_revision:8771; number_of_response:1; }","duration":"200.964028ms","start":"2026-09-01T13:40:02.62304Z","end":"2026-09-01T13:40:02.824004Z","steps":["trace[2055687696] 'process raft request' (duration: 200.624329ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:02.824457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.197598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:40:02.825223Z","caller":"traceutil/trace.go:171","msg":"trace[82656235] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8775; }","duration":"102.028359ms","start":"2026-09-01T13:40:02.72317Z","end":"2026-09-01T13:40:02.825198Z","steps":["trace[82656235] 'agreement among raft nodes before linearized reading' (duration: 101.178837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:02.824934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.080441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:222761"} {"level":"info","ts":"2026-09-01T13:40:02.825464Z","caller":"traceutil/trace.go:171","msg":"trace[1479901831] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8775; }","duration":"201.633945ms","start":"2026-09-01T13:40:02.623814Z","end":"2026-09-01T13:40:02.825448Z","steps":["trace[1479901831] 'agreement among raft nodes before linearized reading' (duration: 200.738281ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:13.555235Z","caller":"traceutil/trace.go:171","msg":"trace[620138811] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"107.966638ms","start":"2026-09-01T13:40:13.447245Z","end":"2026-09-01T13:40:13.555212Z","steps":["trace[620138811] 'process raft request' (duration: 107.932038ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:13.555285Z","caller":"traceutil/trace.go:171","msg":"trace[252145034] transaction","detail":"{read_only:false; response_revision:8831; number_of_response:1; }","duration":"338.762971ms","start":"2026-09-01T13:40:13.216481Z","end":"2026-09-01T13:40:13.555244Z","steps":["trace[252145034] 'process raft request' (duration: 254.421733ms)","trace[252145034] 'compare' (duration: 84.125063ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:40:13.555363Z","caller":"traceutil/trace.go:171","msg":"trace[608338339] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"336.993013ms","start":"2026-09-01T13:40:13.218327Z","end":"2026-09-01T13:40:13.55532Z","steps":["trace[608338339] 'process raft request' (duration: 336.807489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:13.555464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:40:13.216455Z","time spent":"338.907814ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:40:13.555578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:40:13.218302Z","time spent":"337.141027ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:40:15.75906Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ca.wal"} {"level":"info","ts":"2026-09-01T13:40:44.402165Z","caller":"traceutil/trace.go:171","msg":"trace[1163345435] transaction","detail":"{read_only:false; response_revision:9051; number_of_response:1; }","duration":"152.891865ms","start":"2026-09-01T13:40:44.249254Z","end":"2026-09-01T13:40:44.402146Z","steps":["trace[1163345435] 'process raft request' (duration: 152.761272ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:53.178388Z","caller":"traceutil/trace.go:171","msg":"trace[513181926] transaction","detail":"{read_only:false; response_revision:9096; number_of_response:1; }","duration":"118.640722ms","start":"2026-09-01T13:40:53.059712Z","end":"2026-09-01T13:40:53.178353Z","steps":["trace[513181926] 'process raft request' (duration: 118.494078ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:54.044365Z","caller":"traceutil/trace.go:171","msg":"trace[1896352043] transaction","detail":"{read_only:false; response_revision:9104; number_of_response:1; }","duration":"283.699604ms","start":"2026-09-01T13:40:53.76064Z","end":"2026-09-01T13:40:54.044339Z","steps":["trace[1896352043] 'process raft request' (duration: 283.57758ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:54.744027Z","caller":"traceutil/trace.go:171","msg":"trace[2081070875] transaction","detail":"{read_only:false; response_revision:9105; number_of_response:1; }","duration":"270.921813ms","start":"2026-09-01T13:40:54.47308Z","end":"2026-09-01T13:40:54.744002Z","steps":["trace[2081070875] 'process raft request' (duration: 270.771209ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:55.333099Z","caller":"traceutil/trace.go:171","msg":"trace[523485222] linearizableReadLoop","detail":"{readStateIndex:9435; appliedIndex:9434; }","duration":"123.133863ms","start":"2026-09-01T13:40:55.209942Z","end":"2026-09-01T13:40:55.333076Z","steps":["trace[523485222] 'read index received' (duration: 122.891537ms)","trace[523485222] 'applied index is now lower than readState.Index' (duration: 241.236µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:40:55.333242Z","caller":"traceutil/trace.go:171","msg":"trace[1868263424] transaction","detail":"{read_only:false; response_revision:9106; number_of_response:1; }","duration":"149.942097ms","start":"2026-09-01T13:40:55.183282Z","end":"2026-09-01T13:40:55.333224Z","steps":["trace[1868263424] 'process raft request' (duration: 149.62974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:55.333793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.833102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-01T13:40:55.33386Z","caller":"traceutil/trace.go:171","msg":"trace[1247331066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9106; }","duration":"123.930244ms","start":"2026-09-01T13:40:55.209913Z","end":"2026-09-01T13:40:55.333843Z","steps":["trace[1247331066] 'agreement among raft nodes before linearized reading' (duration: 123.753929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:57.843498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.040197ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-01T13:40:57.84364Z","caller":"traceutil/trace.go:171","msg":"trace[78339498] linearizableReadLoop","detail":"{readStateIndex:9463; appliedIndex:9462; }","duration":"245.395743ms","start":"2026-09-01T13:40:57.598223Z","end":"2026-09-01T13:40:57.843618Z","steps":["trace[78339498] 'read index received' (duration: 43.269564ms)","trace[78339498] 'applied index is now lower than readState.Index' (duration: 202.124058ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:40:57.843787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.545966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:40:57.843888Z","caller":"traceutil/trace.go:171","msg":"trace[1334523773] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:9133; }","duration":"245.67573ms","start":"2026-09-01T13:40:57.598189Z","end":"2026-09-01T13:40:57.843865Z","steps":["trace[1334523773] 'agreement among raft nodes before linearized reading' (duration: 245.511236ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:57.922932Z","caller":"traceutil/trace.go:171","msg":"trace[1918057752] transaction","detail":"{read_only:false; response_revision:9134; number_of_response:1; }","duration":"172.093617ms","start":"2026-09-01T13:40:57.750787Z","end":"2026-09-01T13:40:57.92288Z","steps":["trace[1918057752] 'process raft request' (duration: 171.767379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:57.930022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.576971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7jbxb\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-09-01T13:40:57.930081Z","caller":"traceutil/trace.go:171","msg":"trace[623113021] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7jbxb; range_end:; response_count:1; response_revision:9135; }","duration":"109.675664ms","start":"2026-09-01T13:40:57.820394Z","end":"2026-09-01T13:40:57.930069Z","steps":["trace[623113021] 'agreement among raft nodes before linearized reading' (duration: 109.5081ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:57.930234Z","caller":"traceutil/trace.go:171","msg":"trace[690379918] transaction","detail":"{read_only:false; response_revision:9135; number_of_response:1; }","duration":"109.748326ms","start":"2026-09-01T13:40:57.820479Z","end":"2026-09-01T13:40:57.930227Z","steps":["trace[690379918] 'process raft request' (duration: 109.289754ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:41:56.106939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T13:41:56.117478Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T13:41:56.117565Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T13:42:23.766365Z","caller":"traceutil/trace.go:171","msg":"trace[438305332] transaction","detail":"{read_only:false; response_revision:9962; number_of_response:1; }","duration":"102.200714ms","start":"2026-09-01T13:42:23.66414Z","end":"2026-09-01T13:42:23.76634Z","steps":["trace[438305332] 'process raft request' (duration: 102.06029ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:42:23.901539Z","caller":"traceutil/trace.go:171","msg":"trace[269638212] transaction","detail":"{read_only:false; response_revision:9963; number_of_response:1; }","duration":"109.137689ms","start":"2026-09-01T13:42:23.792356Z","end":"2026-09-01T13:42:23.901493Z","steps":["trace[269638212] 'process raft request' (duration: 108.792ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:43:29.123835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8029} {"level":"info","ts":"2026-09-01T13:43:29.207131Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8029,"took":"81.082522ms","hash":2937996701,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":25124864,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-01T13:43:29.207193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2937996701,"revision":8029,"compact-revision":5645} {"level":"info","ts":"2026-09-01T13:48:03.914774Z","caller":"traceutil/trace.go:171","msg":"trace[591577178] linearizableReadLoop","detail":"{readStateIndex:12833; appliedIndex:12832; }","duration":"245.068963ms","start":"2026-09-01T13:48:03.669677Z","end":"2026-09-01T13:48:03.914746Z","steps":["trace[591577178] 'read index received' (duration: 244.825907ms)","trace[591577178] 'applied index is now lower than readState.Index' (duration: 242.106µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:48:03.914856Z","caller":"traceutil/trace.go:171","msg":"trace[794246130] transaction","detail":"{read_only:false; response_revision:12400; number_of_response:1; }","duration":"301.115386ms","start":"2026-09-01T13:48:03.613701Z","end":"2026-09-01T13:48:03.914816Z","steps":["trace[794246130] 'process raft request' (duration: 300.88234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:03.914982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.224347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:48:03.915041Z","caller":"traceutil/trace.go:171","msg":"trace[1428047971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12400; }","duration":"245.383231ms","start":"2026-09-01T13:48:03.669641Z","end":"2026-09-01T13:48:03.915024Z","steps":["trace[1428047971] 'agreement among raft nodes before linearized reading' (duration: 245.233217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:03.915066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.005572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:48:03.915085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:03.613681Z","time spent":"301.254241ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:48:03.915143Z","caller":"traceutil/trace.go:171","msg":"trace[99565243] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:12400; }","duration":"212.132955ms","start":"2026-09-01T13:48:03.702993Z","end":"2026-09-01T13:48:03.915126Z","steps":["trace[99565243] 'agreement among raft nodes before linearized reading' (duration: 211.976631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:03.915287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.385049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-01T13:48:03.915302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.89284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T13:48:03.915374Z","caller":"traceutil/trace.go:171","msg":"trace[756377550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12400; }","duration":"101.051464ms","start":"2026-09-01T13:48:03.814305Z","end":"2026-09-01T13:48:03.915356Z","steps":["trace[756377550] 'agreement among raft nodes before linearized reading' (duration: 100.771986ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:03.915385Z","caller":"traceutil/trace.go:171","msg":"trace[1223666591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12400; }","duration":"102.544083ms","start":"2026-09-01T13:48:03.812801Z","end":"2026-09-01T13:48:03.915345Z","steps":["trace[1223666591] 'agreement among raft nodes before linearized reading' (duration: 102.207004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:03.915482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.05773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T13:48:03.915514Z","caller":"traceutil/trace.go:171","msg":"trace[558655085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12400; }","duration":"132.126113ms","start":"2026-09-01T13:48:03.783378Z","end":"2026-09-01T13:48:03.915504Z","steps":["trace[558655085] 'agreement among raft nodes before linearized reading' (duration: 131.99693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:03.915588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.741135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-01T13:48:03.915636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.491501ms","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-09-01T13:48:03.915637Z","caller":"traceutil/trace.go:171","msg":"trace[1779158248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12400; }","duration":"199.790486ms","start":"2026-09-01T13:48:03.715832Z","end":"2026-09-01T13:48:03.915623Z","steps":["trace[1779158248] 'agreement among raft nodes before linearized reading' (duration: 199.659423ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:03.915664Z","caller":"traceutil/trace.go:171","msg":"trace[1428842599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12400; }","duration":"102.529563ms","start":"2026-09-01T13:48:03.813125Z","end":"2026-09-01T13:48:03.915655Z","steps":["trace[1428842599] 'agreement among raft nodes before linearized reading' (duration: 102.452821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:04.137382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.42998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:48:04.137755Z","caller":"traceutil/trace.go:171","msg":"trace[1558333139] transaction","detail":"{read_only:false; response_revision:12402; number_of_response:1; }","duration":"217.051057ms","start":"2026-09-01T13:48:03.920683Z","end":"2026-09-01T13:48:04.137734Z","steps":["trace[1558333139] 'process raft request' (duration: 75.169834ms)","trace[1558333139] 'compare' (duration: 141.238985ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:48:04.137978Z","caller":"traceutil/trace.go:171","msg":"trace[931645165] transaction","detail":"{read_only:false; response_revision:12403; number_of_response:1; }","duration":"217.111159ms","start":"2026-09-01T13:48:03.920849Z","end":"2026-09-01T13:48:04.137961Z","steps":["trace[931645165] 'process raft request' (duration: 216.646016ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:04.138056Z","caller":"traceutil/trace.go:171","msg":"trace[249704148] transaction","detail":"{read_only:false; response_revision:12404; number_of_response:1; }","duration":"216.924073ms","start":"2026-09-01T13:48:03.921113Z","end":"2026-09-01T13:48:04.138037Z","steps":["trace[249704148] 'process raft request' (duration: 216.46449ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:04.137698Z","caller":"traceutil/trace.go:171","msg":"trace[909155104] transaction","detail":"{read_only:false; response_revision:12405; number_of_response:1; }","duration":"216.410159ms","start":"2026-09-01T13:48:03.921271Z","end":"2026-09-01T13:48:04.137681Z","steps":["trace[909155104] 'process raft request' (duration: 216.351708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.014811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.472367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:48:06.015059Z","caller":"traceutil/trace.go:171","msg":"trace[1607588870] transaction","detail":"{read_only:false; response_revision:12409; number_of_response:1; }","duration":"437.605295ms","start":"2026-09-01T13:48:05.577435Z","end":"2026-09-01T13:48:06.01504Z","steps":["trace[1607588870] 'process raft request' (duration: 437.476212ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.01507Z","caller":"traceutil/trace.go:171","msg":"trace[502928384] linearizableReadLoop","detail":"{readStateIndex:12841; appliedIndex:12840; }","duration":"632.769319ms","start":"2026-09-01T13:48:05.382268Z","end":"2026-09-01T13:48:06.015037Z","steps":["trace[502928384] 'read index received' (duration: 367.102126ms)","trace[502928384] 'applied index is now lower than readState.Index' (duration: 265.665153ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:48:06.015096Z","caller":"traceutil/trace.go:171","msg":"trace[716229891] transaction","detail":"{read_only:false; response_revision:12408; number_of_response:1; }","duration":"754.539714ms","start":"2026-09-01T13:48:05.260513Z","end":"2026-09-01T13:48:06.015053Z","steps":["trace[716229891] 'process raft request' (duration: 488.735468ms)","trace[716229891] 'compare' (duration: 265.082127ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:48:06.015141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.577408Z","time spent":"437.691628ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:48:06.015325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.260473Z","time spent":"754.73685ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:48:06.015411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.068137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:48:06.015461Z","caller":"traceutil/trace.go:171","msg":"trace[381391285] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:12409; }","duration":"633.240232ms","start":"2026-09-01T13:48:05.382209Z","end":"2026-09-01T13:48:06.015449Z","steps":["trace[381391285] 'agreement among raft nodes before linearized reading' (duration: 632.988575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.015854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.38214Z","time spent":"633.693943ms","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":65,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-09-01T13:48:06.424646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.064569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:48:06.424915Z","caller":"traceutil/trace.go:171","msg":"trace[1692263896] transaction","detail":"{read_only:false; response_revision:12410; number_of_response:1; }","duration":"578.793289ms","start":"2026-09-01T13:48:05.846101Z","end":"2026-09-01T13:48:06.424894Z","steps":["trace[1692263896] 'process raft request' (duration: 341.489664ms)","trace[1692263896] 'compare' (duration: 236.818692ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:48:06.425235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.846073Z","time spent":"579.120689ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:48:06.425522Z","caller":"traceutil/trace.go:171","msg":"trace[1644402643] linearizableReadLoop","detail":"{readStateIndex:12844; appliedIndex:12842; }","duration":"410.345629ms","start":"2026-09-01T13:48:06.015138Z","end":"2026-09-01T13:48:06.425484Z","steps":["trace[1644402643] 'read index received' (duration: 172.434007ms)","trace[1644402643] 'applied index is now lower than readState.Index' (duration: 237.909232ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:48:06.425719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.59206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T13:48:06.42569Z","caller":"traceutil/trace.go:171","msg":"trace[1285030119] transaction","detail":"{read_only:false; response_revision:12411; number_of_response:1; }","duration":"503.647257ms","start":"2026-09-01T13:48:05.921984Z","end":"2026-09-01T13:48:06.425632Z","steps":["trace[1285030119] 'process raft request' (duration: 502.794384ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.425721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.69222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:48:06.42574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.494726ms","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-09-01T13:48:06.425765Z","caller":"traceutil/trace.go:171","msg":"trace[798232259] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:12411; }","duration":"726.681202ms","start":"2026-09-01T13:48:05.699073Z","end":"2026-09-01T13:48:06.425754Z","steps":["trace[798232259] 'agreement among raft nodes before linearized reading' (duration: 726.509518ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.425802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.699054Z","time spent":"726.740154ms","remote":"127.0.0.1:40642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":150,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-09-01T13:48:06.4258Z","caller":"traceutil/trace.go:171","msg":"trace[1967723517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12411; }","duration":"448.603929ms","start":"2026-09-01T13:48:05.977181Z","end":"2026-09-01T13:48:06.425785Z","steps":["trace[1967723517] 'agreement among raft nodes before linearized reading' (duration: 448.388264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.425856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.977165Z","time spent":"448.67846ms","remote":"127.0.0.1:40712","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-09-01T13:48:06.425867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.779669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-01T13:48:06.425855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.522122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-01T13:48:06.425884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.584643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T13:48:06.425903Z","caller":"traceutil/trace.go:171","msg":"trace[1542163907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12411; }","duration":"282.990433ms","start":"2026-09-01T13:48:06.142903Z","end":"2026-09-01T13:48:06.425894Z","steps":["trace[1542163907] 'agreement among raft nodes before linearized reading' (duration: 282.86624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.425896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.921957Z","time spent":"503.825701ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:48:06.425929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.573263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-01T13:48:06.4258Z","caller":"traceutil/trace.go:171","msg":"trace[1853141293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12411; }","duration":"757.819772ms","start":"2026-09-01T13:48:05.667959Z","end":"2026-09-01T13:48:06.425778Z","steps":["trace[1853141293] 'agreement among raft nodes before linearized reading' (duration: 757.69452ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.425918Z","caller":"traceutil/trace.go:171","msg":"trace[451453510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12411; }","duration":"282.628405ms","start":"2026-09-01T13:48:06.143276Z","end":"2026-09-01T13:48:06.425904Z","steps":["trace[451453510] 'agreement among raft nodes before linearized reading' (duration: 282.40958ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.425945Z","caller":"traceutil/trace.go:171","msg":"trace[343750880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12411; }","duration":"282.715006ms","start":"2026-09-01T13:48:06.143212Z","end":"2026-09-01T13:48:06.425927Z","steps":["trace[343750880] 'agreement among raft nodes before linearized reading' (duration: 282.498162ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.425972Z","caller":"traceutil/trace.go:171","msg":"trace[1379543620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12411; }","duration":"282.659795ms","start":"2026-09-01T13:48:06.1433Z","end":"2026-09-01T13:48:06.42596Z","steps":["trace[1379543620] 'agreement among raft nodes before linearized reading' (duration: 282.530002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.425741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.083607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:48:06.426028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:05.66788Z","time spent":"758.10613ms","remote":"127.0.0.1:40436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-01T13:48:06.426064Z","caller":"traceutil/trace.go:171","msg":"trace[613741064] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:12411; }","duration":"376.456325ms","start":"2026-09-01T13:48:06.049577Z","end":"2026-09-01T13:48:06.426034Z","steps":["trace[613741064] 'agreement among raft nodes before linearized reading' (duration: 376.097027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.426172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:06.049553Z","time spent":"376.564889ms","remote":"127.0.0.1:43092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-09-01T13:48:06.713176Z","caller":"traceutil/trace.go:171","msg":"trace[1455755520] transaction","detail":"{read_only:false; response_revision:12413; number_of_response:1; }","duration":"282.254034ms","start":"2026-09-01T13:48:06.430876Z","end":"2026-09-01T13:48:06.71313Z","steps":["trace[1455755520] 'process raft request' (duration: 281.980837ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.713618Z","caller":"traceutil/trace.go:171","msg":"trace[1317484938] transaction","detail":"{read_only:false; response_revision:12414; number_of_response:1; }","duration":"281.482904ms","start":"2026-09-01T13:48:06.432118Z","end":"2026-09-01T13:48:06.713601Z","steps":["trace[1317484938] 'process raft request' (duration: 280.9679ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.71406Z","caller":"traceutil/trace.go:171","msg":"trace[1344259955] linearizableReadLoop","detail":"{readStateIndex:12849; appliedIndex:12845; }","duration":"185.995419ms","start":"2026-09-01T13:48:06.528037Z","end":"2026-09-01T13:48:06.714032Z","steps":["trace[1344259955] 'read index received' (duration: 184.792676ms)","trace[1344259955] 'applied index is now lower than readState.Index' (duration: 1.201383ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:48:06.714496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.424399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:48:06.714551Z","caller":"traceutil/trace.go:171","msg":"trace[1473719098] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:12416; }","duration":"186.550052ms","start":"2026-09-01T13:48:06.527989Z","end":"2026-09-01T13:48:06.714539Z","steps":["trace[1473719098] 'agreement among raft nodes before linearized reading' (duration: 186.388908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:06.714774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.425157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-l6xj5\" ","response":"range_response_count:1 size:6441"} {"level":"warn","ts":"2026-09-01T13:48:06.714866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.901488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:48:06.714878Z","caller":"traceutil/trace.go:171","msg":"trace[165517340] range","detail":"{range_begin:/registry/pods/openstack/keepalived-l6xj5; range_end:; response_count:1; response_revision:12416; }","duration":"184.586731ms","start":"2026-09-01T13:48:06.530271Z","end":"2026-09-01T13:48:06.714858Z","steps":["trace[165517340] 'agreement among raft nodes before linearized reading' (duration: 184.323614ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.714981Z","caller":"traceutil/trace.go:171","msg":"trace[60427422] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:12416; }","duration":"158.064223ms","start":"2026-09-01T13:48:06.556867Z","end":"2026-09-01T13:48:06.714931Z","steps":["trace[60427422] 'agreement among raft nodes before linearized reading' (duration: 157.896348ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.714757Z","caller":"traceutil/trace.go:171","msg":"trace[1829164161] transaction","detail":"{read_only:false; response_revision:12415; number_of_response:1; }","duration":"282.461139ms","start":"2026-09-01T13:48:06.432269Z","end":"2026-09-01T13:48:06.71473Z","steps":["trace[1829164161] 'process raft request' (duration: 281.567386ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:06.714915Z","caller":"traceutil/trace.go:171","msg":"trace[1849007975] transaction","detail":"{read_only:false; response_revision:12416; number_of_response:1; }","duration":"282.516721ms","start":"2026-09-01T13:48:06.432331Z","end":"2026-09-01T13:48:06.714848Z","steps":["trace[1849007975] 'process raft request' (duration: 281.648428ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:29.130582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10423} {"level":"info","ts":"2026-09-01T13:48:29.216391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10423,"took":"83.344986ms","hash":139270613,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":25837568,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T13:48:29.216459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":139270613,"revision":10423,"compact-revision":8029} {"level":"info","ts":"2026-09-01T13:53:29.142128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12586} {"level":"info","ts":"2026-09-01T13:53:29.233552Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12586,"took":"88.531854ms","hash":2518942866,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":28090368,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T13:53:29.233625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2518942866,"revision":12586,"compact-revision":10423} {"level":"info","ts":"2026-09-01T13:54:04.808687Z","caller":"traceutil/trace.go:171","msg":"trace[603766558] transaction","detail":"{read_only:false; response_revision:14845; number_of_response:1; }","duration":"173.263146ms","start":"2026-09-01T13:54:04.635397Z","end":"2026-09-01T13:54:04.80866Z","steps":["trace[603766558] 'process raft request' (duration: 172.993239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:54:04.808835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.497274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:54:04.808634Z","caller":"traceutil/trace.go:171","msg":"trace[677261216] linearizableReadLoop","detail":"{readStateIndex:15364; appliedIndex:15363; }","duration":"140.293558ms","start":"2026-09-01T13:54:04.668308Z","end":"2026-09-01T13:54:04.808602Z","steps":["trace[677261216] 'read index received' (duration: 140.072512ms)","trace[677261216] 'applied index is now lower than readState.Index' (duration: 219.456µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:54:04.809164Z","caller":"traceutil/trace.go:171","msg":"trace[1404896727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14845; }","duration":"140.841653ms","start":"2026-09-01T13:54:04.668303Z","end":"2026-09-01T13:54:04.809145Z","steps":["trace[1404896727] 'agreement among raft nodes before linearized reading' (duration: 140.451182ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:04.811157Z","caller":"traceutil/trace.go:171","msg":"trace[1163776314] transaction","detail":"{read_only:false; response_revision:14846; number_of_response:1; }","duration":"105.273088ms","start":"2026-09-01T13:54:04.705855Z","end":"2026-09-01T13:54:04.811128Z","steps":["trace[1163776314] 'process raft request' (duration: 103.598284ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:17.94419Z","caller":"traceutil/trace.go:171","msg":"trace[1821728360] transaction","detail":"{read_only:false; response_revision:14922; number_of_response:1; }","duration":"106.84502ms","start":"2026-09-01T13:54:17.837319Z","end":"2026-09-01T13:54:17.944164Z","steps":["trace[1821728360] 'process raft request' (duration: 106.396869ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:39.173542Z","caller":"traceutil/trace.go:171","msg":"trace[1869707024] transaction","detail":"{read_only:false; response_revision:15030; number_of_response:1; }","duration":"137.3361ms","start":"2026-09-01T13:54:39.036173Z","end":"2026-09-01T13:54:39.173509Z","steps":["trace[1869707024] 'process raft request' (duration: 132.030528ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:39.174981Z","caller":"traceutil/trace.go:171","msg":"trace[341358770] transaction","detail":"{read_only:false; response_revision:15031; number_of_response:1; }","duration":"138.656185ms","start":"2026-09-01T13:54:39.036314Z","end":"2026-09-01T13:54:39.17497Z","steps":["trace[341358770] 'process raft request' (duration: 138.515711ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:39.17525Z","caller":"traceutil/trace.go:171","msg":"trace[72642018] transaction","detail":"{read_only:false; response_revision:15032; number_of_response:1; }","duration":"138.919072ms","start":"2026-09-01T13:54:39.036322Z","end":"2026-09-01T13:54:39.175241Z","steps":["trace[72642018] 'process raft request' (duration: 138.577793ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:39.175246Z","caller":"traceutil/trace.go:171","msg":"trace[16484302] transaction","detail":"{read_only:false; response_revision:15033; number_of_response:1; }","duration":"137.029631ms","start":"2026-09-01T13:54:39.038197Z","end":"2026-09-01T13:54:39.175227Z","steps":["trace[16484302] 'process raft request' (duration: 136.941168ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:55.766677Z","caller":"traceutil/trace.go:171","msg":"trace[1778975838] linearizableReadLoop","detail":"{readStateIndex:15661; appliedIndex:15660; }","duration":"109.102098ms","start":"2026-09-01T13:54:55.657554Z","end":"2026-09-01T13:54:55.766656Z","steps":["trace[1778975838] 'read index received' (duration: 109.024525ms)","trace[1778975838] 'applied index is now lower than readState.Index' (duration: 76.863µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:54:55.766849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.275632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:54:55.766934Z","caller":"traceutil/trace.go:171","msg":"trace[2065275851] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:15130; }","duration":"109.361024ms","start":"2026-09-01T13:54:55.657538Z","end":"2026-09-01T13:54:55.766899Z","steps":["trace[2065275851] 'agreement among raft nodes before linearized reading' (duration: 109.19612ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:55.766797Z","caller":"traceutil/trace.go:171","msg":"trace[1671364544] transaction","detail":"{read_only:false; response_revision:15130; number_of_response:1; }","duration":"117.547272ms","start":"2026-09-01T13:54:55.649214Z","end":"2026-09-01T13:54:55.766761Z","steps":["trace[1671364544] 'process raft request' (duration: 117.331887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:54:55.800125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.96738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:54:55.80018Z","caller":"traceutil/trace.go:171","msg":"trace[1828268233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15131; }","duration":"134.073132ms","start":"2026-09-01T13:54:55.666098Z","end":"2026-09-01T13:54:55.800171Z","steps":["trace[1828268233] 'agreement among raft nodes before linearized reading' (duration: 133.98115ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:56.721417Z","caller":"traceutil/trace.go:171","msg":"trace[1343939119] linearizableReadLoop","detail":"{readStateIndex:15665; appliedIndex:15664; }","duration":"116.71525ms","start":"2026-09-01T13:54:56.604678Z","end":"2026-09-01T13:54:56.721394Z","steps":["trace[1343939119] 'read index received' (duration: 116.535445ms)","trace[1343939119] 'applied index is now lower than readState.Index' (duration: 178.935µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:54:56.721544Z","caller":"traceutil/trace.go:171","msg":"trace[2074965023] transaction","detail":"{read_only:false; response_revision:15134; number_of_response:1; }","duration":"299.287835ms","start":"2026-09-01T13:54:56.422236Z","end":"2026-09-01T13:54:56.721524Z","steps":["trace[2074965023] 'process raft request' (duration: 299.003738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:54:56.72173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.030169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:54:56.721813Z","caller":"traceutil/trace.go:171","msg":"trace[1064256031] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:15134; }","duration":"117.166833ms","start":"2026-09-01T13:54:56.604626Z","end":"2026-09-01T13:54:56.721792Z","steps":["trace[1064256031] 'agreement among raft nodes before linearized reading' (duration: 117.011128ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:54:58.583803Z","caller":"traceutil/trace.go:171","msg":"trace[1533093397] transaction","detail":"{read_only:false; response_revision:15150; number_of_response:1; }","duration":"130.426325ms","start":"2026-09-01T13:54:58.45327Z","end":"2026-09-01T13:54:58.583696Z","steps":["trace[1533093397] 'process raft request' (duration: 129.85989ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:06.888652Z","caller":"traceutil/trace.go:171","msg":"trace[803097613] transaction","detail":"{read_only:false; response_revision:15305; number_of_response:1; }","duration":"335.13945ms","start":"2026-09-01T13:55:06.553486Z","end":"2026-09-01T13:55:06.888626Z","steps":["trace[803097613] 'process raft request' (duration: 334.989217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:06.888803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:55:06.553461Z","time spent":"335.266034ms","remote":"127.0.0.1:40620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3708,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:55:06.932848Z","caller":"traceutil/trace.go:171","msg":"trace[549001538] linearizableReadLoop","detail":"{readStateIndex:15840; appliedIndex:15838; }","duration":"265.942227ms","start":"2026-09-01T13:55:06.66688Z","end":"2026-09-01T13:55:06.932822Z","steps":["trace[549001538] 'read index received' (duration: 221.581275ms)","trace[549001538] 'applied index is now lower than readState.Index' (duration: 44.359782ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:55:06.932923Z","caller":"traceutil/trace.go:171","msg":"trace[1251147647] transaction","detail":"{read_only:false; response_revision:15306; number_of_response:1; }","duration":"274.097224ms","start":"2026-09-01T13:55:06.658773Z","end":"2026-09-01T13:55:06.93287Z","steps":["trace[1251147647] 'process raft request' (duration: 273.921339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:06.933049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.169009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-01T13:55:06.933026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.131613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:55:06.933083Z","caller":"traceutil/trace.go:171","msg":"trace[439865010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15306; }","duration":"133.252062ms","start":"2026-09-01T13:55:06.799822Z","end":"2026-09-01T13:55:06.933074Z","steps":["trace[439865010] 'agreement among raft nodes before linearized reading' (duration: 133.130038ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:06.933106Z","caller":"traceutil/trace.go:171","msg":"trace[1386851856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15306; }","duration":"266.262316ms","start":"2026-09-01T13:55:06.666826Z","end":"2026-09-01T13:55:06.933088Z","steps":["trace[1386851856] 'agreement among raft nodes before linearized reading' (duration: 266.115702ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:07.09877Z","caller":"traceutil/trace.go:171","msg":"trace[294223148] transaction","detail":"{read_only:false; response_revision:15307; number_of_response:1; }","duration":"161.892284ms","start":"2026-09-01T13:55:06.936841Z","end":"2026-09-01T13:55:07.098733Z","steps":["trace[294223148] 'process raft request' (duration: 155.146665ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:07.101489Z","caller":"traceutil/trace.go:171","msg":"trace[173598902] transaction","detail":"{read_only:false; response_revision:15308; number_of_response:1; }","duration":"160.502536ms","start":"2026-09-01T13:55:06.940959Z","end":"2026-09-01T13:55:07.101461Z","steps":["trace[173598902] 'process raft request' (duration: 160.389593ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:14.629386Z","caller":"traceutil/trace.go:171","msg":"trace[1403914341] transaction","detail":"{read_only:false; response_revision:16229; number_of_response:1; }","duration":"174.594243ms","start":"2026-09-01T13:56:14.454758Z","end":"2026-09-01T13:56:14.629352Z","steps":["trace[1403914341] 'process raft request' (duration: 174.344656ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:48.393666Z","caller":"traceutil/trace.go:171","msg":"trace[1610101321] linearizableReadLoop","detail":"{readStateIndex:16973; appliedIndex:16970; }","duration":"285.94629ms","start":"2026-09-01T13:56:48.107696Z","end":"2026-09-01T13:56:48.393642Z","steps":["trace[1610101321] 'read index received' (duration: 24.147303ms)","trace[1610101321] 'applied index is now lower than readState.Index' (duration: 261.798027ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:56:48.393741Z","caller":"traceutil/trace.go:171","msg":"trace[1962295818] transaction","detail":"{read_only:false; response_revision:16403; number_of_response:1; }","duration":"288.167109ms","start":"2026-09-01T13:56:48.10554Z","end":"2026-09-01T13:56:48.393707Z","steps":["trace[1962295818] 'process raft request' (duration: 287.879481ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:48.393807Z","caller":"traceutil/trace.go:171","msg":"trace[1607612844] transaction","detail":"{read_only:false; response_revision:16404; number_of_response:1; }","duration":"287.82934ms","start":"2026-09-01T13:56:48.105953Z","end":"2026-09-01T13:56:48.393782Z","steps":["trace[1607612844] 'process raft request' (duration: 287.628555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:48.394715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.00989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764175"} {"level":"info","ts":"2026-09-01T13:56:48.394784Z","caller":"traceutil/trace.go:171","msg":"trace[1906021159] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:16404; }","duration":"287.123412ms","start":"2026-09-01T13:56:48.107643Z","end":"2026-09-01T13:56:48.394766Z","steps":["trace[1906021159] 'agreement among raft nodes before linearized reading' (duration: 286.138605ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:48.418935Z","caller":"traceutil/trace.go:171","msg":"trace[1762592679] transaction","detail":"{read_only:false; response_revision:16405; number_of_response:1; }","duration":"226.737172ms","start":"2026-09-01T13:56:48.192163Z","end":"2026-09-01T13:56:48.4189Z","steps":["trace[1762592679] 'process raft request' (duration: 226.179017ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:48.41919Z","caller":"traceutil/trace.go:171","msg":"trace[1377950777] transaction","detail":"{read_only:false; response_revision:16406; number_of_response:1; }","duration":"178.795975ms","start":"2026-09-01T13:56:48.240379Z","end":"2026-09-01T13:56:48.419175Z","steps":["trace[1377950777] 'process raft request' (duration: 178.077945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:48.420004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.280316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-l8qdr\" ","response":"range_response_count:1 size:22011"} {"level":"info","ts":"2026-09-01T13:56:48.420055Z","caller":"traceutil/trace.go:171","msg":"trace[830642231] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-l8qdr; range_end:; response_count:1; response_revision:16406; }","duration":"290.371289ms","start":"2026-09-01T13:56:48.12967Z","end":"2026-09-01T13:56:48.420042Z","steps":["trace[830642231] 'agreement among raft nodes before linearized reading' (duration: 290.230865ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:48.420418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.540265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2z8bh\" ","response":"range_response_count:1 size:20555"} {"level":"info","ts":"2026-09-01T13:56:48.420517Z","caller":"traceutil/trace.go:171","msg":"trace[177152115] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2z8bh; range_end:; response_count:1; response_revision:16406; }","duration":"158.689618ms","start":"2026-09-01T13:56:48.261804Z","end":"2026-09-01T13:56:48.420493Z","steps":["trace[177152115] 'agreement among raft nodes before linearized reading' (duration: 158.468762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:48.420575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.228503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-01T13:56:48.42064Z","caller":"traceutil/trace.go:171","msg":"trace[572017139] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:16406; }","duration":"201.327455ms","start":"2026-09-01T13:56:48.219296Z","end":"2026-09-01T13:56:48.420624Z","steps":["trace[572017139] 'agreement among raft nodes before linearized reading' (duration: 200.216596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:48.42157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.665776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-01T13:56:48.421647Z","caller":"traceutil/trace.go:171","msg":"trace[1161079082] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16406; }","duration":"308.781579ms","start":"2026-09-01T13:56:48.112847Z","end":"2026-09-01T13:56:48.421628Z","steps":["trace[1161079082] 'agreement among raft nodes before linearized reading' (duration: 308.609284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:48.421691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:56:48.112836Z","time spent":"308.84231ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":692,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-09-01T13:56:53.643373Z","caller":"traceutil/trace.go:171","msg":"trace[2134985480] linearizableReadLoop","detail":"{readStateIndex:17000; appliedIndex:16999; }","duration":"102.698257ms","start":"2026-09-01T13:56:53.540649Z","end":"2026-09-01T13:56:53.643347Z","steps":["trace[2134985480] 'read index received' (duration: 102.545203ms)","trace[2134985480] 'applied index is now lower than readState.Index' (duration: 151.644µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:56:53.643448Z","caller":"traceutil/trace.go:171","msg":"trace[455729711] transaction","detail":"{read_only:false; response_revision:16430; number_of_response:1; }","duration":"172.064526ms","start":"2026-09-01T13:56:53.471349Z","end":"2026-09-01T13:56:53.643414Z","steps":["trace[455729711] 'process raft request' (duration: 171.84344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:53.643633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.968435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"info","ts":"2026-09-01T13:56:53.643669Z","caller":"traceutil/trace.go:171","msg":"trace[67476476] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16430; }","duration":"103.057618ms","start":"2026-09-01T13:56:53.540599Z","end":"2026-09-01T13:56:53.643656Z","steps":["trace[67476476] 'agreement among raft nodes before linearized reading' (duration: 102.883903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:53.67672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.681113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T13:56:53.676815Z","caller":"traceutil/trace.go:171","msg":"trace[1961175635] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16431; }","duration":"109.790727ms","start":"2026-09-01T13:56:53.567007Z","end":"2026-09-01T13:56:53.676798Z","steps":["trace[1961175635] 'agreement among raft nodes before linearized reading' (duration: 109.627362ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:53.676809Z","caller":"traceutil/trace.go:171","msg":"trace[1664308941] transaction","detail":"{read_only:false; response_revision:16431; number_of_response:1; }","duration":"111.154752ms","start":"2026-09-01T13:56:53.565618Z","end":"2026-09-01T13:56:53.676773Z","steps":["trace[1664308941] 'process raft request' (duration: 110.886045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:53.677104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.275569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T13:56:53.677187Z","caller":"traceutil/trace.go:171","msg":"trace[756423894] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16431; }","duration":"104.362522ms","start":"2026-09-01T13:56:53.572802Z","end":"2026-09-01T13:56:53.677165Z","steps":["trace[756423894] 'agreement among raft nodes before linearized reading' (duration: 104.216878ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.542331Z","caller":"traceutil/trace.go:171","msg":"trace[1688280170] linearizableReadLoop","detail":"{readStateIndex:17005; appliedIndex:17004; }","duration":"122.180167ms","start":"2026-09-01T13:56:54.420109Z","end":"2026-09-01T13:56:54.542289Z","steps":["trace[1688280170] 'read index received' (duration: 122.091965ms)","trace[1688280170] 'applied index is now lower than readState.Index' (duration: 87.132µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:56:54.542429Z","caller":"traceutil/trace.go:171","msg":"trace[342408213] transaction","detail":"{read_only:false; response_revision:16435; number_of_response:1; }","duration":"122.66292ms","start":"2026-09-01T13:56:54.419734Z","end":"2026-09-01T13:56:54.542396Z","steps":["trace[342408213] 'process raft request' (duration: 122.407193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.542595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.455334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-01T13:56:54.54267Z","caller":"traceutil/trace.go:171","msg":"trace[1621593347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16435; }","duration":"122.557737ms","start":"2026-09-01T13:56:54.420093Z","end":"2026-09-01T13:56:54.542651Z","steps":["trace[1621593347] 'agreement among raft nodes before linearized reading' (duration: 122.349031ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.728807Z","caller":"traceutil/trace.go:171","msg":"trace[1713622695] transaction","detail":"{read_only:false; response_revision:16436; number_of_response:1; }","duration":"288.216191ms","start":"2026-09-01T13:56:54.440557Z","end":"2026-09-01T13:56:54.728773Z","steps":["trace[1713622695] 'process raft request' (duration: 287.623145ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.729026Z","caller":"traceutil/trace.go:171","msg":"trace[1632202458] linearizableReadLoop","detail":"{readStateIndex:17007; appliedIndex:17005; }","duration":"186.599492ms","start":"2026-09-01T13:56:54.542402Z","end":"2026-09-01T13:56:54.729002Z","steps":["trace[1632202458] 'read index received' (duration: 185.7959ms)","trace[1632202458] 'applied index is now lower than readState.Index' (duration: 802.352µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:56:54.729332Z","caller":"traceutil/trace.go:171","msg":"trace[451100287] transaction","detail":"{read_only:false; response_revision:16437; number_of_response:1; }","duration":"287.293427ms","start":"2026-09-01T13:56:54.442023Z","end":"2026-09-01T13:56:54.729317Z","steps":["trace[451100287] 'process raft request' (duration: 286.657679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.729489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.94066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T13:56:54.729541Z","caller":"traceutil/trace.go:171","msg":"trace[356814761] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16437; }","duration":"289.032082ms","start":"2026-09-01T13:56:54.440496Z","end":"2026-09-01T13:56:54.729528Z","steps":["trace[356814761] 'agreement among raft nodes before linearized reading' (duration: 288.94436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.729587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.041928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"warn","ts":"2026-09-01T13:56:54.729579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.700724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T13:56:54.729737Z","caller":"traceutil/trace.go:171","msg":"trace[2002230644] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16437; }","duration":"297.873588ms","start":"2026-09-01T13:56:54.431811Z","end":"2026-09-01T13:56:54.729684Z","steps":["trace[2002230644] 'agreement among raft nodes before linearized reading' (duration: 297.656003ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.729641Z","caller":"traceutil/trace.go:171","msg":"trace[1651096998] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16437; }","duration":"228.104899ms","start":"2026-09-01T13:56:54.501517Z","end":"2026-09-01T13:56:54.729622Z","steps":["trace[1651096998] 'agreement among raft nodes before linearized reading' (duration: 228.002666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.729811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.238633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T13:56:54.729848Z","caller":"traceutil/trace.go:171","msg":"trace[837108395] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16437; }","duration":"231.307295ms","start":"2026-09-01T13:56:54.498528Z","end":"2026-09-01T13:56:54.729836Z","steps":["trace[837108395] 'agreement among raft nodes before linearized reading' (duration: 231.255383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.729585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.492532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T13:56:54.729761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.182361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-01T13:56:54.729983Z","caller":"traceutil/trace.go:171","msg":"trace[409865283] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16437; }","duration":"287.971225ms","start":"2026-09-01T13:56:54.441991Z","end":"2026-09-01T13:56:54.729963Z","steps":["trace[409865283] 'agreement among raft nodes before linearized reading' (duration: 287.363179ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.730022Z","caller":"traceutil/trace.go:171","msg":"trace[32729871] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16437; }","duration":"231.434398ms","start":"2026-09-01T13:56:54.498568Z","end":"2026-09-01T13:56:54.730003Z","steps":["trace[32729871] 'agreement among raft nodes before linearized reading' (duration: 231.096839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.730048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.493128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"warn","ts":"2026-09-01T13:56:54.729489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.109741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-09-01T13:56:54.730113Z","caller":"traceutil/trace.go:171","msg":"trace[1550684798] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16437; }","duration":"284.77522ms","start":"2026-09-01T13:56:54.445325Z","end":"2026-09-01T13:56:54.7301Z","steps":["trace[1550684798] 'agreement among raft nodes before linearized reading' (duration: 284.006929ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.730132Z","caller":"traceutil/trace.go:171","msg":"trace[285041643] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16437; }","duration":"285.58186ms","start":"2026-09-01T13:56:54.444531Z","end":"2026-09-01T13:56:54.730113Z","steps":["trace[285041643] 'agreement among raft nodes before linearized reading' (duration: 285.149398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.730148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.380639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T13:56:54.730199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.949981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-l8qdr\" ","response":"range_response_count:1 size:22011"} {"level":"warn","ts":"2026-09-01T13:56:54.730128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.541927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2z8bh\" ","response":"range_response_count:1 size:20555"} {"level":"info","ts":"2026-09-01T13:56:54.730241Z","caller":"traceutil/trace.go:171","msg":"trace[1676935932] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-l8qdr; range_end:; response_count:1; response_revision:16438; }","duration":"268.015483ms","start":"2026-09-01T13:56:54.462214Z","end":"2026-09-01T13:56:54.730229Z","steps":["trace[1676935932] 'agreement among raft nodes before linearized reading' (duration: 267.930601ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.73025Z","caller":"traceutil/trace.go:171","msg":"trace[1001065868] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2z8bh; range_end:; response_count:1; response_revision:16438; }","duration":"235.702451ms","start":"2026-09-01T13:56:54.494535Z","end":"2026-09-01T13:56:54.730238Z","steps":["trace[1001065868] 'agreement among raft nodes before linearized reading' (duration: 235.513746ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.730378Z","caller":"traceutil/trace.go:171","msg":"trace[417327006] transaction","detail":"{read_only:false; response_revision:16438; number_of_response:1; }","duration":"177.912921ms","start":"2026-09-01T13:56:54.55245Z","end":"2026-09-01T13:56:54.730363Z","steps":["trace[417327006] 'process raft request' (duration: 177.368567ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.730511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.039085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"info","ts":"2026-09-01T13:56:54.730553Z","caller":"traceutil/trace.go:171","msg":"trace[1097977192] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16438; }","duration":"285.116618ms","start":"2026-09-01T13:56:54.445428Z","end":"2026-09-01T13:56:54.730544Z","steps":["trace[1097977192] 'agreement among raft nodes before linearized reading' (duration: 285.013215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.730033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.584792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-l8qdr\" ","response":"range_response_count:1 size:22011"} {"level":"warn","ts":"2026-09-01T13:56:54.730536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.333083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"info","ts":"2026-09-01T13:56:54.730606Z","caller":"traceutil/trace.go:171","msg":"trace[2036466439] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-l8qdr; range_end:; response_count:1; response_revision:16437; }","duration":"235.181167ms","start":"2026-09-01T13:56:54.49541Z","end":"2026-09-01T13:56:54.730592Z","steps":["trace[2036466439] 'agreement among raft nodes before linearized reading' (duration: 234.546921ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.730232Z","caller":"traceutil/trace.go:171","msg":"trace[279692746] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:16438; }","duration":"114.520103ms","start":"2026-09-01T13:56:54.615689Z","end":"2026-09-01T13:56:54.730209Z","steps":["trace[279692746] 'agreement among raft nodes before linearized reading' (duration: 114.341688ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:54.730622Z","caller":"traceutil/trace.go:171","msg":"trace[169874750] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16438; }","duration":"288.457437ms","start":"2026-09-01T13:56:54.442151Z","end":"2026-09-01T13:56:54.730608Z","steps":["trace[169874750] 'agreement among raft nodes before linearized reading' (duration: 288.263802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:54.730349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.911108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T13:56:54.730822Z","caller":"traceutil/trace.go:171","msg":"trace[1982099803] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16438; }","duration":"286.396452ms","start":"2026-09-01T13:56:54.44441Z","end":"2026-09-01T13:56:54.730807Z","steps":["trace[1982099803] 'agreement among raft nodes before linearized reading' (duration: 285.889058ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:57:04.879201Z","caller":"traceutil/trace.go:171","msg":"trace[626973162] transaction","detail":"{read_only:false; response_revision:16486; number_of_response:1; }","duration":"109.439077ms","start":"2026-09-01T13:57:04.769729Z","end":"2026-09-01T13:57:04.879169Z","steps":["trace[626973162] 'process raft request' (duration: 62.63609ms)","trace[626973162] 'compare' (duration: 46.56555ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:57:29.951428Z","caller":"traceutil/trace.go:171","msg":"trace[579277988] transaction","detail":"{read_only:false; response_revision:16637; number_of_response:1; }","duration":"140.767011ms","start":"2026-09-01T13:57:29.810619Z","end":"2026-09-01T13:57:29.951386Z","steps":["trace[579277988] 'process raft request' (duration: 140.488374ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:57:29.952525Z","caller":"traceutil/trace.go:171","msg":"trace[1529172133] transaction","detail":"{read_only:false; response_revision:16638; number_of_response:1; }","duration":"131.525095ms","start":"2026-09-01T13:57:29.820969Z","end":"2026-09-01T13:57:29.952494Z","steps":["trace[1529172133] 'process raft request' (duration: 131.364431ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:58:29.15019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14590} {"level":"info","ts":"2026-09-01T13:58:29.231134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14590,"took":"77.987898ms","hash":151428574,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":30818304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T13:58:29.231214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":151428574,"revision":14590,"compact-revision":12586} {"level":"warn","ts":"2026-09-01T14:02:27.288261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.826414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-09-01T14:02:27.288358Z","caller":"traceutil/trace.go:171","msg":"trace[575498515] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:18892; }","duration":"205.966028ms","start":"2026-09-01T14:02:27.082373Z","end":"2026-09-01T14:02:27.288339Z","steps":["trace[575498515] 'range keys from in-memory index tree' (duration: 205.698841ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:02:27.293627Z","caller":"traceutil/trace.go:171","msg":"trace[1178182390] transaction","detail":"{read_only:false; response_revision:18893; number_of_response:1; }","duration":"200.320468ms","start":"2026-09-01T14:02:27.09329Z","end":"2026-09-01T14:02:27.293611Z","steps":["trace[1178182390] 'process raft request' (duration: 200.186534ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:02:27.294915Z","caller":"traceutil/trace.go:171","msg":"trace[1263588166] transaction","detail":"{read_only:false; response_revision:18894; number_of_response:1; }","duration":"189.933792ms","start":"2026-09-01T14:02:27.104972Z","end":"2026-09-01T14:02:27.294906Z","steps":["trace[1263588166] 'process raft request' (duration: 189.693495ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:02:32.836681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.194587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T14:02:32.83684Z","caller":"traceutil/trace.go:171","msg":"trace[282413200] linearizableReadLoop","detail":"{readStateIndex:19618; appliedIndex:19617; }","duration":"498.860454ms","start":"2026-09-01T14:02:32.337962Z","end":"2026-09-01T14:02:32.836822Z","steps":["trace[282413200] 'read index received' (duration: 221.42079ms)","trace[282413200] 'applied index is now lower than readState.Index' (duration: 277.438224ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:02:32.836901Z","caller":"traceutil/trace.go:171","msg":"trace[100472225] transaction","detail":"{read_only:false; response_revision:18965; number_of_response:1; }","duration":"541.018127ms","start":"2026-09-01T14:02:32.295853Z","end":"2026-09-01T14:02:32.836871Z","steps":["trace[100472225] 'process raft request' (duration: 263.527872ms)","trace[100472225] 'compare' (duration: 277.062344ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:02:32.837073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:02:32.295826Z","time spent":"541.142661ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T14:02:32.838064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.118455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-09-01T14:02:32.83811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.820506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-09-01T14:02:32.838092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.092047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T14:02:32.838164Z","caller":"traceutil/trace.go:171","msg":"trace[915031586] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18965; }","duration":"351.923409ms","start":"2026-09-01T14:02:32.486226Z","end":"2026-09-01T14:02:32.838149Z","steps":["trace[915031586] 'agreement among raft nodes before linearized reading' (duration: 351.690292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:02:32.838205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:02:32.486199Z","time spent":"351.996921ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-09-01T14:02:32.838138Z","caller":"traceutil/trace.go:171","msg":"trace[204952695] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:18965; }","duration":"477.188007ms","start":"2026-09-01T14:02:32.360929Z","end":"2026-09-01T14:02:32.838117Z","steps":["trace[204952695] 'agreement among raft nodes before linearized reading' (duration: 476.980291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:02:32.838485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:02:32.36089Z","time spent":"477.506015ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"info","ts":"2026-09-01T14:02:32.838203Z","caller":"traceutil/trace.go:171","msg":"trace[655329721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18965; }","duration":"500.245891ms","start":"2026-09-01T14:02:32.33793Z","end":"2026-09-01T14:02:32.838176Z","steps":["trace[655329721] 'agreement among raft nodes before linearized reading' (duration: 499.892482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:02:32.838616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:02:32.337916Z","time spent":"500.680203ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T14:02:32.838064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.597128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2z8bh\" ","response":"range_response_count:1 size:20657"} {"level":"info","ts":"2026-09-01T14:02:32.838874Z","caller":"traceutil/trace.go:171","msg":"trace[509408873] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2z8bh; range_end:; response_count:1; response_revision:18965; }","duration":"266.440549ms","start":"2026-09-01T14:02:32.572398Z","end":"2026-09-01T14:02:32.838839Z","steps":["trace[509408873] 'agreement among raft nodes before linearized reading' (duration: 265.512065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:02:32.839065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.418722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-01T14:02:32.839389Z","caller":"traceutil/trace.go:171","msg":"trace[650482243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18965; }","duration":"313.871874ms","start":"2026-09-01T14:02:32.525484Z","end":"2026-09-01T14:02:32.839356Z","steps":["trace[650482243] 'agreement among raft nodes before linearized reading' (duration: 312.429486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:02:32.83951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:02:32.525468Z","time spent":"314.024238ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-01T14:03:06.39984Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T14:03:06.406263Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T14:03:06.406404Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T14:03:22.673702Z","caller":"traceutil/trace.go:171","msg":"trace[1052532646] transaction","detail":"{read_only:false; response_revision:19451; number_of_response:1; }","duration":"109.447466ms","start":"2026-09-01T14:03:22.56421Z","end":"2026-09-01T14:03:22.673658Z","steps":["trace[1052532646] 'process raft request' (duration: 109.19571ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:03:29.166392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17053} {"level":"info","ts":"2026-09-01T14:03:29.27555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17053,"took":"106.186969ms","hash":3126425340,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":34091008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T14:03:29.275612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3126425340,"revision":17053,"compact-revision":14590} {"level":"info","ts":"2026-09-01T14:08:25.803344Z","caller":"traceutil/trace.go:171","msg":"trace[1144342950] transaction","detail":"{read_only:false; response_revision:21762; number_of_response:1; }","duration":"131.913845ms","start":"2026-09-01T14:08:25.671377Z","end":"2026-09-01T14:08:25.803291Z","steps":["trace[1144342950] 'process raft request' (duration: 131.621648ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:08:29.176941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19661} {"level":"warn","ts":"2026-09-01T14:08:29.521721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.310612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7913"} {"level":"info","ts":"2026-09-01T14:08:29.521801Z","caller":"traceutil/trace.go:171","msg":"trace[1419090521] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:21780; }","duration":"151.446735ms","start":"2026-09-01T14:08:29.370336Z","end":"2026-09-01T14:08:29.521782Z","steps":["trace[1419090521] 'range keys from in-memory index tree' (duration: 151.045765ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:08:29.851956Z","caller":"traceutil/trace.go:171","msg":"trace[244377734] transaction","detail":"{read_only:false; response_revision:21781; number_of_response:1; }","duration":"348.441546ms","start":"2026-09-01T14:08:29.503464Z","end":"2026-09-01T14:08:29.851906Z","steps":["trace[244377734] 'process raft request' (duration: 301.097184ms)","trace[244377734] 'compare' (duration: 31.183621ms)","trace[244377734] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:538; } (duration: 15.923465ms)"],"step_count":3} {"level":"warn","ts":"2026-09-01T14:08:29.852105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:08:29.503431Z","time spent":"348.59271ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T14:08:29.852429Z","caller":"traceutil/trace.go:171","msg":"trace[753368807] transaction","detail":"{read_only:false; response_revision:21782; number_of_response:1; }","duration":"323.234673ms","start":"2026-09-01T14:08:29.529181Z","end":"2026-09-01T14:08:29.852415Z","steps":["trace[753368807] 'process raft request' (duration: 322.637198ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:08:29.852504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:08:29.529101Z","time spent":"323.367597ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T14:08:29.852714Z","caller":"traceutil/trace.go:171","msg":"trace[658773136] linearizableReadLoop","detail":"{readStateIndex:22527; appliedIndex:22525; }","duration":"217.149487ms","start":"2026-09-01T14:08:29.635552Z","end":"2026-09-01T14:08:29.852702Z","steps":["trace[658773136] 'read index received' (duration: 168.926182ms)","trace[658773136] 'applied index is now lower than readState.Index' (duration: 48.221485ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:08:29.852896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.307721ms","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-09-01T14:08:29.853029Z","caller":"traceutil/trace.go:171","msg":"trace[570486816] 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:21782; }","duration":"217.496786ms","start":"2026-09-01T14:08:29.635469Z","end":"2026-09-01T14:08:29.853002Z","steps":["trace[570486816] 'agreement among raft nodes before linearized reading' (duration: 217.263469ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:08:29.853403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.684818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T14:08:29.853508Z","caller":"traceutil/trace.go:171","msg":"trace[1900048726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21783; }","duration":"182.835203ms","start":"2026-09-01T14:08:29.670647Z","end":"2026-09-01T14:08:29.853483Z","steps":["trace[1900048726] 'agreement among raft nodes before linearized reading' (duration: 182.572406ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:08:29.868281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19661,"took":"684.988045ms","hash":888608848,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T14:08:29.868351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":888608848,"revision":19661,"compact-revision":17053} {"level":"info","ts":"2026-09-01T14:08:46.083079Z","caller":"traceutil/trace.go:171","msg":"trace[373572109] transaction","detail":"{read_only:false; response_revision:21865; number_of_response:1; }","duration":"159.931062ms","start":"2026-09-01T14:08:45.923122Z","end":"2026-09-01T14:08:46.083053Z","steps":["trace[373572109] 'process raft request' (duration: 159.727537ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:08:46.094269Z","caller":"traceutil/trace.go:171","msg":"trace[1071580212] linearizableReadLoop","detail":"{readStateIndex:22615; appliedIndex:22613; }","duration":"165.724966ms","start":"2026-09-01T14:08:45.928526Z","end":"2026-09-01T14:08:46.094251Z","steps":["trace[1071580212] 'read index received' (duration: 154.300582ms)","trace[1071580212] 'applied index is now lower than readState.Index' (duration: 11.423524ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:08:46.094469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.318666ms","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-09-01T14:08:46.094446Z","caller":"traceutil/trace.go:171","msg":"trace[1941263708] transaction","detail":"{read_only:false; response_revision:21866; number_of_response:1; }","duration":"170.935325ms","start":"2026-09-01T14:08:45.923475Z","end":"2026-09-01T14:08:46.094411Z","steps":["trace[1941263708] 'process raft request' (duration: 170.533644ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:08:46.094516Z","caller":"traceutil/trace.go:171","msg":"trace[814291955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21866; }","duration":"156.416798ms","start":"2026-09-01T14:08:45.938087Z","end":"2026-09-01T14:08:46.094504Z","steps":["trace[814291955] 'agreement among raft nodes before linearized reading' (duration: 156.265054ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:08:46.095166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.627441ms","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-09-01T14:08:46.09523Z","caller":"traceutil/trace.go:171","msg":"trace[990295561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21866; }","duration":"166.715722ms","start":"2026-09-01T14:08:45.928499Z","end":"2026-09-01T14:08:46.095214Z","steps":["trace[990295561] 'agreement among raft nodes before linearized reading' (duration: 165.85669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:08:46.376778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.574835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T14:08:46.376878Z","caller":"traceutil/trace.go:171","msg":"trace[1375456421] transaction","detail":"{read_only:false; response_revision:21867; number_of_response:1; }","duration":"277.414203ms","start":"2026-09-01T14:08:46.099445Z","end":"2026-09-01T14:08:46.376859Z","steps":["trace[1375456421] 'process raft request' (duration: 121.680293ms)","trace[1375456421] 'compare' (duration: 155.406271ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:08:46.391268Z","caller":"traceutil/trace.go:171","msg":"trace[709927621] transaction","detail":"{read_only:false; response_revision:21869; number_of_response:1; }","duration":"289.981539ms","start":"2026-09-01T14:08:46.101249Z","end":"2026-09-01T14:08:46.39123Z","steps":["trace[709927621] 'process raft request' (duration: 289.926487ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:08:46.391314Z","caller":"traceutil/trace.go:171","msg":"trace[187716069] transaction","detail":"{read_only:false; response_revision:21868; number_of_response:1; }","duration":"291.310603ms","start":"2026-09-01T14:08:46.099958Z","end":"2026-09-01T14:08:46.391268Z","steps":["trace[187716069] 'process raft request' (duration: 291.051746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:10:02.701934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:10:02.388153Z","time spent":"313.776842ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-01T14:10:02.887939Z","caller":"traceutil/trace.go:171","msg":"trace[1979670392] transaction","detail":"{read_only:false; response_revision:22560; number_of_response:1; }","duration":"461.635211ms","start":"2026-09-01T14:10:02.426278Z","end":"2026-09-01T14:10:02.887913Z","steps":["trace[1979670392] 'process raft request' (duration: 461.383035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:10:02.888108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:10:02.426245Z","time spent":"461.776385ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T14:10:02.888243Z","caller":"traceutil/trace.go:171","msg":"trace[163813452] transaction","detail":"{read_only:false; response_revision:22561; number_of_response:1; }","duration":"461.480078ms","start":"2026-09-01T14:10:02.426751Z","end":"2026-09-01T14:10:02.888231Z","steps":["trace[163813452] 'process raft request' (duration: 461.082797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:10:02.888298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:10:02.426727Z","time spent":"461.544069ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T14:10:02.888282Z","caller":"traceutil/trace.go:171","msg":"trace[1310467646] transaction","detail":"{read_only:false; response_revision:22562; number_of_response:1; }","duration":"209.38538ms","start":"2026-09-01T14:10:02.678868Z","end":"2026-09-01T14:10:02.888253Z","steps":["trace[1310467646] 'process raft request' (duration: 209.221945ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:10:02.888398Z","caller":"traceutil/trace.go:171","msg":"trace[793575656] linearizableReadLoop","detail":"{readStateIndex:23337; appliedIndex:23334; }","duration":"405.979739ms","start":"2026-09-01T14:10:02.482387Z","end":"2026-09-01T14:10:02.888367Z","steps":["trace[793575656] 'read index received' (duration: 219.440158ms)","trace[793575656] 'applied index is now lower than readState.Index' (duration: 186.536061ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:10:02.888561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.149343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T14:10:02.888656Z","caller":"traceutil/trace.go:171","msg":"trace[1200971183] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:22562; }","duration":"406.303188ms","start":"2026-09-01T14:10:02.482332Z","end":"2026-09-01T14:10:02.888635Z","steps":["trace[1200971183] 'agreement among raft nodes before linearized reading' (duration: 406.130573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:10:02.888708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:10:02.482306Z","time spent":"406.38617ms","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-09-01T14:10:02.895117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.522501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T14:10:02.895151Z","caller":"traceutil/trace.go:171","msg":"trace[1130866586] transaction","detail":"{read_only:false; response_revision:22563; number_of_response:1; }","duration":"192.081489ms","start":"2026-09-01T14:10:02.703048Z","end":"2026-09-01T14:10:02.89513Z","steps":["trace[1130866586] 'process raft request' (duration: 191.874784ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:10:02.895175Z","caller":"traceutil/trace.go:171","msg":"trace[1711389732] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:22563; }","duration":"359.626734ms","start":"2026-09-01T14:10:02.535531Z","end":"2026-09-01T14:10:02.895158Z","steps":["trace[1711389732] 'agreement among raft nodes before linearized reading' (duration: 359.510891ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:10:02.895292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:10:02.535494Z","time spent":"359.774458ms","remote":"127.0.0.1:42336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-01T14:10:02.895438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.014599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T14:10:02.8955Z","caller":"traceutil/trace.go:171","msg":"trace[363646817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22563; }","duration":"227.112601ms","start":"2026-09-01T14:10:02.66837Z","end":"2026-09-01T14:10:02.895483Z","steps":["trace[363646817] 'agreement among raft nodes before linearized reading' (duration: 227.023649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:12:52.666911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.191039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T14:12:52.667027Z","caller":"traceutil/trace.go:171","msg":"trace[1409179532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24391; }","duration":"303.370224ms","start":"2026-09-01T14:12:52.363638Z","end":"2026-09-01T14:12:52.667008Z","steps":["trace[1409179532] 'range keys from in-memory index tree' (duration: 302.956372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:12:52.667065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:12:52.363616Z","time spent":"303.439376ms","remote":"127.0.0.1:40712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T14:12:52.667069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.367811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-01T14:12:52.667197Z","caller":"traceutil/trace.go:171","msg":"trace[706572824] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:24391; }","duration":"265.654309ms","start":"2026-09-01T14:12:52.401506Z","end":"2026-09-01T14:12:52.66716Z","steps":["trace[706572824] 'range keys from in-memory index tree' (duration: 265.134035ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:12:52.66873Z","caller":"traceutil/trace.go:171","msg":"trace[1530209640] transaction","detail":"{read_only:false; response_revision:24392; number_of_response:1; }","duration":"173.560825ms","start":"2026-09-01T14:12:52.495131Z","end":"2026-09-01T14:12:52.668692Z","steps":["trace[1530209640] 'process raft request' (duration: 173.3608ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:13:29.184655Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21780} {"level":"info","ts":"2026-09-01T14:13:29.298535Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21780,"took":"109.835166ms","hash":2717256894,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":42733568,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-01T14:13:29.29862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2717256894,"revision":21780,"compact-revision":19661} {"level":"info","ts":"2026-09-01T14:17:50.296043Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c93.wal"} {"level":"info","ts":"2026-09-01T14:18:29.195808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24786} {"level":"info","ts":"2026-09-01T14:18:29.343211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24786,"took":"143.252528ms","hash":2640283149,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":32743424,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-01T14:18:29.343278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2640283149,"revision":24786,"compact-revision":21780} {"level":"info","ts":"2026-09-01T14:22:39.950512Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T14:22:39.959183Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T14:22:39.959319Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-01T14:23:29.202714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27185} {"level":"info","ts":"2026-09-01T14:23:29.326822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27185,"took":"119.594977ms","hash":1195322507,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":29499392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T14:23:29.326901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1195322507,"revision":27185,"compact-revision":24786}