{"level":"warn","ts":"2026-08-24T15:46:19.945756Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:46:19.945833Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.249: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.19.213.249:2380","--initial-cluster=instance=https://199.19.213.249:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.249:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.249:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-24T15:46:19.945901Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:46:19.945916Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-08-24T15:46:19.945949Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:46:19.946549Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"]} {"level":"info","ts":"2026-08-24T15:46:19.946623Z","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.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.249:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-24T15:46:19.951179Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.327755ms"} {"level":"info","ts":"2026-08-24T15:46:19.957558Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5835264b86215850","cluster-id":"4e10f3807bdbbd9b"} {"level":"info","ts":"2026-08-24T15:46:19.957642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:46:19.957668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 0"} {"level":"info","ts":"2026-08-24T15:46:19.957679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5835264b86215850 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:46:19.957695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 1"} {"level":"info","ts":"2026-08-24T15:46:19.957737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"warn","ts":"2026-08-24T15:46:19.966671Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:46:19.968349Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:46:19.969383Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-24T15:46:19.972246Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5835264b86215850","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:46:19.972507Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5835264b86215850","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:46:19.972618Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:46:19.97265Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:46:19.972659Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-24T15:46:19.973314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"info","ts":"2026-08-24T15:46:19.973462Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","added-peer-id":"5835264b86215850","added-peer-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-08-24T15:46:19.978463Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-24T15:46:19.978609Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-08-24T15:46:19.978655Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-08-24T15:46:19.978903Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5835264b86215850","initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:46:19.978964Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:46:20.358048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:46:20.358097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:46:20.358127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgPreVoteResp from 5835264b86215850 at term 1"} {"level":"info","ts":"2026-08-24T15:46:20.358149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:46:20.358155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgVoteResp from 5835264b86215850 at term 2"} {"level":"info","ts":"2026-08-24T15:46:20.358165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became leader at term 2"} {"level":"info","ts":"2026-08-24T15:46:20.358174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5835264b86215850 elected leader 5835264b86215850 at term 2"} {"level":"info","ts":"2026-08-24T15:46:20.359416Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:46:20.359478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:46:20.359523Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:46:20.359421Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5835264b86215850","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.249:2379]}","request-path":"/0/members/5835264b86215850/attributes","cluster-id":"4e10f3807bdbbd9b","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:46:20.360083Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:46:20.360142Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:46:20.361489Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:46:20.361621Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:46:20.361666Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:46:20.362996Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.249:2379"} {"level":"info","ts":"2026-08-24T15:46:20.364704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:46:40.072149Z","caller":"traceutil/trace.go:171","msg":"trace[592359605] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"128.451817ms","start":"2026-08-24T15:46:39.943674Z","end":"2026-08-24T15:46:40.072126Z","steps":["trace[592359605] 'read index received' (duration: 128.217102ms)","trace[592359605] 'applied index is now lower than readState.Index' (duration: 233.865µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:46:40.072225Z","caller":"traceutil/trace.go:171","msg":"trace[1017808324] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"146.851882ms","start":"2026-08-24T15:46:39.925346Z","end":"2026-08-24T15:46:40.072198Z","steps":["trace[1017808324] 'process raft request' (duration: 146.638037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:46:40.072321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.618971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:46:40.072426Z","caller":"traceutil/trace.go:171","msg":"trace[1915916893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"128.771735ms","start":"2026-08-24T15:46:39.943634Z","end":"2026-08-24T15:46:40.072406Z","steps":["trace[1915916893] 'agreement among raft nodes before linearized reading' (duration: 128.601531ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:46:55.918866Z","caller":"traceutil/trace.go:171","msg":"trace[1091708422] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"100.475581ms","start":"2026-08-24T15:46:55.818356Z","end":"2026-08-24T15:46:55.918831Z","steps":["trace[1091708422] 'process raft request' (duration: 97.14274ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:02.001663Z","caller":"traceutil/trace.go:171","msg":"trace[30197908] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"131.724306ms","start":"2026-08-24T15:47:01.869906Z","end":"2026-08-24T15:47:02.00163Z","steps":["trace[30197908] 'process raft request' (duration: 131.02763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:02.571314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.842028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:47:02.571631Z","caller":"traceutil/trace.go:171","msg":"trace[1590577812] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"404.431764ms","start":"2026-08-24T15:47:02.167158Z","end":"2026-08-24T15:47:02.57159Z","steps":["trace[1590577812] 'process raft request' (duration: 171.833177ms)","trace[1590577812] 'compare' (duration: 231.739986ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:47:02.571784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:02.167144Z","time spent":"404.555408ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:47:02.646062Z","caller":"traceutil/trace.go:171","msg":"trace[626902310] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"118.489456ms","start":"2026-08-24T15:47:02.527522Z","end":"2026-08-24T15:47:02.646011Z","steps":["trace[626902310] 'process raft request' (duration: 114.752896ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:03.041439Z","caller":"traceutil/trace.go:171","msg":"trace[164200156] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"347.032916ms","start":"2026-08-24T15:47:02.694372Z","end":"2026-08-24T15:47:03.041405Z","steps":["trace[164200156] 'read index received' (duration: 345.163772ms)","trace[164200156] 'applied index is now lower than readState.Index' (duration: 1.867604ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:47:03.04158Z","caller":"traceutil/trace.go:171","msg":"trace[822812659] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"458.248156ms","start":"2026-08-24T15:47:02.583285Z","end":"2026-08-24T15:47:03.041533Z","steps":["trace[822812659] 'process raft request' (duration: 456.231858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:03.041742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.349043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:47:03.041795Z","caller":"traceutil/trace.go:171","msg":"trace[1968197826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"347.472346ms","start":"2026-08-24T15:47:02.694308Z","end":"2026-08-24T15:47:03.04178Z","steps":["trace[1968197826] 'agreement among raft nodes before linearized reading' (duration: 347.263211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:03.041803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:02.583261Z","time spent":"458.42756ms","remote":"127.0.0.1:51094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:47:03.041837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:02.694282Z","time spent":"347.544899ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T15:47:03.044647Z","caller":"traceutil/trace.go:171","msg":"trace[2120336897] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"338.94558ms","start":"2026-08-24T15:47:02.705675Z","end":"2026-08-24T15:47:03.044621Z","steps":["trace[2120336897] 'process raft request' (duration: 338.462497ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:03.04471Z","caller":"traceutil/trace.go:171","msg":"trace[1174976890] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"286.938483ms","start":"2026-08-24T15:47:02.757718Z","end":"2026-08-24T15:47:03.044657Z","steps":["trace[1174976890] 'process raft request' (duration: 286.578193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:03.044809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:47:02.705652Z","time spent":"339.090154ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:48:29.244972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.084184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:48:29.245075Z","caller":"traceutil/trace.go:171","msg":"trace[557464563] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"337.550839ms","start":"2026-08-24T15:48:28.907509Z","end":"2026-08-24T15:48:29.24506Z","steps":["trace[557464563] 'read index received' (duration: 86.339162ms)","trace[557464563] 'applied index is now lower than readState.Index' (duration: 251.210697ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:29.245116Z","caller":"traceutil/trace.go:171","msg":"trace[1120387588] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"369.661519ms","start":"2026-08-24T15:48:28.875422Z","end":"2026-08-24T15:48:29.245083Z","steps":["trace[1120387588] 'process raft request' (duration: 118.39493ms)","trace[1120387588] 'compare' (duration: 250.942341ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:48:29.245264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:28.8754Z","time spent":"369.787301ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:48:29.245288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.774635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-22c11b0c-bf1c-4625-81b8-94bb365da05d\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-24T15:48:29.245293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.984522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:48:29.24534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.441719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:48:29.245343Z","caller":"traceutil/trace.go:171","msg":"trace[1759764682] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-22c11b0c-bf1c-4625-81b8-94bb365da05d; range_end:; response_count:1; response_revision:1845; }","duration":"337.830857ms","start":"2026-08-24T15:48:28.907495Z","end":"2026-08-24T15:48:29.245326Z","steps":["trace[1759764682] 'agreement among raft nodes before linearized reading' (duration: 337.660743ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:29.245373Z","caller":"traceutil/trace.go:171","msg":"trace[1761655585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"295.513231ms","start":"2026-08-24T15:48:28.94985Z","end":"2026-08-24T15:48:29.245363Z","steps":["trace[1761655585] 'agreement among raft nodes before linearized reading' (duration: 295.437729ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:29.245371Z","caller":"traceutil/trace.go:171","msg":"trace[114510371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1845; }","duration":"303.108175ms","start":"2026-08-24T15:48:28.942245Z","end":"2026-08-24T15:48:29.245353Z","steps":["trace[114510371] 'agreement among raft nodes before linearized reading' (duration: 302.966962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:29.245391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:28.907477Z","time spent":"337.901038ms","remote":"127.0.0.1:51094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-22c11b0c-bf1c-4625-81b8-94bb365da05d\" "} {"level":"warn","ts":"2026-08-24T15:48:29.245387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.880951ms","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":"warn","ts":"2026-08-24T15:48:29.245417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:28.942232Z","time spent":"303.176457ms","remote":"127.0.0.1:50926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:48:29.245453Z","caller":"traceutil/trace.go:171","msg":"trace[801063862] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1845; }","duration":"229.982373ms","start":"2026-08-24T15:48:29.015454Z","end":"2026-08-24T15:48:29.245436Z","steps":["trace[801063862] 'agreement among raft nodes before linearized reading' (duration: 229.817199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:29.245491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.280916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-22c11b0c-bf1c-4625-81b8-94bb365da05d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-24T15:48:29.245509Z","caller":"traceutil/trace.go:171","msg":"trace[1689303299] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-22c11b0c-bf1c-4625-81b8-94bb365da05d; range_end:; response_count:1; response_revision:1845; }","duration":"210.323597ms","start":"2026-08-24T15:48:29.035178Z","end":"2026-08-24T15:48:29.245502Z","steps":["trace[1689303299] 'agreement among raft nodes before linearized reading' (duration: 210.264515ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:41.509925Z","caller":"traceutil/trace.go:171","msg":"trace[310843948] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"176.223741ms","start":"2026-08-24T15:48:41.333683Z","end":"2026-08-24T15:48:41.509907Z","steps":["trace[310843948] 'read index received' (duration: 176.057447ms)","trace[310843948] 'applied index is now lower than readState.Index' (duration: 165.384µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:41.510014Z","caller":"traceutil/trace.go:171","msg":"trace[416367985] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"219.739695ms","start":"2026-08-24T15:48:41.290267Z","end":"2026-08-24T15:48:41.510007Z","steps":["trace[416367985] 'process raft request' (duration: 219.52682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:41.510459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.761285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:48:41.510506Z","caller":"traceutil/trace.go:171","msg":"trace[638616616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1951; }","duration":"176.853007ms","start":"2026-08-24T15:48:41.333641Z","end":"2026-08-24T15:48:41.510494Z","steps":["trace[638616616] 'agreement among raft nodes before linearized reading' (duration: 176.705004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:41.510458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.798769ms","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-08-24T15:48:41.510618Z","caller":"traceutil/trace.go:171","msg":"trace[1620324518] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1951; }","duration":"144.982694ms","start":"2026-08-24T15:48:41.365625Z","end":"2026-08-24T15:48:41.510608Z","steps":["trace[1620324518] 'agreement among raft nodes before linearized reading' (duration: 144.497992ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:19.829037Z","caller":"traceutil/trace.go:171","msg":"trace[170075187] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"141.199835ms","start":"2026-08-24T15:49:19.68781Z","end":"2026-08-24T15:49:19.829009Z","steps":["trace[170075187] 'read index received' (duration: 140.974219ms)","trace[170075187] 'applied index is now lower than readState.Index' (duration: 224.846µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:49:19.829126Z","caller":"traceutil/trace.go:171","msg":"trace[115065268] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"197.588563ms","start":"2026-08-24T15:49:19.631488Z","end":"2026-08-24T15:49:19.829077Z","steps":["trace[115065268] 'process raft request' (duration: 197.362928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:49:19.829338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.139442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T15:49:19.829412Z","caller":"traceutil/trace.go:171","msg":"trace[823222597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2173; }","duration":"110.259654ms","start":"2026-08-24T15:49:19.719137Z","end":"2026-08-24T15:49:19.829397Z","steps":["trace[823222597] 'agreement among raft nodes before linearized reading' (duration: 110.046529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:49:19.829768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.962583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-24T15:49:19.829845Z","caller":"traceutil/trace.go:171","msg":"trace[1216491294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2173; }","duration":"142.077156ms","start":"2026-08-24T15:49:19.687751Z","end":"2026-08-24T15:49:19.829828Z","steps":["trace[1216491294] 'agreement among raft nodes before linearized reading' (duration: 141.937763ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:50:50.720912Z","caller":"traceutil/trace.go:171","msg":"trace[1225256096] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"124.34591ms","start":"2026-08-24T15:50:50.596541Z","end":"2026-08-24T15:50:50.720887Z","steps":["trace[1225256096] 'process raft request' (duration: 124.204587ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:53:11.009309Z","caller":"traceutil/trace.go:171","msg":"trace[603640344] transaction","detail":"{read_only:false; response_revision:3799; number_of_response:1; }","duration":"109.27611ms","start":"2026-08-24T15:53:10.900007Z","end":"2026-08-24T15:53:11.009283Z","steps":["trace[603640344] 'process raft request' (duration: 109.052224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:54:35.094431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.949307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16084"} {"level":"info","ts":"2026-08-24T15:54:35.094555Z","caller":"traceutil/trace.go:171","msg":"trace[290615510] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4855; }","duration":"124.099291ms","start":"2026-08-24T15:54:34.970431Z","end":"2026-08-24T15:54:35.09453Z","steps":["trace[290615510] 'range keys from in-memory index tree' (duration: 123.66979ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:37.857262Z","caller":"traceutil/trace.go:171","msg":"trace[1217072063] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"103.269806ms","start":"2026-08-24T15:54:37.753973Z","end":"2026-08-24T15:54:37.857243Z","steps":["trace[1217072063] 'read index received' (duration: 103.164333ms)","trace[1217072063] 'applied index is now lower than readState.Index' (duration: 104.723µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:54:37.85733Z","caller":"traceutil/trace.go:171","msg":"trace[999742303] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"105.655834ms","start":"2026-08-24T15:54:37.751635Z","end":"2026-08-24T15:54:37.857291Z","steps":["trace[999742303] 'process raft request' (duration: 105.4851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:54:37.857385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.390598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:54:37.857424Z","caller":"traceutil/trace.go:171","msg":"trace[512264098] range","detail":"{range_begin:/registry/configmaps/monitoring/vector; range_end:; response_count:0; response_revision:4985; }","duration":"103.477851ms","start":"2026-08-24T15:54:37.753935Z","end":"2026-08-24T15:54:37.857413Z","steps":["trace[512264098] 'agreement among raft nodes before linearized reading' (duration: 103.400649ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:38.042305Z","caller":"traceutil/trace.go:171","msg":"trace[1249818724] linearizableReadLoop","detail":"{readStateIndex:5173; appliedIndex:5172; }","duration":"107.59652ms","start":"2026-08-24T15:54:37.934675Z","end":"2026-08-24T15:54:38.042272Z","steps":["trace[1249818724] 'read index received' (duration: 37.0906ms)","trace[1249818724] 'applied index is now lower than readState.Index' (duration: 70.50252ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:54:38.042385Z","caller":"traceutil/trace.go:171","msg":"trace[1288495805] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4985; }","duration":"163.898446ms","start":"2026-08-24T15:54:37.878422Z","end":"2026-08-24T15:54:38.042321Z","steps":["trace[1288495805] 'process raft request' (duration: 93.362316ms)","trace[1288495805] 'compare' (duration: 70.427228ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:54:38.042701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.99358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-24T15:54:38.042819Z","caller":"traceutil/trace.go:171","msg":"trace[923253856] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4985; }","duration":"108.195715ms","start":"2026-08-24T15:54:37.934604Z","end":"2026-08-24T15:54:38.0428Z","steps":["trace[923253856] 'agreement among raft nodes before linearized reading' (duration: 107.770155ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:51.939401Z","caller":"traceutil/trace.go:171","msg":"trace[1860547019] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"107.645431ms","start":"2026-08-24T15:54:51.831722Z","end":"2026-08-24T15:54:51.939368Z","steps":["trace[1860547019] 'process raft request' (duration: 107.366623ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:52.066117Z","caller":"traceutil/trace.go:171","msg":"trace[901893342] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"123.991386ms","start":"2026-08-24T15:54:51.942097Z","end":"2026-08-24T15:54:52.066088Z","steps":["trace[901893342] 'process raft request' (duration: 98.58188ms)","trace[901893342] 'compare' (duration: 25.241272ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:54:52.080833Z","caller":"traceutil/trace.go:171","msg":"trace[1262259395] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"132.81249ms","start":"2026-08-24T15:54:51.948003Z","end":"2026-08-24T15:54:52.080816Z","steps":["trace[1262259395] 'process raft request' (duration: 132.694987ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:52.44081Z","caller":"traceutil/trace.go:171","msg":"trace[860964200] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"277.887938ms","start":"2026-08-24T15:54:52.162898Z","end":"2026-08-24T15:54:52.440786Z","steps":["trace[860964200] 'process raft request' (duration: 267.68154ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:52.441121Z","caller":"traceutil/trace.go:171","msg":"trace[539738233] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"277.695873ms","start":"2026-08-24T15:54:52.163379Z","end":"2026-08-24T15:54:52.441075Z","steps":["trace[539738233] 'process raft request' (duration: 277.327794ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:52.441157Z","caller":"traceutil/trace.go:171","msg":"trace[1515836097] linearizableReadLoop","detail":"{readStateIndex:5459; appliedIndex:5455; }","duration":"111.538444ms","start":"2026-08-24T15:54:52.329595Z","end":"2026-08-24T15:54:52.441133Z","steps":["trace[1515836097] 'read index received' (duration: 100.996278ms)","trace[1515836097] 'applied index is now lower than readState.Index' (duration: 10.541366ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:54:52.441285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.672588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:54:52.441336Z","caller":"traceutil/trace.go:171","msg":"trace[1756376432] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5262; }","duration":"111.77321ms","start":"2026-08-24T15:54:52.329552Z","end":"2026-08-24T15:54:52.441326Z","steps":["trace[1756376432] 'agreement among raft nodes before linearized reading' (duration: 111.652737ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:52.441335Z","caller":"traceutil/trace.go:171","msg":"trace[570317614] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"277.833225ms","start":"2026-08-24T15:54:52.163487Z","end":"2026-08-24T15:54:52.44132Z","steps":["trace[570317614] 'process raft request' (duration: 277.59475ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:54:52.441346Z","caller":"traceutil/trace.go:171","msg":"trace[294362779] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"277.933278ms","start":"2026-08-24T15:54:52.163379Z","end":"2026-08-24T15:54:52.441312Z","steps":["trace[294362779] 'process raft request' (duration: 277.559209ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:56:20.470569Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-08-24T15:56:20.560171Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"87.873138ms","hash":3806913126,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":32243712,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T15:56:20.560229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3806913126,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-08-24T16:01:15.189672Z","caller":"traceutil/trace.go:171","msg":"trace[1802180236] transaction","detail":"{read_only:false; response_revision:8298; number_of_response:1; }","duration":"110.49095ms","start":"2026-08-24T16:01:15.079141Z","end":"2026-08-24T16:01:15.189632Z","steps":["trace[1802180236] 'process raft request' (duration: 110.07565ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:01:20.479462Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6161} {"level":"info","ts":"2026-08-24T16:01:20.581355Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6161,"took":"100.432546ms","hash":2582144798,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":23511040,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-24T16:01:20.581412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2582144798,"revision":6161,"compact-revision":3020} {"level":"warn","ts":"2026-08-24T16:01:26.662232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.124199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:01:26.662336Z","caller":"traceutil/trace.go:171","msg":"trace[1105580871] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8357; }","duration":"199.279623ms","start":"2026-08-24T16:01:26.463031Z","end":"2026-08-24T16:01:26.66231Z","steps":["trace[1105580871] 'count revisions from in-memory index tree' (duration: 199.006556ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:01:27.405553Z","caller":"traceutil/trace.go:171","msg":"trace[1381039761] transaction","detail":"{read_only:false; response_revision:8361; number_of_response:1; }","duration":"171.744255ms","start":"2026-08-24T16:01:27.23372Z","end":"2026-08-24T16:01:27.405464Z","steps":["trace[1381039761] 'process raft request' (duration: 171.600722ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:01:57.127539Z","caller":"traceutil/trace.go:171","msg":"trace[689910956] linearizableReadLoop","detail":"{readStateIndex:8876; appliedIndex:8875; }","duration":"195.779688ms","start":"2026-08-24T16:01:56.931741Z","end":"2026-08-24T16:01:57.12752Z","steps":["trace[689910956] 'read index received' (duration: 195.667595ms)","trace[689910956] 'applied index is now lower than readState.Index' (duration: 111.003µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:01:57.127595Z","caller":"traceutil/trace.go:171","msg":"trace[1503364281] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"280.101493ms","start":"2026-08-24T16:01:56.84746Z","end":"2026-08-24T16:01:57.127562Z","steps":["trace[1503364281] 'process raft request' (duration: 279.944599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:01:57.127692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.935862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6hq9j\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-24T16:01:57.127719Z","caller":"traceutil/trace.go:171","msg":"trace[1432486907] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6hq9j; range_end:; response_count:1; response_revision:8574; }","duration":"196.003124ms","start":"2026-08-24T16:01:56.931707Z","end":"2026-08-24T16:01:57.127711Z","steps":["trace[1432486907] 'agreement among raft nodes before linearized reading' (duration: 195.887391ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:01:57.140758Z","caller":"traceutil/trace.go:171","msg":"trace[942076334] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"120.692058ms","start":"2026-08-24T16:01:57.020038Z","end":"2026-08-24T16:01:57.14073Z","steps":["trace[942076334] 'process raft request' (duration: 120.547235ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:01:57.140785Z","caller":"traceutil/trace.go:171","msg":"trace[543003418] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"108.933502ms","start":"2026-08-24T16:01:57.031826Z","end":"2026-08-24T16:01:57.14076Z","steps":["trace[543003418] 'process raft request' (duration: 108.836399ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:10.542098Z","caller":"traceutil/trace.go:171","msg":"trace[270827161] linearizableReadLoop","detail":"{readStateIndex:9000; appliedIndex:8999; }","duration":"109.668439ms","start":"2026-08-24T16:02:10.4324Z","end":"2026-08-24T16:02:10.542069Z","steps":["trace[270827161] 'read index received' (duration: 109.449724ms)","trace[270827161] 'applied index is now lower than readState.Index' (duration: 217.665µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:02:10.542309Z","caller":"traceutil/trace.go:171","msg":"trace[946732538] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"249.807078ms","start":"2026-08-24T16:02:10.292462Z","end":"2026-08-24T16:02:10.54227Z","steps":["trace[946732538] 'process raft request' (duration: 249.397088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:10.542369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.922785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T16:02:10.542437Z","caller":"traceutil/trace.go:171","msg":"trace[1617806552] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:8692; }","duration":"110.05223ms","start":"2026-08-24T16:02:10.432364Z","end":"2026-08-24T16:02:10.542417Z","steps":["trace[1617806552] 'agreement among raft nodes before linearized reading' (duration: 109.816483ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:11.538268Z","caller":"traceutil/trace.go:171","msg":"trace[969913741] linearizableReadLoop","detail":"{readStateIndex:9006; appliedIndex:9005; }","duration":"260.18511ms","start":"2026-08-24T16:02:11.278064Z","end":"2026-08-24T16:02:11.538249Z","steps":["trace[969913741] 'read index received' (duration: 223.563252ms)","trace[969913741] 'applied index is now lower than readState.Index' (duration: 36.621188ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:02:11.538563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.21775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:02:11.5386Z","caller":"traceutil/trace.go:171","msg":"trace[834937008] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8698; }","duration":"201.315032ms","start":"2026-08-24T16:02:11.337277Z","end":"2026-08-24T16:02:11.538592Z","steps":["trace[834937008] 'agreement among raft nodes before linearized reading' (duration: 201.23755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:11.538633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.562409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-08-24T16:02:11.538648Z","caller":"traceutil/trace.go:171","msg":"trace[193797734] transaction","detail":"{read_only:false; response_revision:8698; number_of_response:1; }","duration":"264.110474ms","start":"2026-08-24T16:02:11.274505Z","end":"2026-08-24T16:02:11.538615Z","steps":["trace[193797734] 'process raft request' (duration: 227.052956ms)","trace[193797734] 'compare' (duration: 36.607648ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:02:11.538743Z","caller":"traceutil/trace.go:171","msg":"trace[1395342770] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8698; }","duration":"260.740843ms","start":"2026-08-24T16:02:11.277986Z","end":"2026-08-24T16:02:11.538727Z","steps":["trace[1395342770] 'agreement among raft nodes before linearized reading' (duration: 260.439976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:11.538659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.752507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-24T16:02:11.538856Z","caller":"traceutil/trace.go:171","msg":"trace[1095385082] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8698; }","duration":"137.000622ms","start":"2026-08-24T16:02:11.401819Z","end":"2026-08-24T16:02:11.53882Z","steps":["trace[1095385082] 'agreement among raft nodes before linearized reading' (duration: 136.746266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:12.080779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.122304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-08-24T16:02:12.080842Z","caller":"traceutil/trace.go:171","msg":"trace[2002735424] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8703; }","duration":"200.214496ms","start":"2026-08-24T16:02:11.880613Z","end":"2026-08-24T16:02:12.080827Z","steps":["trace[2002735424] 'range keys from in-memory index tree' (duration: 199.980131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:12.080875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.340467ms","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-08-24T16:02:12.080901Z","caller":"traceutil/trace.go:171","msg":"trace[643806815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8703; }","duration":"136.366967ms","start":"2026-08-24T16:02:11.944527Z","end":"2026-08-24T16:02:12.080894Z","steps":["trace[643806815] 'range keys from in-memory index tree' (duration: 136.251935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:02:12.081014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.780919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"info","ts":"2026-08-24T16:02:12.081111Z","caller":"traceutil/trace.go:171","msg":"trace[449930898] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8703; }","duration":"207.907742ms","start":"2026-08-24T16:02:11.873181Z","end":"2026-08-24T16:02:12.081089Z","steps":["trace[449930898] 'range keys from in-memory index tree' (duration: 207.658036ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:12.412917Z","caller":"traceutil/trace.go:171","msg":"trace[329953106] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"276.768132ms","start":"2026-08-24T16:02:12.136117Z","end":"2026-08-24T16:02:12.412885Z","steps":["trace[329953106] 'process raft request' (duration: 276.550317ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:12.413002Z","caller":"traceutil/trace.go:171","msg":"trace[1395837450] linearizableReadLoop","detail":"{readStateIndex:9014; appliedIndex:9013; }","duration":"246.704023ms","start":"2026-08-24T16:02:12.166273Z","end":"2026-08-24T16:02:12.412978Z","steps":["trace[1395837450] 'read index received' (duration: 246.335874ms)","trace[1395837450] 'applied index is now lower than readState.Index' (duration: 364.919µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:02:12.413105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.820236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:02:12.413142Z","caller":"traceutil/trace.go:171","msg":"trace[1107555629] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8706; }","duration":"246.896397ms","start":"2026-08-24T16:02:12.16623Z","end":"2026-08-24T16:02:12.413127Z","steps":["trace[1107555629] 'agreement among raft nodes before linearized reading' (duration: 246.816085ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:02:25.225685Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c4.wal"} {"level":"info","ts":"2026-08-24T16:03:11.076594Z","caller":"traceutil/trace.go:171","msg":"trace[1857918250] transaction","detail":"{read_only:false; response_revision:9346; number_of_response:1; }","duration":"107.213615ms","start":"2026-08-24T16:03:10.969353Z","end":"2026-08-24T16:03:11.076567Z","steps":["trace[1857918250] 'process raft request' (duration: 106.98276ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:03:58.310732Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T16:03:58.317115Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-24T16:03:58.317278Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-24T16:06:20.485948Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8324} {"level":"info","ts":"2026-08-24T16:06:20.568969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8324,"took":"81.13836ms","hash":536242923,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":26427392,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-24T16:06:20.569032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":536242923,"revision":8324,"compact-revision":6161} {"level":"info","ts":"2026-08-24T16:07:10.463115Z","caller":"traceutil/trace.go:171","msg":"trace[276625097] transaction","detail":"{read_only:false; response_revision:11287; number_of_response:1; }","duration":"150.829267ms","start":"2026-08-24T16:07:10.312252Z","end":"2026-08-24T16:07:10.463081Z","steps":["trace[276625097] 'process raft request' (duration: 57.727685ms)","trace[276625097] 'compare' (duration: 92.433216ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:11:20.492721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10815} {"level":"info","ts":"2026-08-24T16:11:20.580653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10815,"took":"83.349588ms","hash":1523974614,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":27025408,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-24T16:11:20.5808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1523974614,"revision":10815,"compact-revision":8324} {"level":"info","ts":"2026-08-24T16:13:09.126781Z","caller":"traceutil/trace.go:171","msg":"trace[2138209107] transaction","detail":"{read_only:false; response_revision:13720; number_of_response:1; }","duration":"113.596371ms","start":"2026-08-24T16:13:09.013161Z","end":"2026-08-24T16:13:09.126757Z","steps":["trace[2138209107] 'process raft request' (duration: 113.386536ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:13:31.08649Z","caller":"traceutil/trace.go:171","msg":"trace[986169361] transaction","detail":"{read_only:false; response_revision:13838; number_of_response:1; }","duration":"119.870561ms","start":"2026-08-24T16:13:30.966588Z","end":"2026-08-24T16:13:31.086459Z","steps":["trace[986169361] 'process raft request' (duration: 119.590805ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:13:47.436121Z","caller":"traceutil/trace.go:171","msg":"trace[1098637561] transaction","detail":"{read_only:false; response_revision:13939; number_of_response:1; }","duration":"145.486293ms","start":"2026-08-24T16:13:47.290605Z","end":"2026-08-24T16:13:47.436091Z","steps":["trace[1098637561] 'process raft request' (duration: 55.227468ms)","trace[1098637561] 'compare' (duration: 90.05046ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:13:47.437896Z","caller":"traceutil/trace.go:171","msg":"trace[1864919550] transaction","detail":"{read_only:false; response_revision:13940; number_of_response:1; }","duration":"142.063278ms","start":"2026-08-24T16:13:47.295814Z","end":"2026-08-24T16:13:47.437877Z","steps":["trace[1864919550] 'process raft request' (duration: 141.927665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:13:48.416865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.253637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T16:13:48.416997Z","caller":"traceutil/trace.go:171","msg":"trace[1961956503] linearizableReadLoop","detail":"{readStateIndex:14424; appliedIndex:14423; }","duration":"380.059262ms","start":"2026-08-24T16:13:48.036914Z","end":"2026-08-24T16:13:48.416973Z","steps":["trace[1961956503] 'read index received' (duration: 215.524338ms)","trace[1961956503] 'applied index is now lower than readState.Index' (duration: 164.532544ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:13:48.417095Z","caller":"traceutil/trace.go:171","msg":"trace[932217075] transaction","detail":"{read_only:false; response_revision:13945; number_of_response:1; }","duration":"427.011238ms","start":"2026-08-24T16:13:47.990043Z","end":"2026-08-24T16:13:48.417054Z","steps":["trace[932217075] 'process raft request' (duration: 262.413633ms)","trace[932217075] 'compare' (duration: 163.877818ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:13:48.417157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.233996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-xlt8v\" ","response":"range_response_count:1 size:11628"} {"level":"info","ts":"2026-08-24T16:13:48.417219Z","caller":"traceutil/trace.go:171","msg":"trace[1194334328] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-xlt8v; range_end:; response_count:1; response_revision:13945; }","duration":"380.304128ms","start":"2026-08-24T16:13:48.036903Z","end":"2026-08-24T16:13:48.417207Z","steps":["trace[1194334328] 'agreement among raft nodes before linearized reading' (duration: 380.150934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:13:48.417248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:13:48.036856Z","time spent":"380.3879ms","remote":"127.0.0.1:51094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11650,"request content":"key:\"/registry/pods/openstack/openvswitch-xlt8v\" "} {"level":"warn","ts":"2026-08-24T16:13:48.417252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:13:47.990019Z","time spent":"427.142151ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:13:48.423341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.119114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:13:48.423383Z","caller":"traceutil/trace.go:171","msg":"trace[1854546374] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:13946; }","duration":"100.193236ms","start":"2026-08-24T16:13:48.323182Z","end":"2026-08-24T16:13:48.423375Z","steps":["trace[1854546374] 'agreement among raft nodes before linearized reading' (duration: 100.123534ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:13:48.423353Z","caller":"traceutil/trace.go:171","msg":"trace[386456414] transaction","detail":"{read_only:false; response_revision:13946; number_of_response:1; }","duration":"276.525185ms","start":"2026-08-24T16:13:48.14678Z","end":"2026-08-24T16:13:48.423305Z","steps":["trace[386456414] 'process raft request' (duration: 276.282069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:13:48.423629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.369503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:13:48.423696Z","caller":"traceutil/trace.go:171","msg":"trace[2038428130] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:13946; }","duration":"267.476575ms","start":"2026-08-24T16:13:48.156204Z","end":"2026-08-24T16:13:48.42368Z","steps":["trace[2038428130] 'agreement among raft nodes before linearized reading' (duration: 267.298581ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:13:50.118581Z","caller":"traceutil/trace.go:171","msg":"trace[2126143178] linearizableReadLoop","detail":"{readStateIndex:14439; appliedIndex:14438; }","duration":"146.689491ms","start":"2026-08-24T16:13:49.971872Z","end":"2026-08-24T16:13:50.118562Z","steps":["trace[2126143178] 'read index received' (duration: 146.65826ms)","trace[2126143178] 'applied index is now lower than readState.Index' (duration: 30.381µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:13:50.118644Z","caller":"traceutil/trace.go:171","msg":"trace[1854968290] transaction","detail":"{read_only:false; response_revision:13959; number_of_response:1; }","duration":"163.388755ms","start":"2026-08-24T16:13:49.955233Z","end":"2026-08-24T16:13:50.118622Z","steps":["trace[1854968290] 'process raft request' (duration: 163.200171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:13:50.118739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.842535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:13:50.118818Z","caller":"traceutil/trace.go:171","msg":"trace[1865994344] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:13959; }","duration":"146.976538ms","start":"2026-08-24T16:13:49.971826Z","end":"2026-08-24T16:13:50.118802Z","steps":["trace[1865994344] 'agreement among raft nodes before linearized reading' (duration: 146.816665ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:13:57.930308Z","caller":"traceutil/trace.go:171","msg":"trace[1632177245] transaction","detail":"{read_only:false; response_revision:14115; number_of_response:1; }","duration":"198.521876ms","start":"2026-08-24T16:13:57.73176Z","end":"2026-08-24T16:13:57.930282Z","steps":["trace[1632177245] 'process raft request' (duration: 198.346902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:13:58.083521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.967238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:13:58.083619Z","caller":"traceutil/trace.go:171","msg":"trace[2059390282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14115; }","duration":"109.132182ms","start":"2026-08-24T16:13:57.974464Z","end":"2026-08-24T16:13:58.083596Z","steps":["trace[2059390282] 'count revisions from in-memory index tree' (duration: 108.849296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.241548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.698808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-24T16:15:36.241655Z","caller":"traceutil/trace.go:171","msg":"trace[1296251053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15193; }","duration":"300.853672ms","start":"2026-08-24T16:15:35.940779Z","end":"2026-08-24T16:15:36.241633Z","steps":["trace[1296251053] 'range keys from in-memory index tree' (duration: 300.39039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.241702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:15:35.940745Z","time spent":"300.944194ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T16:15:36.243814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.611509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-24T16:15:36.243882Z","caller":"traceutil/trace.go:171","msg":"trace[1145058250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15193; }","duration":"140.694612ms","start":"2026-08-24T16:15:36.103172Z","end":"2026-08-24T16:15:36.243866Z","steps":["trace[1145058250] 'range keys from in-memory index tree' (duration: 140.506788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.243923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.607828ms","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-08-24T16:15:36.24437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.131668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-08-24T16:15:36.244379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.400152ms","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-08-24T16:15:36.244402Z","caller":"traceutil/trace.go:171","msg":"trace[1399851444] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15193; }","duration":"251.167749ms","start":"2026-08-24T16:15:35.993223Z","end":"2026-08-24T16:15:36.244391Z","steps":["trace[1399851444] 'range keys from in-memory index tree' (duration: 251.070327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.244388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.176641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-24T16:15:36.244411Z","caller":"traceutil/trace.go:171","msg":"trace[1856847886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15193; }","duration":"295.109011ms","start":"2026-08-24T16:15:35.949277Z","end":"2026-08-24T16:15:36.244386Z","steps":["trace[1856847886] 'range keys from in-memory index tree' (duration: 294.506866ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.244443Z","caller":"traceutil/trace.go:171","msg":"trace[1721611195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15193; }","duration":"294.459703ms","start":"2026-08-24T16:15:35.949957Z","end":"2026-08-24T16:15:36.244417Z","steps":["trace[1721611195] 'range keys from in-memory index tree' (duration: 294.30508ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.244452Z","caller":"traceutil/trace.go:171","msg":"trace[978429752] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15193; }","duration":"269.266134ms","start":"2026-08-24T16:15:35.975167Z","end":"2026-08-24T16:15:36.244433Z","steps":["trace[978429752] 'range keys from in-memory index tree' (duration: 269.055379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.244416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.158027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T16:15:36.243809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.149465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bcrws\" ","response":"range_response_count:1 size:22014"} {"level":"info","ts":"2026-08-24T16:15:36.244519Z","caller":"traceutil/trace.go:171","msg":"trace[1463897930] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15193; }","duration":"270.463084ms","start":"2026-08-24T16:15:35.974031Z","end":"2026-08-24T16:15:36.244494Z","steps":["trace[1463897930] 'range keys from in-memory index tree' (duration: 270.078344ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.244623Z","caller":"traceutil/trace.go:171","msg":"trace[1404292871] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bcrws; range_end:; response_count:1; response_revision:15193; }","duration":"158.276654ms","start":"2026-08-24T16:15:36.08627Z","end":"2026-08-24T16:15:36.244546Z","steps":["trace[1404292871] 'range keys from in-memory index tree' (duration: 156.278975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.244252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.23396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:15:36.244675Z","caller":"traceutil/trace.go:171","msg":"trace[1066121543] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15193; }","duration":"251.657972ms","start":"2026-08-24T16:15:35.993007Z","end":"2026-08-24T16:15:36.244665Z","steps":["trace[1066121543] 'range keys from in-memory index tree' (duration: 251.166359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.244226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.088315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:15:36.244751Z","caller":"traceutil/trace.go:171","msg":"trace[696386230] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15193; }","duration":"270.693569ms","start":"2026-08-24T16:15:35.974047Z","end":"2026-08-24T16:15:36.244741Z","steps":["trace[696386230] 'range keys from in-memory index tree' (duration: 269.976493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.244274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.339064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-24T16:15:36.244834Z","caller":"traceutil/trace.go:171","msg":"trace[547126344] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15193; }","duration":"251.934787ms","start":"2026-08-24T16:15:35.99289Z","end":"2026-08-24T16:15:36.244824Z","steps":["trace[547126344] 'range keys from in-memory index tree' (duration: 251.18211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.244303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.082049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bcrws\" ","response":"range_response_count:1 size:22014"} {"level":"info","ts":"2026-08-24T16:15:36.244904Z","caller":"traceutil/trace.go:171","msg":"trace[554604440] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bcrws; range_end:; response_count:1; response_revision:15193; }","duration":"184.708384ms","start":"2026-08-24T16:15:36.060188Z","end":"2026-08-24T16:15:36.244896Z","steps":["trace[554604440] 'range keys from in-memory index tree' (duration: 183.970746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.244345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.441034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:15:36.244992Z","caller":"traceutil/trace.go:171","msg":"trace[866871691] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15193; }","duration":"271.1095ms","start":"2026-08-24T16:15:35.973865Z","end":"2026-08-24T16:15:36.244974Z","steps":["trace[866871691] 'range keys from in-memory index tree' (duration: 270.164537ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.401508Z","caller":"traceutil/trace.go:171","msg":"trace[571408772] transaction","detail":"{read_only:false; response_revision:15194; number_of_response:1; }","duration":"152.587884ms","start":"2026-08-24T16:15:36.248902Z","end":"2026-08-24T16:15:36.40149Z","steps":["trace[571408772] 'process raft request' (duration: 152.455511ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.48014Z","caller":"traceutil/trace.go:171","msg":"trace[2023468618] transaction","detail":"{read_only:false; response_revision:15195; number_of_response:1; }","duration":"231.068755ms","start":"2026-08-24T16:15:36.249052Z","end":"2026-08-24T16:15:36.48012Z","steps":["trace[2023468618] 'process raft request' (duration: 218.024543ms)","trace[2023468618] 'compare' (duration: 12.918229ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:15:36.480279Z","caller":"traceutil/trace.go:171","msg":"trace[1156493154] transaction","detail":"{read_only:false; response_revision:15196; number_of_response:1; }","duration":"231.108926ms","start":"2026-08-24T16:15:36.249158Z","end":"2026-08-24T16:15:36.480267Z","steps":["trace[1156493154] 'process raft request' (duration: 230.930922ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.480338Z","caller":"traceutil/trace.go:171","msg":"trace[1975329874] transaction","detail":"{read_only:false; response_revision:15197; number_of_response:1; }","duration":"225.125068ms","start":"2026-08-24T16:15:36.255205Z","end":"2026-08-24T16:15:36.48033Z","steps":["trace[1975329874] 'process raft request' (duration: 225.000045ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.480547Z","caller":"traceutil/trace.go:171","msg":"trace[2142982844] transaction","detail":"{read_only:false; response_revision:15198; number_of_response:1; }","duration":"224.171175ms","start":"2026-08-24T16:15:36.256354Z","end":"2026-08-24T16:15:36.480525Z","steps":["trace[2142982844] 'process raft request' (duration: 223.891538ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:15:36.480597Z","caller":"traceutil/trace.go:171","msg":"trace[1501386104] linearizableReadLoop","detail":"{readStateIndex:15714; appliedIndex:15712; }","duration":"226.101163ms","start":"2026-08-24T16:15:36.254399Z","end":"2026-08-24T16:15:36.4805Z","steps":["trace[1501386104] 'read index received' (duration: 147.52073ms)","trace[1501386104] 'applied index is now lower than readState.Index' (duration: 78.579803ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:15:36.480657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.243326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T16:15:36.480695Z","caller":"traceutil/trace.go:171","msg":"trace[573000175] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15198; }","duration":"226.306577ms","start":"2026-08-24T16:15:36.254379Z","end":"2026-08-24T16:15:36.480685Z","steps":["trace[573000175] 'agreement among raft nodes before linearized reading' (duration: 226.240206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.480741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.242356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-08-24T16:15:36.480765Z","caller":"traceutil/trace.go:171","msg":"trace[1262208103] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15198; }","duration":"226.293367ms","start":"2026-08-24T16:15:36.254465Z","end":"2026-08-24T16:15:36.480759Z","steps":["trace[1262208103] 'agreement among raft nodes before linearized reading' (duration: 226.203445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.48093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.438825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-08-24T16:15:36.480976Z","caller":"traceutil/trace.go:171","msg":"trace[2134201863] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15198; }","duration":"225.499007ms","start":"2026-08-24T16:15:36.255464Z","end":"2026-08-24T16:15:36.480963Z","steps":["trace[2134201863] 'agreement among raft nodes before linearized reading' (duration: 225.384864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.481299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.109648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764243"} {"level":"info","ts":"2026-08-24T16:15:36.481341Z","caller":"traceutil/trace.go:171","msg":"trace[1664098787] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15198; }","duration":"223.161529ms","start":"2026-08-24T16:15:36.258166Z","end":"2026-08-24T16:15:36.481328Z","steps":["trace[1664098787] 'agreement among raft nodes before linearized reading' (duration: 222.702938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:15:36.48139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.124664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:764243"} {"level":"info","ts":"2026-08-24T16:15:36.481411Z","caller":"traceutil/trace.go:171","msg":"trace[2067221991] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15198; }","duration":"222.158085ms","start":"2026-08-24T16:15:36.259248Z","end":"2026-08-24T16:15:36.481406Z","steps":["trace[2067221991] 'agreement among raft nodes before linearized reading' (duration: 221.563311ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:16:20.498633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12887} {"level":"info","ts":"2026-08-24T16:16:20.576213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12887,"took":"75.652911ms","hash":106695993,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":30871552,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T16:16:20.576258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":106695993,"revision":12887,"compact-revision":10815} {"level":"info","ts":"2026-08-24T16:21:20.508194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15484} {"level":"info","ts":"2026-08-24T16:21:20.611678Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15484,"took":"100.477063ms","hash":1334243414,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":32428032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T16:21:20.61174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1334243414,"revision":15484,"compact-revision":12887} {"level":"info","ts":"2026-08-24T16:21:22.082931Z","caller":"traceutil/trace.go:171","msg":"trace[2065319959] transaction","detail":"{read_only:false; response_revision:17837; number_of_response:1; }","duration":"403.217535ms","start":"2026-08-24T16:21:21.679694Z","end":"2026-08-24T16:21:22.082911Z","steps":["trace[2065319959] 'process raft request' (duration: 403.100802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:21:22.08306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:21:21.679662Z","time spent":"403.334098ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:21:22.121002Z","caller":"traceutil/trace.go:171","msg":"trace[1213599950] linearizableReadLoop","detail":"{readStateIndex:18444; appliedIndex:18443; }","duration":"178.384094ms","start":"2026-08-24T16:21:21.942592Z","end":"2026-08-24T16:21:22.120976Z","steps":["trace[1213599950] 'read index received' (duration: 140.831738ms)","trace[1213599950] 'applied index is now lower than readState.Index' (duration: 37.551726ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:21:22.121119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.510897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:21:22.12114Z","caller":"traceutil/trace.go:171","msg":"trace[356496005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17838; }","duration":"178.576309ms","start":"2026-08-24T16:21:21.942557Z","end":"2026-08-24T16:21:22.121133Z","steps":["trace[356496005] 'agreement among raft nodes before linearized reading' (duration: 178.503857ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:21:22.121133Z","caller":"traceutil/trace.go:171","msg":"trace[771464887] transaction","detail":"{read_only:false; response_revision:17838; number_of_response:1; }","duration":"284.798453ms","start":"2026-08-24T16:21:21.836287Z","end":"2026-08-24T16:21:22.121086Z","steps":["trace[771464887] 'process raft request' (duration: 284.575338ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:24:34.804077Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T16:24:34.808633Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-24T16:24:34.80875Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-24T16:26:20.516024Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17830} {"level":"info","ts":"2026-08-24T16:26:20.612114Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17830,"took":"93.178085ms","hash":4234870047,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":34508800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-24T16:26:20.612189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4234870047,"revision":17830,"compact-revision":15484} {"level":"warn","ts":"2026-08-24T16:27:10.639901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.464831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:27:10.63998Z","caller":"traceutil/trace.go:171","msg":"trace[1346394563] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:20443; }","duration":"120.590554ms","start":"2026-08-24T16:27:10.519376Z","end":"2026-08-24T16:27:10.639967Z","steps":["trace[1346394563] 'count revisions from in-memory index tree' (duration: 120.388859ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:27:16.33084Z","caller":"traceutil/trace.go:171","msg":"trace[1273708323] transaction","detail":"{read_only:false; response_revision:20472; number_of_response:1; }","duration":"111.00451ms","start":"2026-08-24T16:27:16.219808Z","end":"2026-08-24T16:27:16.330813Z","steps":["trace[1273708323] 'process raft request' (duration: 110.828916ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:27:16.33515Z","caller":"traceutil/trace.go:171","msg":"trace[390587058] transaction","detail":"{read_only:false; response_revision:20473; number_of_response:1; }","duration":"114.578987ms","start":"2026-08-24T16:27:16.220548Z","end":"2026-08-24T16:27:16.335127Z","steps":["trace[390587058] 'process raft request' (duration: 114.437974ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:27:16.499832Z","caller":"traceutil/trace.go:171","msg":"trace[1955072053] linearizableReadLoop","detail":"{readStateIndex:21169; appliedIndex:21168; }","duration":"100.206636ms","start":"2026-08-24T16:27:16.399604Z","end":"2026-08-24T16:27:16.49981Z","steps":["trace[1955072053] 'read index received' (duration: 94.64773ms)","trace[1955072053] 'applied index is now lower than readState.Index' (duration: 5.557996ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:27:16.500012Z","caller":"traceutil/trace.go:171","msg":"trace[773365017] transaction","detail":"{read_only:false; response_revision:20475; number_of_response:1; }","duration":"114.27238ms","start":"2026-08-24T16:27:16.385732Z","end":"2026-08-24T16:27:16.500004Z","steps":["trace[773365017] 'process raft request' (duration: 108.574341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:27:16.500166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.553175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:27:16.500188Z","caller":"traceutil/trace.go:171","msg":"trace[813786641] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20475; }","duration":"100.625127ms","start":"2026-08-24T16:27:16.399555Z","end":"2026-08-24T16:27:16.500181Z","steps":["trace[813786641] 'agreement among raft nodes before linearized reading' (duration: 100.564386ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:27:41.779039Z","caller":"traceutil/trace.go:171","msg":"trace[875564981] linearizableReadLoop","detail":"{readStateIndex:21300; appliedIndex:21299; }","duration":"136.660047ms","start":"2026-08-24T16:27:41.642361Z","end":"2026-08-24T16:27:41.779021Z","steps":["trace[875564981] 'read index received' (duration: 136.478922ms)","trace[875564981] 'applied index is now lower than readState.Index' (duration: 180.465µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:27:41.779291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.918414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417426"} {"level":"info","ts":"2026-08-24T16:27:41.779314Z","caller":"traceutil/trace.go:171","msg":"trace[789082751] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:20601; }","duration":"137.016045ms","start":"2026-08-24T16:27:41.64229Z","end":"2026-08-24T16:27:41.779306Z","steps":["trace[789082751] 'agreement among raft nodes before linearized reading' (duration: 136.80269ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:27:41.779429Z","caller":"traceutil/trace.go:171","msg":"trace[1669980088] transaction","detail":"{read_only:false; response_revision:20601; number_of_response:1; }","duration":"213.736958ms","start":"2026-08-24T16:27:41.565669Z","end":"2026-08-24T16:27:41.779406Z","steps":["trace[1669980088] 'process raft request' (duration: 213.250726ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:31:20.529735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20185} {"level":"info","ts":"2026-08-24T16:31:20.659724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20185,"took":"117.126276ms","hash":2045695422,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":34611200,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-24T16:31:20.659785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2045695422,"revision":20185,"compact-revision":17830} {"level":"warn","ts":"2026-08-24T16:35:22.417867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.004018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:22.417956Z","caller":"traceutil/trace.go:171","msg":"trace[1418142959] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25308; }","duration":"114.168702ms","start":"2026-08-24T16:35:22.303768Z","end":"2026-08-24T16:35:22.417937Z","steps":["trace[1418142959] 'count revisions from in-memory index tree' (duration: 113.879775ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:23.186746Z","caller":"traceutil/trace.go:171","msg":"trace[1849119912] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"116.060347ms","start":"2026-08-24T16:35:23.070612Z","end":"2026-08-24T16:35:23.186672Z","steps":["trace[1849119912] 'process raft request' (duration: 115.740439ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:23.344992Z","caller":"traceutil/trace.go:171","msg":"trace[273366023] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"208.413514ms","start":"2026-08-24T16:35:23.136542Z","end":"2026-08-24T16:35:23.344956Z","steps":["trace[273366023] 'process raft request' (duration: 208.162188ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:23.509358Z","caller":"traceutil/trace.go:171","msg":"trace[1798777046] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"160.045777ms","start":"2026-08-24T16:35:23.349269Z","end":"2026-08-24T16:35:23.509315Z","steps":["trace[1798777046] 'process raft request' (duration: 127.94865ms)","trace[1798777046] 'compare' (duration: 31.832121ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:23.649727Z","caller":"traceutil/trace.go:171","msg":"trace[1334870872] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"298.810162ms","start":"2026-08-24T16:35:23.350884Z","end":"2026-08-24T16:35:23.649694Z","steps":["trace[1334870872] 'process raft request' (duration: 298.444962ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:23.649717Z","caller":"traceutil/trace.go:171","msg":"trace[1657212899] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"259.838566ms","start":"2026-08-24T16:35:23.389848Z","end":"2026-08-24T16:35:23.649687Z","steps":["trace[1657212899] 'process raft request' (duration: 259.724903ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:23.650259Z","caller":"traceutil/trace.go:171","msg":"trace[698106738] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"259.973309ms","start":"2026-08-24T16:35:23.390265Z","end":"2026-08-24T16:35:23.650238Z","steps":["trace[698106738] 'process raft request' (duration: 259.372465ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:23.650662Z","caller":"traceutil/trace.go:171","msg":"trace[79850162] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"239.723003ms","start":"2026-08-24T16:35:23.410926Z","end":"2026-08-24T16:35:23.650649Z","steps":["trace[79850162] 'process raft request' (duration: 239.18758ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:23.651024Z","caller":"traceutil/trace.go:171","msg":"trace[1936740340] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"239.319552ms","start":"2026-08-24T16:35:23.41169Z","end":"2026-08-24T16:35:23.651009Z","steps":["trace[1936740340] 'process raft request' (duration: 238.889222ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:27.843171Z","caller":"traceutil/trace.go:171","msg":"trace[1851812945] linearizableReadLoop","detail":"{readStateIndex:26186; appliedIndex:26185; }","duration":"176.259454ms","start":"2026-08-24T16:35:27.666887Z","end":"2026-08-24T16:35:27.843146Z","steps":["trace[1851812945] 'read index received' (duration: 77.615774ms)","trace[1851812945] 'applied index is now lower than readState.Index' (duration: 98.64269ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:27.843305Z","caller":"traceutil/trace.go:171","msg":"trace[1073264532] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"218.721308ms","start":"2026-08-24T16:35:27.62455Z","end":"2026-08-24T16:35:27.843271Z","steps":["trace[1073264532] 'process raft request' (duration: 119.968334ms)","trace[1073264532] 'compare' (duration: 98.428875ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:27.843391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.48807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T16:35:27.843435Z","caller":"traceutil/trace.go:171","msg":"trace[1056279193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25351; }","duration":"176.571783ms","start":"2026-08-24T16:35:27.666845Z","end":"2026-08-24T16:35:27.843416Z","steps":["trace[1056279193] 'agreement among raft nodes before linearized reading' (duration: 176.418469ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:27.912631Z","caller":"traceutil/trace.go:171","msg":"trace[1766396454] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"244.817707ms","start":"2026-08-24T16:35:27.667793Z","end":"2026-08-24T16:35:27.912611Z","steps":["trace[1766396454] 'process raft request' (duration: 244.762635ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:27.912687Z","caller":"traceutil/trace.go:171","msg":"trace[1653391930] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"245.833701ms","start":"2026-08-24T16:35:27.666791Z","end":"2026-08-24T16:35:27.912625Z","steps":["trace[1653391930] 'process raft request' (duration: 245.610966ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:27.912776Z","caller":"traceutil/trace.go:171","msg":"trace[1792746219] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"244.913059ms","start":"2026-08-24T16:35:27.667835Z","end":"2026-08-24T16:35:27.912749Z","steps":["trace[1792746219] 'process raft request' (duration: 244.746885ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:27.912667Z","caller":"traceutil/trace.go:171","msg":"trace[1296346896] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"245.295298ms","start":"2026-08-24T16:35:27.667335Z","end":"2026-08-24T16:35:27.91263Z","steps":["trace[1296346896] 'process raft request' (duration: 245.190256ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:28.142599Z","caller":"traceutil/trace.go:171","msg":"trace[1225846750] linearizableReadLoop","detail":"{readStateIndex:26192; appliedIndex:26187; }","duration":"299.334704ms","start":"2026-08-24T16:35:27.843238Z","end":"2026-08-24T16:35:28.142573Z","steps":["trace[1225846750] 'read index received' (duration: 69.283859ms)","trace[1225846750] 'applied index is now lower than readState.Index' (duration: 230.049775ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:28.142842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.178294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-24T16:35:28.142864Z","caller":"traceutil/trace.go:171","msg":"trace[591666701] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25358; }","duration":"313.216105ms","start":"2026-08-24T16:35:27.829641Z","end":"2026-08-24T16:35:28.142857Z","steps":["trace[591666701] 'agreement among raft nodes before linearized reading' (duration: 313.068101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.142887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:27.829566Z","time spent":"313.314737ms","remote":"127.0.0.1:51094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-24T16:35:28.143151Z","caller":"traceutil/trace.go:171","msg":"trace[590156204] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"381.253756ms","start":"2026-08-24T16:35:27.761889Z","end":"2026-08-24T16:35:28.143143Z","steps":["trace[590156204] 'process raft request' (duration: 372.195123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.143205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:27.761853Z","time spent":"381.312308ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:28.143281Z","caller":"traceutil/trace.go:171","msg":"trace[767484525] transaction","detail":"{read_only:false; response_revision:25357; number_of_response:1; }","duration":"367.976229ms","start":"2026-08-24T16:35:27.7753Z","end":"2026-08-24T16:35:28.143276Z","steps":["trace[767484525] 'process raft request' (duration: 367.147308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.143307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:27.775256Z","time spent":"368.035541ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:28.143409Z","caller":"traceutil/trace.go:171","msg":"trace[1193281185] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"295.426109ms","start":"2026-08-24T16:35:27.847971Z","end":"2026-08-24T16:35:28.143397Z","steps":["trace[1193281185] 'process raft request' (duration: 294.547847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.149403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.04797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:28.149432Z","caller":"traceutil/trace.go:171","msg":"trace[1428640829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25359; }","duration":"207.080411ms","start":"2026-08-24T16:35:27.942346Z","end":"2026-08-24T16:35:28.149426Z","steps":["trace[1428640829] 'agreement among raft nodes before linearized reading' (duration: 207.016459ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:28.15405Z","caller":"traceutil/trace.go:171","msg":"trace[1133210977] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"168.871714ms","start":"2026-08-24T16:35:27.980701Z","end":"2026-08-24T16:35:28.149573Z","steps":["trace[1133210977] 'process raft request' (duration: 168.612317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.327605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.701873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T16:35:28.327692Z","caller":"traceutil/trace.go:171","msg":"trace[1108976288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25359; }","duration":"116.803285ms","start":"2026-08-24T16:35:28.210866Z","end":"2026-08-24T16:35:28.32767Z","steps":["trace[1108976288] 'range keys from in-memory index tree' (duration: 116.494098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.327692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.94742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:28.328205Z","caller":"traceutil/trace.go:171","msg":"trace[908967008] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25359; }","duration":"104.522425ms","start":"2026-08-24T16:35:28.223653Z","end":"2026-08-24T16:35:28.328175Z","steps":["trace[908967008] 'range keys from in-memory index tree' (duration: 103.833818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.653931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.412661ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:28.654046Z","caller":"traceutil/trace.go:171","msg":"trace[1075870169] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25360; }","duration":"152.539594ms","start":"2026-08-24T16:35:28.501492Z","end":"2026-08-24T16:35:28.654031Z","steps":["trace[1075870169] 'range keys from in-memory index tree' (duration: 152.39919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.654174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.595759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:28.654265Z","caller":"traceutil/trace.go:171","msg":"trace[1329620645] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25360; }","duration":"186.747553ms","start":"2026-08-24T16:35:28.467496Z","end":"2026-08-24T16:35:28.654244Z","steps":["trace[1329620645] 'count revisions from in-memory index tree' (duration: 186.450746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.654435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.194488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:28.654555Z","caller":"traceutil/trace.go:171","msg":"trace[867896265] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"173.520628ms","start":"2026-08-24T16:35:28.481023Z","end":"2026-08-24T16:35:28.654543Z","steps":["trace[867896265] 'process raft request' (duration: 29.125985ms)","trace[867896265] 'compare' (duration: 143.556683ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:28.654555Z","caller":"traceutil/trace.go:171","msg":"trace[297370682] linearizableReadLoop","detail":"{readStateIndex:26196; appliedIndex:26195; }","duration":"101.211803ms","start":"2026-08-24T16:35:28.553327Z","end":"2026-08-24T16:35:28.654539Z","steps":["trace[297370682] 'read index received' (duration: 50.161µs)","trace[297370682] 'applied index is now lower than readState.Index' (duration: 101.159702ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:28.654729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.401867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:28.654769Z","caller":"traceutil/trace.go:171","msg":"trace[670778693] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25361; }","duration":"101.49089ms","start":"2026-08-24T16:35:28.553265Z","end":"2026-08-24T16:35:28.654756Z","steps":["trace[670778693] 'agreement among raft nodes before linearized reading' (duration: 101.366137ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:28.955815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.10751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:28.955999Z","caller":"traceutil/trace.go:171","msg":"trace[2145013283] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"288.738305ms","start":"2026-08-24T16:35:28.667235Z","end":"2026-08-24T16:35:28.955973Z","steps":["trace[2145013283] 'process raft request' (duration: 131.774973ms)","trace[2145013283] 'compare' (duration: 155.632199ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:29.449337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.131661ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T16:35:29.449421Z","caller":"traceutil/trace.go:171","msg":"trace[620986131] linearizableReadLoop","detail":"{readStateIndex:26198; appliedIndex:26197; }","duration":"233.492219ms","start":"2026-08-24T16:35:29.215913Z","end":"2026-08-24T16:35:29.449405Z","steps":["trace[620986131] 'read index received' (duration: 35.681µs)","trace[620986131] 'applied index is now lower than readState.Index' (duration: 233.455298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:29.449638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.806663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:29.449669Z","caller":"traceutil/trace.go:171","msg":"trace[1374216055] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25362; }","duration":"135.874824ms","start":"2026-08-24T16:35:29.313783Z","end":"2026-08-24T16:35:29.449658Z","steps":["trace[1374216055] 'agreement among raft nodes before linearized reading' (duration: 135.787012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:29.449722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.784796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-24T16:35:29.449805Z","caller":"traceutil/trace.go:171","msg":"trace[1530242890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25362; }","duration":"233.91144ms","start":"2026-08-24T16:35:29.215875Z","end":"2026-08-24T16:35:29.449786Z","steps":["trace[1530242890] 'agreement among raft nodes before linearized reading' (duration: 233.657934ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:29.634019Z","caller":"traceutil/trace.go:171","msg":"trace[1656866051] linearizableReadLoop","detail":"{readStateIndex:26199; appliedIndex:26198; }","duration":"124.903545ms","start":"2026-08-24T16:35:29.508901Z","end":"2026-08-24T16:35:29.633805Z","steps":["trace[1656866051] 'read index received' (duration: 124.70825ms)","trace[1656866051] 'applied index is now lower than readState.Index' (duration: 194.025µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:29.634193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.263024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:29.634174Z","caller":"traceutil/trace.go:171","msg":"trace[1106965483] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"180.654273ms","start":"2026-08-24T16:35:29.453484Z","end":"2026-08-24T16:35:29.634138Z","steps":["trace[1106965483] 'process raft request' (duration: 180.146211ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:29.634247Z","caller":"traceutil/trace.go:171","msg":"trace[1234163776] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25363; }","duration":"125.358116ms","start":"2026-08-24T16:35:29.508875Z","end":"2026-08-24T16:35:29.634233Z","steps":["trace[1234163776] 'agreement among raft nodes before linearized reading' (duration: 125.248174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:29.634389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.641424ms","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-08-24T16:35:29.634442Z","caller":"traceutil/trace.go:171","msg":"trace[1697343592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25363; }","duration":"101.735196ms","start":"2026-08-24T16:35:29.532689Z","end":"2026-08-24T16:35:29.634424Z","steps":["trace[1697343592] 'agreement among raft nodes before linearized reading' (duration: 101.572732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:29.898793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.758044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:29.898883Z","caller":"traceutil/trace.go:171","msg":"trace[953299833] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"259.814324ms","start":"2026-08-24T16:35:29.639044Z","end":"2026-08-24T16:35:29.898858Z","steps":["trace[953299833] 'process raft request' (duration: 109.925567ms)","trace[953299833] 'compare' (duration: 149.480697ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:30.230404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.946694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:30.230476Z","caller":"traceutil/trace.go:171","msg":"trace[1151221974] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"312.160861ms","start":"2026-08-24T16:35:29.9183Z","end":"2026-08-24T16:35:30.230461Z","steps":["trace[1151221974] 'process raft request' (duration: 150.089534ms)","trace[1151221974] 'compare' (duration: 161.758249ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:30.230514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:29.918279Z","time spent":"312.215401ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:30.251248Z","caller":"traceutil/trace.go:171","msg":"trace[1221122751] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"332.224272ms","start":"2026-08-24T16:35:29.918996Z","end":"2026-08-24T16:35:30.25122Z","steps":["trace[1221122751] 'process raft request' (duration: 332.056378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:30.251417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:29.918989Z","time spent":"332.342375ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:30.251542Z","caller":"traceutil/trace.go:171","msg":"trace[1361114656] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"333.061532ms","start":"2026-08-24T16:35:29.918443Z","end":"2026-08-24T16:35:30.251504Z","steps":["trace[1361114656] 'process raft request' (duration: 332.459338ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:30.251682Z","caller":"traceutil/trace.go:171","msg":"trace[228642343] linearizableReadLoop","detail":"{readStateIndex:26204; appliedIndex:26200; }","duration":"309.74094ms","start":"2026-08-24T16:35:29.941905Z","end":"2026-08-24T16:35:30.251645Z","steps":["trace[228642343] 'read index received' (duration: 126.497154ms)","trace[228642343] 'applied index is now lower than readState.Index' (duration: 183.241106ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:30.251735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:29.918436Z","time spent":"333.184265ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:30.251714Z","caller":"traceutil/trace.go:171","msg":"trace[2087556813] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"331.635527ms","start":"2026-08-24T16:35:29.920058Z","end":"2026-08-24T16:35:30.251694Z","steps":["trace[2087556813] 'process raft request' (duration: 331.103514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:30.251818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.903014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:30.25188Z","caller":"traceutil/trace.go:171","msg":"trace[1514011094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25368; }","duration":"309.997256ms","start":"2026-08-24T16:35:29.941868Z","end":"2026-08-24T16:35:30.251865Z","steps":["trace[1514011094] 'agreement among raft nodes before linearized reading' (duration: 309.899914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:30.251909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:29.920035Z","time spent":"331.787421ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:30.25193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:29.941849Z","time spent":"310.068917ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T16:35:30.261406Z","caller":"traceutil/trace.go:171","msg":"trace[6965709] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"100.982427ms","start":"2026-08-24T16:35:30.160396Z","end":"2026-08-24T16:35:30.261378Z","steps":["trace[6965709] 'process raft request' (duration: 100.831424ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:30.261428Z","caller":"traceutil/trace.go:171","msg":"trace[1906787043] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"100.556597ms","start":"2026-08-24T16:35:30.160855Z","end":"2026-08-24T16:35:30.261412Z","steps":["trace[1906787043] 'process raft request' (duration: 100.437044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:30.261616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.50625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T16:35:30.261663Z","caller":"traceutil/trace.go:171","msg":"trace[2004229508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25371; }","duration":"103.581872ms","start":"2026-08-24T16:35:30.15807Z","end":"2026-08-24T16:35:30.261652Z","steps":["trace[2004229508] 'agreement among raft nodes before linearized reading' (duration: 103.468729ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:30.572483Z","caller":"traceutil/trace.go:171","msg":"trace[588236072] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"145.8865ms","start":"2026-08-24T16:35:30.426573Z","end":"2026-08-24T16:35:30.57246Z","steps":["trace[588236072] 'process raft request' (duration: 145.554462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:31.234855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.764418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:35:31.234966Z","caller":"traceutil/trace.go:171","msg":"trace[2029540597] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25375; }","duration":"119.886991ms","start":"2026-08-24T16:35:31.115056Z","end":"2026-08-24T16:35:31.234943Z","steps":["trace[2029540597] 'count revisions from in-memory index tree' (duration: 119.653176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:31.86603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.606599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-24T16:35:31.866115Z","caller":"traceutil/trace.go:171","msg":"trace[1316284401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25376; }","duration":"228.736163ms","start":"2026-08-24T16:35:31.63736Z","end":"2026-08-24T16:35:31.866096Z","steps":["trace[1316284401] 'range keys from in-memory index tree' (duration: 228.426964ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:32.076554Z","caller":"traceutil/trace.go:171","msg":"trace[132354862] linearizableReadLoop","detail":"{readStateIndex:26214; appliedIndex:26213; }","duration":"133.495597ms","start":"2026-08-24T16:35:31.943029Z","end":"2026-08-24T16:35:32.076525Z","steps":["trace[132354862] 'read index received' (duration: 115.007653ms)","trace[132354862] 'applied index is now lower than readState.Index' (duration: 18.486133ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:32.076586Z","caller":"traceutil/trace.go:171","msg":"trace[1401122933] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"170.291298ms","start":"2026-08-24T16:35:31.906267Z","end":"2026-08-24T16:35:32.076558Z","steps":["trace[1401122933] 'process raft request' (duration: 151.906807ms)","trace[1401122933] 'compare' (duration: 18.224177ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:32.076708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.65617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:32.076752Z","caller":"traceutil/trace.go:171","msg":"trace[1743905861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25378; }","duration":"133.714402ms","start":"2026-08-24T16:35:31.943024Z","end":"2026-08-24T16:35:32.076739Z","steps":["trace[1743905861] 'agreement among raft nodes before linearized reading' (duration: 133.611579ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:32.441192Z","caller":"traceutil/trace.go:171","msg":"trace[851662978] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"205.087602ms","start":"2026-08-24T16:35:32.23608Z","end":"2026-08-24T16:35:32.441168Z","steps":["trace[851662978] 'process raft request' (duration: 204.925468ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:32.722606Z","caller":"traceutil/trace.go:171","msg":"trace[69995300] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"466.182948ms","start":"2026-08-24T16:35:32.256388Z","end":"2026-08-24T16:35:32.722571Z","steps":["trace[69995300] 'process raft request' (duration: 465.921462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:32.722864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.256372Z","time spent":"466.376153ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:32.723128Z","caller":"traceutil/trace.go:171","msg":"trace[1225329548] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"466.297171ms","start":"2026-08-24T16:35:32.256799Z","end":"2026-08-24T16:35:32.723096Z","steps":["trace[1225329548] 'process raft request' (duration: 465.706837ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:32.723203Z","caller":"traceutil/trace.go:171","msg":"trace[1837193129] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26215; }","duration":"427.9764ms","start":"2026-08-24T16:35:32.295209Z","end":"2026-08-24T16:35:32.723186Z","steps":["trace[1837193129] 'read index received' (duration: 146.015572ms)","trace[1837193129] 'applied index is now lower than readState.Index' (duration: 281.960128ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:32.72325Z","caller":"traceutil/trace.go:171","msg":"trace[1462382220] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"456.83184ms","start":"2026-08-24T16:35:32.266401Z","end":"2026-08-24T16:35:32.723233Z","steps":["trace[1462382220] 'process raft request' (duration: 456.665865ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:32.723258Z","caller":"traceutil/trace.go:171","msg":"trace[544104822] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"456.764208ms","start":"2026-08-24T16:35:32.266458Z","end":"2026-08-24T16:35:32.723222Z","steps":["trace[544104822] 'process raft request' (duration: 456.668996ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:32.72332Z","caller":"traceutil/trace.go:171","msg":"trace[1692087317] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"455.203359ms","start":"2026-08-24T16:35:32.268081Z","end":"2026-08-24T16:35:32.723284Z","steps":["trace[1692087317] 'process raft request' (duration: 455.074325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:32.723365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.72255ms","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:6"} {"level":"warn","ts":"2026-08-24T16:35:32.723361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.266393Z","time spent":"456.908642ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:32.723393Z","caller":"traceutil/trace.go:171","msg":"trace[914342448] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25385; }","duration":"276.768111ms","start":"2026-08-24T16:35:32.446615Z","end":"2026-08-24T16:35:32.723383Z","steps":["trace[914342448] 'agreement among raft nodes before linearized reading' (duration: 276.669858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:32.723414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.266438Z","time spent":"456.902092ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:32.723455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.248058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-24T16:35:32.723466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.26807Z","time spent":"455.318533ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:32.723481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.29092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-24T16:35:32.723514Z","caller":"traceutil/trace.go:171","msg":"trace[1209911983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25385; }","duration":"146.395483ms","start":"2026-08-24T16:35:32.57711Z","end":"2026-08-24T16:35:32.723505Z","steps":["trace[1209911983] 'agreement among raft nodes before linearized reading' (duration: 146.26865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:32.723337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.256789Z","time spent":"466.458707ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:32.723524Z","caller":"traceutil/trace.go:171","msg":"trace[752295316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25385; }","duration":"428.339471ms","start":"2026-08-24T16:35:32.295156Z","end":"2026-08-24T16:35:32.723496Z","steps":["trace[752295316] 'agreement among raft nodes before linearized reading' (duration: 428.126165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:32.723563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.295137Z","time spent":"428.418072ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T16:35:32.723181Z","caller":"traceutil/trace.go:171","msg":"trace[456287473] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"465.727908ms","start":"2026-08-24T16:35:32.257434Z","end":"2026-08-24T16:35:32.723162Z","steps":["trace[456287473] 'process raft request' (duration: 465.520842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:32.723949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.257428Z","time spent":"466.352933ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:33.078214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.706627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:33.078645Z","caller":"traceutil/trace.go:171","msg":"trace[1534005233] linearizableReadLoop","detail":"{readStateIndex:26224; appliedIndex:26222; }","duration":"102.936765ms","start":"2026-08-24T16:35:32.975678Z","end":"2026-08-24T16:35:33.078614Z","steps":["trace[1534005233] 'read index received' (duration: 44.541µs)","trace[1534005233] 'applied index is now lower than readState.Index' (duration: 102.890124ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:33.078663Z","caller":"traceutil/trace.go:171","msg":"trace[1397542258] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"350.324746ms","start":"2026-08-24T16:35:32.728294Z","end":"2026-08-24T16:35:33.078619Z","steps":["trace[1397542258] 'process raft request' (duration: 102.041284ms)","trace[1397542258] 'compare' (duration: 247.299768ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:33.078679Z","caller":"traceutil/trace.go:171","msg":"trace[50384343] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"314.973908ms","start":"2026-08-24T16:35:32.763648Z","end":"2026-08-24T16:35:33.078622Z","steps":["trace[50384343] 'process raft request' (duration: 314.744462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:33.078843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.12664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:33.078962Z","caller":"traceutil/trace.go:171","msg":"trace[1120157897] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25388; }","duration":"103.292754ms","start":"2026-08-24T16:35:32.975639Z","end":"2026-08-24T16:35:33.078932Z","steps":["trace[1120157897] 'agreement among raft nodes before linearized reading' (duration: 103.084699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:33.079011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.763624Z","time spent":"315.293506ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:33.080771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:32.72827Z","time spent":"350.519821ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:33.685236Z","caller":"traceutil/trace.go:171","msg":"trace[70326539] linearizableReadLoop","detail":"{readStateIndex:26226; appliedIndex:26225; }","duration":"218.404809ms","start":"2026-08-24T16:35:33.4668Z","end":"2026-08-24T16:35:33.685204Z","steps":["trace[70326539] 'read index received' (duration: 218.098712ms)","trace[70326539] 'applied index is now lower than readState.Index' (duration: 304.367µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:33.68534Z","caller":"traceutil/trace.go:171","msg":"trace[611235571] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"220.218064ms","start":"2026-08-24T16:35:33.465063Z","end":"2026-08-24T16:35:33.685282Z","steps":["trace[611235571] 'process raft request' (duration: 219.852275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:33.685418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.581483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:33.685461Z","caller":"traceutil/trace.go:171","msg":"trace[1176683858] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25390; }","duration":"218.705966ms","start":"2026-08-24T16:35:33.466741Z","end":"2026-08-24T16:35:33.685447Z","steps":["trace[1176683858] 'agreement among raft nodes before linearized reading' (duration: 218.589523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.347411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.398117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:41.347538Z","caller":"traceutil/trace.go:171","msg":"trace[1741905676] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"571.873652ms","start":"2026-08-24T16:35:40.775652Z","end":"2026-08-24T16:35:41.347525Z","steps":["trace[1741905676] 'process raft request' (duration: 272.295992ms)","trace[1741905676] 'compare' (duration: 298.714049ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:41.347578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:40.77563Z","time spent":"571.927923ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:41.347848Z","caller":"traceutil/trace.go:171","msg":"trace[312416666] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"571.260788ms","start":"2026-08-24T16:35:40.776534Z","end":"2026-08-24T16:35:41.347795Z","steps":["trace[312416666] 'process raft request' (duration: 570.95209ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:41.34795Z","caller":"traceutil/trace.go:171","msg":"trace[1141461280] linearizableReadLoop","detail":"{readStateIndex:26281; appliedIndex:26278; }","duration":"507.345829ms","start":"2026-08-24T16:35:40.840567Z","end":"2026-08-24T16:35:41.347913Z","steps":["trace[1141461280] 'read index received' (duration: 207.395099ms)","trace[1141461280] 'applied index is now lower than readState.Index' (duration: 299.94912ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:41.348095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:40.776484Z","time spent":"571.480683ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:41.348331Z","caller":"traceutil/trace.go:171","msg":"trace[992156701] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"568.998292ms","start":"2026-08-24T16:35:40.779299Z","end":"2026-08-24T16:35:41.348297Z","steps":["trace[992156701] 'process raft request' (duration: 568.390297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.348493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.920772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-24T16:35:41.348577Z","caller":"traceutil/trace.go:171","msg":"trace[432235415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25443; }","duration":"508.061206ms","start":"2026-08-24T16:35:40.840494Z","end":"2026-08-24T16:35:41.348556Z","steps":["trace[432235415] 'agreement among raft nodes before linearized reading' (duration: 507.860451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.348625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:40.840392Z","time spent":"508.22099ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T16:35:41.348639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:40.779167Z","time spent":"569.34518ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:41.866165Z","caller":"traceutil/trace.go:171","msg":"trace[626573262] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"749.390218ms","start":"2026-08-24T16:35:41.11675Z","end":"2026-08-24T16:35:41.866141Z","steps":["trace[626573262] 'process raft request' (duration: 657.401011ms)","trace[626573262] 'compare' (duration: 91.560426ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:41.86629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.116735Z","time spent":"749.48455ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:41.869101Z","caller":"traceutil/trace.go:171","msg":"trace[76703392] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"532.096477ms","start":"2026-08-24T16:35:41.336973Z","end":"2026-08-24T16:35:41.86907Z","steps":["trace[76703392] 'process raft request' (duration: 531.858591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.869224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.336954Z","time spent":"532.206069ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:41.869665Z","caller":"traceutil/trace.go:171","msg":"trace[1113615773] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"516.403241ms","start":"2026-08-24T16:35:41.353242Z","end":"2026-08-24T16:35:41.869645Z","steps":["trace[1113615773] 'process raft request' (duration: 515.744315ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:41.869773Z","caller":"traceutil/trace.go:171","msg":"trace[1044105915] linearizableReadLoop","detail":"{readStateIndex:26283; appliedIndex:26281; }","duration":"521.701592ms","start":"2026-08-24T16:35:41.348051Z","end":"2026-08-24T16:35:41.869753Z","steps":["trace[1044105915] 'read index received' (duration: 426.216228ms)","trace[1044105915] 'applied index is now lower than readState.Index' (duration: 95.483904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:41.869934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.353209Z","time spent":"516.670988ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:41.869992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.201706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:41.870188Z","caller":"traceutil/trace.go:171","msg":"trace[1213389571] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25447; }","duration":"668.427142ms","start":"2026-08-24T16:35:41.201729Z","end":"2026-08-24T16:35:41.870156Z","steps":["trace[1213389571] 'agreement among raft nodes before linearized reading' (duration: 668.140745ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:41.870201Z","caller":"traceutil/trace.go:171","msg":"trace[1086963476] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"318.772863ms","start":"2026-08-24T16:35:41.551375Z","end":"2026-08-24T16:35:41.870148Z","steps":["trace[1086963476] 'process raft request' (duration: 318.106276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.870294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.201685Z","time spent":"668.593456ms","remote":"127.0.0.1:54480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"warn","ts":"2026-08-24T16:35:41.870399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.823322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-24T16:35:41.870375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.011809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:41.870442Z","caller":"traceutil/trace.go:171","msg":"trace[863023189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25447; }","duration":"754.899584ms","start":"2026-08-24T16:35:41.115531Z","end":"2026-08-24T16:35:41.87043Z","steps":["trace[863023189] 'agreement among raft nodes before linearized reading' (duration: 754.619197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.870224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.192299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T16:35:41.870528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.115511Z","time spent":"755.007577ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T16:35:41.870537Z","caller":"traceutil/trace.go:171","msg":"trace[1179331105] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25447; }","duration":"823.549078ms","start":"2026-08-24T16:35:41.046977Z","end":"2026-08-24T16:35:41.870526Z","steps":["trace[1179331105] 'agreement among raft nodes before linearized reading' (duration: 823.192329ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:41.870462Z","caller":"traceutil/trace.go:171","msg":"trace[2145782545] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:25447; }","duration":"401.112042ms","start":"2026-08-24T16:35:41.469328Z","end":"2026-08-24T16:35:41.87044Z","steps":["trace[2145782545] 'agreement among raft nodes before linearized reading' (duration: 400.959798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.870585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.046949Z","time spent":"823.61927ms","remote":"127.0.0.1:42460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-24T16:35:41.870604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.469216Z","time spent":"401.371778ms","remote":"127.0.0.1:41840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-08-24T16:35:41.870089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.905356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:41.870742Z","caller":"traceutil/trace.go:171","msg":"trace[891272081] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25447; }","duration":"652.647095ms","start":"2026-08-24T16:35:41.218082Z","end":"2026-08-24T16:35:41.870729Z","steps":["trace[891272081] 'agreement among raft nodes before linearized reading' (duration: 651.751163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.870125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.841895ms","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-08-24T16:35:41.870806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.218069Z","time spent":"652.706946ms","remote":"127.0.0.1:48008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-08-24T16:35:41.870806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.336358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-08-24T16:35:41.870836Z","caller":"traceutil/trace.go:171","msg":"trace[1527020657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25447; }","duration":"718.570662ms","start":"2026-08-24T16:35:41.152249Z","end":"2026-08-24T16:35:41.87082Z","steps":["trace[1527020657] 'agreement among raft nodes before linearized reading' (duration: 717.773683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.870864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.152237Z","time spent":"718.618624ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T16:35:41.870862Z","caller":"traceutil/trace.go:171","msg":"trace[806980773] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25447; }","duration":"324.425731ms","start":"2026-08-24T16:35:41.546423Z","end":"2026-08-24T16:35:41.870849Z","steps":["trace[806980773] 'agreement among raft nodes before linearized reading' (duration: 324.0035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:41.870893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.546408Z","time spent":"324.477012ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418326,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-08-24T16:35:41.871289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.550603Z","time spent":"319.717285ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:42.162765Z","caller":"traceutil/trace.go:171","msg":"trace[1499662774] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"288.333655ms","start":"2026-08-24T16:35:41.874408Z","end":"2026-08-24T16:35:42.162741Z","steps":["trace[1499662774] 'process raft request' (duration: 265.121326ms)","trace[1499662774] 'compare' (duration: 23.093506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:42.729621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.599161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:42.729791Z","caller":"traceutil/trace.go:171","msg":"trace[1430111412] linearizableReadLoop","detail":"{readStateIndex:26287; appliedIndex:26285; }","duration":"807.331761ms","start":"2026-08-24T16:35:41.922439Z","end":"2026-08-24T16:35:42.729771Z","steps":["trace[1430111412] 'read index received' (duration: 217.103198ms)","trace[1430111412] 'applied index is now lower than readState.Index' (duration: 590.226873ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:42.729923Z","caller":"traceutil/trace.go:171","msg":"trace[735624077] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"855.456562ms","start":"2026-08-24T16:35:41.874424Z","end":"2026-08-24T16:35:42.72988Z","steps":["trace[735624077] 'process raft request' (duration: 707.495711ms)","trace[735624077] 'compare' (duration: 147.318094ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:42.730152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.874406Z","time spent":"855.607055ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:42.730176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.71856ms","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-08-24T16:35:42.73024Z","caller":"traceutil/trace.go:171","msg":"trace[850560847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25449; }","duration":"807.836553ms","start":"2026-08-24T16:35:41.922389Z","end":"2026-08-24T16:35:42.730226Z","steps":["trace[850560847] 'agreement among raft nodes before linearized reading' (duration: 807.567696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:42.730273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.92237Z","time spent":"807.897325ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-24T16:35:43.047046Z","caller":"traceutil/trace.go:171","msg":"trace[839156664] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"752.485944ms","start":"2026-08-24T16:35:42.294537Z","end":"2026-08-24T16:35:43.047023Z","steps":["trace[839156664] 'process raft request' (duration: 751.91003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.04719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.294511Z","time spent":"752.604898ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:43.047272Z","caller":"traceutil/trace.go:171","msg":"trace[528931232] linearizableReadLoop","detail":"{readStateIndex:26289; appliedIndex:26287; }","duration":"317.423339ms","start":"2026-08-24T16:35:42.729814Z","end":"2026-08-24T16:35:43.047237Z","steps":["trace[528931232] 'read index received' (duration: 316.257709ms)","trace[528931232] 'applied index is now lower than readState.Index' (duration: 1.162279ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:43.047364Z","caller":"traceutil/trace.go:171","msg":"trace[1357763563] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"547.853764ms","start":"2026-08-24T16:35:42.499473Z","end":"2026-08-24T16:35:43.047326Z","steps":["trace[1357763563] 'process raft request' (duration: 547.484275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.047496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.499435Z","time spent":"547.988837ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:43.047594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104255646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:43.047638Z","caller":"traceutil/trace.go:171","msg":"trace[1847652795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25451; }","duration":"1.104328898s","start":"2026-08-24T16:35:41.943298Z","end":"2026-08-24T16:35:43.047627Z","steps":["trace[1847652795] 'agreement among raft nodes before linearized reading' (duration: 1.104253036s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.047649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.863104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-24T16:35:43.047671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.943279Z","time spent":"1.104386089s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T16:35:43.047703Z","caller":"traceutil/trace.go:171","msg":"trace[77351701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25451; }","duration":"924.958676ms","start":"2026-08-24T16:35:42.122728Z","end":"2026-08-24T16:35:43.047687Z","steps":["trace[77351701] 'agreement among raft nodes before linearized reading' (duration: 924.648549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.04776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.122711Z","time spent":"925.034908ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T16:35:43.04789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085695321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:43.048043Z","caller":"traceutil/trace.go:171","msg":"trace[1961911672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25451; }","duration":"1.085948027s","start":"2026-08-24T16:35:41.96206Z","end":"2026-08-24T16:35:43.048008Z","steps":["trace[1961911672] 'agreement among raft nodes before linearized reading' (duration: 1.085333452s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.048117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:41.962041Z","time spent":"1.086059649s","remote":"127.0.0.1:58746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-08-24T16:35:43.262246Z","caller":"traceutil/trace.go:171","msg":"trace[1178606353] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"527.136265ms","start":"2026-08-24T16:35:42.735057Z","end":"2026-08-24T16:35:43.262193Z","steps":["trace[1178606353] 'process raft request' (duration: 524.48331ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:43.262436Z","caller":"traceutil/trace.go:171","msg":"trace[761734351] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"500.373629ms","start":"2026-08-24T16:35:42.762036Z","end":"2026-08-24T16:35:43.26241Z","steps":["trace[761734351] 'process raft request' (duration: 500.289277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.262498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.735036Z","time spent":"527.330131ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:43.262598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.762023Z","time spent":"500.491881ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:43.262647Z","caller":"traceutil/trace.go:171","msg":"trace[404861240] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"502.538812ms","start":"2026-08-24T16:35:42.76008Z","end":"2026-08-24T16:35:43.262618Z","steps":["trace[404861240] 'process raft request' (duration: 501.921386ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:43.262788Z","caller":"traceutil/trace.go:171","msg":"trace[710646094] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"495.500139ms","start":"2026-08-24T16:35:42.767248Z","end":"2026-08-24T16:35:43.262748Z","steps":["trace[710646094] 'process raft request' (duration: 495.12032ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:43.262874Z","caller":"traceutil/trace.go:171","msg":"trace[2113734614] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"406.891734ms","start":"2026-08-24T16:35:42.855957Z","end":"2026-08-24T16:35:43.262849Z","steps":["trace[2113734614] 'process raft request' (duration: 406.71086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.262933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.767216Z","time spent":"495.642542ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:43.262971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.85592Z","time spent":"407.005257ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:43.262978Z","caller":"traceutil/trace.go:171","msg":"trace[658119835] linearizableReadLoop","detail":"{readStateIndex:26294; appliedIndex:26289; }","duration":"215.608801ms","start":"2026-08-24T16:35:43.047345Z","end":"2026-08-24T16:35:43.262954Z","steps":["trace[658119835] 'read index received' (duration: 211.559791ms)","trace[658119835] 'applied index is now lower than readState.Index' (duration: 4.04647ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:43.263064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:42.760055Z","time spent":"502.745517ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:35:43.263059Z","caller":"traceutil/trace.go:171","msg":"trace[1773484566] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"211.250064ms","start":"2026-08-24T16:35:43.051703Z","end":"2026-08-24T16:35:43.262953Z","steps":["trace[1773484566] 'process raft request' (duration: 211.129381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.263167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.560345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:43.2632Z","caller":"traceutil/trace.go:171","msg":"trace[704671335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25457; }","duration":"214.638388ms","start":"2026-08-24T16:35:43.048554Z","end":"2026-08-24T16:35:43.263192Z","steps":["trace[704671335] 'agreement among raft nodes before linearized reading' (duration: 214.530375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:43.263242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.568666ms","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:6"} {"level":"info","ts":"2026-08-24T16:35:43.263307Z","caller":"traceutil/trace.go:171","msg":"trace[1674575378] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25457; }","duration":"256.72048ms","start":"2026-08-24T16:35:43.006569Z","end":"2026-08-24T16:35:43.263289Z","steps":["trace[1674575378] 'agreement among raft nodes before linearized reading' (duration: 256.471034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:48.697078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.660031ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:48.697234Z","caller":"traceutil/trace.go:171","msg":"trace[2035792519] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25551; }","duration":"195.825685ms","start":"2026-08-24T16:35:48.501378Z","end":"2026-08-24T16:35:48.697204Z","steps":["trace[2035792519] 'range keys from in-memory index tree' (duration: 195.63461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:48.697297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.459066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:48.697423Z","caller":"traceutil/trace.go:171","msg":"trace[1163731726] linearizableReadLoop","detail":"{readStateIndex:26392; appliedIndex:26391; }","duration":"230.803034ms","start":"2026-08-24T16:35:48.466597Z","end":"2026-08-24T16:35:48.6974Z","steps":["trace[1163731726] 'read index received' (duration: 66.081013ms)","trace[1163731726] 'applied index is now lower than readState.Index' (duration: 164.719771ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:48.697556Z","caller":"traceutil/trace.go:171","msg":"trace[1722614779] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"280.3952ms","start":"2026-08-24T16:35:48.417142Z","end":"2026-08-24T16:35:48.697537Z","steps":["trace[1722614779] 'process raft request' (duration: 115.565605ms)","trace[1722614779] 'compare' (duration: 164.087997ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:48.697783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.008772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7a78c192-721b-4d97-81da-243d9a39dd88\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-24T16:35:48.697862Z","caller":"traceutil/trace.go:171","msg":"trace[522422739] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7a78c192-721b-4d97-81da-243d9a39dd88; range_end:; response_count:1; response_revision:25552; }","duration":"192.118644ms","start":"2026-08-24T16:35:48.505724Z","end":"2026-08-24T16:35:48.697842Z","steps":["trace[522422739] 'agreement among raft nodes before linearized reading' (duration: 191.95175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:48.698059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.406329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-24T16:35:48.698125Z","caller":"traceutil/trace.go:171","msg":"trace[809546109] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25552; }","duration":"231.547252ms","start":"2026-08-24T16:35:48.46656Z","end":"2026-08-24T16:35:48.698107Z","steps":["trace[809546109] 'agreement among raft nodes before linearized reading' (duration: 231.149363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:48.948445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.616221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:48.948638Z","caller":"traceutil/trace.go:171","msg":"trace[1409982067] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"191.398996ms","start":"2026-08-24T16:35:48.757217Z","end":"2026-08-24T16:35:48.948616Z","steps":["trace[1409982067] 'process raft request' (duration: 83.518419ms)","trace[1409982067] 'compare' (duration: 107.483288ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:49.42234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.183769ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T16:35:49.423188Z","caller":"traceutil/trace.go:171","msg":"trace[659146185] linearizableReadLoop","detail":"{readStateIndex:26395; appliedIndex:26393; }","duration":"346.183095ms","start":"2026-08-24T16:35:49.076989Z","end":"2026-08-24T16:35:49.423172Z","steps":["trace[659146185] 'read index received' (duration: 73.175146ms)","trace[659146185] 'applied index is now lower than readState.Index' (duration: 273.006909ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:49.423248Z","caller":"traceutil/trace.go:171","msg":"trace[1269771588] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"349.194179ms","start":"2026-08-24T16:35:49.074013Z","end":"2026-08-24T16:35:49.423207Z","steps":["trace[1269771588] 'process raft request' (duration: 348.42221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:49.423424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:49.073993Z","time spent":"349.335762ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:35:49.49236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.854959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:49.492676Z","caller":"traceutil/trace.go:171","msg":"trace[239473898] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25554; }","duration":"415.691381ms","start":"2026-08-24T16:35:49.076947Z","end":"2026-08-24T16:35:49.492638Z","steps":["trace[239473898] 'agreement among raft nodes before linearized reading' (duration: 346.297148ms)","trace[239473898] 'range keys from in-memory index tree' (duration: 68.561492ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:49.492787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:49.07693Z","time spent":"415.826574ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-24T16:35:49.494121Z","caller":"traceutil/trace.go:171","msg":"trace[153571091] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"211.161921ms","start":"2026-08-24T16:35:49.282895Z","end":"2026-08-24T16:35:49.494057Z","steps":["trace[153571091] 'process raft request' (duration: 140.528318ms)","trace[153571091] 'compare' (duration: 68.319396ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:49.500163Z","caller":"traceutil/trace.go:171","msg":"trace[1720575658] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"214.37471ms","start":"2026-08-24T16:35:49.285763Z","end":"2026-08-24T16:35:49.500138Z","steps":["trace[1720575658] 'process raft request' (duration: 213.451598ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:49.500397Z","caller":"traceutil/trace.go:171","msg":"trace[1247103990] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"214.304368ms","start":"2026-08-24T16:35:49.286084Z","end":"2026-08-24T16:35:49.500389Z","steps":["trace[1247103990] 'process raft request' (duration: 213.330025ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:49.500472Z","caller":"traceutil/trace.go:171","msg":"trace[367344783] transaction","detail":"{read_only:false; response_revision:25558; number_of_response:1; }","duration":"111.798602ms","start":"2026-08-24T16:35:49.388667Z","end":"2026-08-24T16:35:49.500465Z","steps":["trace[367344783] 'process raft request' (duration: 110.788158ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:49.500535Z","caller":"traceutil/trace.go:171","msg":"trace[2118455499] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"108.745278ms","start":"2026-08-24T16:35:49.391784Z","end":"2026-08-24T16:35:49.500529Z","steps":["trace[2118455499] 'process raft request' (duration: 107.702512ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:49.500607Z","caller":"traceutil/trace.go:171","msg":"trace[1075627999] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"106.697738ms","start":"2026-08-24T16:35:49.393904Z","end":"2026-08-24T16:35:49.500602Z","steps":["trace[1075627999] 'process raft request' (duration: 105.608412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:49.500763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.530836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-24T16:35:49.50106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.315567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:35:49.501136Z","caller":"traceutil/trace.go:171","msg":"trace[857378996] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25560; }","duration":"273.397358ms","start":"2026-08-24T16:35:49.227705Z","end":"2026-08-24T16:35:49.501103Z","steps":["trace[857378996] 'agreement among raft nodes before linearized reading' (duration: 273.271935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:49.501337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.996662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-24T16:35:49.501325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.625598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:49.501403Z","caller":"traceutil/trace.go:171","msg":"trace[906959926] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25560; }","duration":"300.7687ms","start":"2026-08-24T16:35:49.20062Z","end":"2026-08-24T16:35:49.501389Z","steps":["trace[906959926] 'agreement among raft nodes before linearized reading' (duration: 300.641207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:49.501467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:35:49.2006Z","time spent":"300.859823ms","remote":"127.0.0.1:51132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-24T16:35:49.501366Z","caller":"traceutil/trace.go:171","msg":"trace[1377509437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25560; }","duration":"214.052123ms","start":"2026-08-24T16:35:49.287306Z","end":"2026-08-24T16:35:49.501358Z","steps":["trace[1377509437] 'agreement among raft nodes before linearized reading' (duration: 213.95386ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:49.502015Z","caller":"traceutil/trace.go:171","msg":"trace[100656723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25560; }","duration":"201.611147ms","start":"2026-08-24T16:35:49.299179Z","end":"2026-08-24T16:35:49.50079Z","steps":["trace[100656723] 'agreement among raft nodes before linearized reading' (duration: 201.485853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:51.810498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.174461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:51.810622Z","caller":"traceutil/trace.go:171","msg":"trace[1434904859] linearizableReadLoop","detail":"{readStateIndex:26420; appliedIndex:26419; }","duration":"233.003487ms","start":"2026-08-24T16:35:51.577598Z","end":"2026-08-24T16:35:51.810601Z","steps":["trace[1434904859] 'read index received' (duration: 94.745055ms)","trace[1434904859] 'applied index is now lower than readState.Index' (duration: 138.256492ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:51.81102Z","caller":"traceutil/trace.go:171","msg":"trace[1436314863] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"260.471942ms","start":"2026-08-24T16:35:51.55051Z","end":"2026-08-24T16:35:51.810981Z","steps":["trace[1436314863] 'process raft request' (duration: 121.80753ms)","trace[1436314863] 'compare' (duration: 137.957085ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:51.811047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.442128ms","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-08-24T16:35:51.811484Z","caller":"traceutil/trace.go:171","msg":"trace[636530598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25579; }","duration":"233.684704ms","start":"2026-08-24T16:35:51.577547Z","end":"2026-08-24T16:35:51.811232Z","steps":["trace[636530598] 'agreement among raft nodes before linearized reading' (duration: 233.359766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:56.20501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.640052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:56.205115Z","caller":"traceutil/trace.go:171","msg":"trace[40419349] linearizableReadLoop","detail":"{readStateIndex:26476; appliedIndex:26475; }","duration":"261.706022ms","start":"2026-08-24T16:35:55.943393Z","end":"2026-08-24T16:35:56.205098Z","steps":["trace[40419349] 'read index received' (duration: 131.828114ms)","trace[40419349] 'applied index is now lower than readState.Index' (duration: 129.876418ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:56.20516Z","caller":"traceutil/trace.go:171","msg":"trace[1374233546] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"278.940695ms","start":"2026-08-24T16:35:55.926167Z","end":"2026-08-24T16:35:56.205108Z","steps":["trace[1374233546] 'process raft request' (duration: 149.106789ms)","trace[1374233546] 'compare' (duration: 129.434296ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:56.205205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.812995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:35:56.205876Z","caller":"traceutil/trace.go:171","msg":"trace[127526245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25634; }","duration":"262.464171ms","start":"2026-08-24T16:35:55.943384Z","end":"2026-08-24T16:35:56.205848Z","steps":["trace[127526245] 'agreement among raft nodes before linearized reading' (duration: 261.782214ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:35:56.206372Z","caller":"traceutil/trace.go:171","msg":"trace[144142988] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"236.070952ms","start":"2026-08-24T16:35:55.97026Z","end":"2026-08-24T16:35:56.206331Z","steps":["trace[144142988] 'process raft request' (duration: 235.96038ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:56.20642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.376136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:35:56.206461Z","caller":"traceutil/trace.go:171","msg":"trace[2042750095] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25635; }","duration":"257.429687ms","start":"2026-08-24T16:35:55.949018Z","end":"2026-08-24T16:35:56.206448Z","steps":["trace[2042750095] 'agreement among raft nodes before linearized reading' (duration: 257.301974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:56.206637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.805999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-24T16:35:56.20678Z","caller":"traceutil/trace.go:171","msg":"trace[744489209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25635; }","duration":"228.105906ms","start":"2026-08-24T16:35:55.978648Z","end":"2026-08-24T16:35:56.206754Z","steps":["trace[744489209] 'agreement among raft nodes before linearized reading' (duration: 227.719647ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:35:56.467335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.697275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:35:56.467464Z","caller":"traceutil/trace.go:171","msg":"trace[605186471] linearizableReadLoop","detail":"{readStateIndex:26479; appliedIndex:26478; }","duration":"128.494782ms","start":"2026-08-24T16:35:56.338948Z","end":"2026-08-24T16:35:56.467443Z","steps":["trace[605186471] 'read index received' (duration: 17.580591ms)","trace[605186471] 'applied index is now lower than readState.Index' (duration: 110.912691ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:35:56.467558Z","caller":"traceutil/trace.go:171","msg":"trace[1077385102] transaction","detail":"{read_only:false; response_revision:25637; number_of_response:1; }","duration":"172.8276ms","start":"2026-08-24T16:35:56.294696Z","end":"2026-08-24T16:35:56.467524Z","steps":["trace[1077385102] 'process raft request' (duration: 61.824427ms)","trace[1077385102] 'compare' (duration: 110.557432ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:35:56.468955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.002889ms","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-08-24T16:35:56.468993Z","caller":"traceutil/trace.go:171","msg":"trace[624858262] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25637; }","duration":"130.069941ms","start":"2026-08-24T16:35:56.338914Z","end":"2026-08-24T16:35:56.468984Z","steps":["trace[624858262] 'agreement among raft nodes before linearized reading' (duration: 128.615495ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:11.605314Z","caller":"traceutil/trace.go:171","msg":"trace[103118264] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"103.010428ms","start":"2026-08-24T16:36:11.502285Z","end":"2026-08-24T16:36:11.605296Z","steps":["trace[103118264] 'process raft request' (duration: 102.901765ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:11.992889Z","caller":"traceutil/trace.go:171","msg":"trace[461863566] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"415.66578ms","start":"2026-08-24T16:36:11.577196Z","end":"2026-08-24T16:36:11.992862Z","steps":["trace[461863566] 'process raft request' (duration: 415.413233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:11.993028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:11.57718Z","time spent":"415.776813ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:36:11.993138Z","caller":"traceutil/trace.go:171","msg":"trace[1845047783] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"413.060116ms","start":"2026-08-24T16:36:11.580033Z","end":"2026-08-24T16:36:11.993093Z","steps":["trace[1845047783] 'process raft request' (duration: 412.736807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:11.99336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:11.58002Z","time spent":"413.24024ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:36:11.993729Z","caller":"traceutil/trace.go:171","msg":"trace[1521051779] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"408.288908ms","start":"2026-08-24T16:36:11.585424Z","end":"2026-08-24T16:36:11.993713Z","steps":["trace[1521051779] 'process raft request' (duration: 408.252657ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:11.993792Z","caller":"traceutil/trace.go:171","msg":"trace[1570064104] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"411.684062ms","start":"2026-08-24T16:36:11.582099Z","end":"2026-08-24T16:36:11.993783Z","steps":["trace[1570064104] 'process raft request' (duration: 411.477887ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:11.993817Z","caller":"traceutil/trace.go:171","msg":"trace[1477320637] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"411.245851ms","start":"2026-08-24T16:36:11.582554Z","end":"2026-08-24T16:36:11.9938Z","steps":["trace[1477320637] 'process raft request' (duration: 411.089157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:11.993895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:11.58541Z","time spent":"408.424732ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:36:11.993909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:11.582538Z","time spent":"411.322093ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:36:11.993895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:11.582081Z","time spent":"411.747933ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:36:11.993736Z","caller":"traceutil/trace.go:171","msg":"trace[163419597] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"412.116822ms","start":"2026-08-24T16:36:11.581513Z","end":"2026-08-24T16:36:11.99363Z","steps":["trace[163419597] 'process raft request' (duration: 411.296171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:11.994087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:11.581502Z","time spent":"412.561723ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:36:12.016323Z","caller":"traceutil/trace.go:171","msg":"trace[644228301] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"132.429579ms","start":"2026-08-24T16:36:11.883864Z","end":"2026-08-24T16:36:12.016294Z","steps":["trace[644228301] 'process raft request' (duration: 132.270905ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:12.317403Z","caller":"traceutil/trace.go:171","msg":"trace[2146714798] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"296.183176ms","start":"2026-08-24T16:36:12.021196Z","end":"2026-08-24T16:36:12.317379Z","steps":["trace[2146714798] 'process raft request' (duration: 240.577042ms)","trace[2146714798] 'compare' (duration: 55.254076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:12.62518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.413972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:36:12.62527Z","caller":"traceutil/trace.go:171","msg":"trace[547447874] linearizableReadLoop","detail":"{readStateIndex:26609; appliedIndex:26608; }","duration":"221.501875ms","start":"2026-08-24T16:36:12.403753Z","end":"2026-08-24T16:36:12.625255Z","steps":["trace[547447874] 'read index received' (duration: 83.881158ms)","trace[547447874] 'applied index is now lower than readState.Index' (duration: 137.619517ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:12.625488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.73097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:36:12.625513Z","caller":"traceutil/trace.go:171","msg":"trace[1715855178] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"262.137122ms","start":"2026-08-24T16:36:12.363345Z","end":"2026-08-24T16:36:12.625482Z","steps":["trace[1715855178] 'process raft request' (duration: 124.342021ms)","trace[1715855178] 'compare' (duration: 137.269688ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:12.625549Z","caller":"traceutil/trace.go:171","msg":"trace[241067029] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25764; }","duration":"221.839664ms","start":"2026-08-24T16:36:12.403691Z","end":"2026-08-24T16:36:12.625531Z","steps":["trace[241067029] 'agreement among raft nodes before linearized reading' (duration: 221.647459ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:13.314521Z","caller":"traceutil/trace.go:171","msg":"trace[1704444931] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"207.219334ms","start":"2026-08-24T16:36:13.107279Z","end":"2026-08-24T16:36:13.314498Z","steps":["trace[1704444931] 'process raft request' (duration: 207.083451ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:13.4908Z","caller":"traceutil/trace.go:171","msg":"trace[1889652843] linearizableReadLoop","detail":"{readStateIndex:26612; appliedIndex:26610; }","duration":"187.569142ms","start":"2026-08-24T16:36:13.303213Z","end":"2026-08-24T16:36:13.490782Z","steps":["trace[1889652843] 'read index received' (duration: 11.224955ms)","trace[1889652843] 'applied index is now lower than readState.Index' (duration: 176.343097ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:13.490909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.688525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:13.490941Z","caller":"traceutil/trace.go:171","msg":"trace[986270416] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25767; }","duration":"187.738106ms","start":"2026-08-24T16:36:13.303194Z","end":"2026-08-24T16:36:13.490932Z","steps":["trace[986270416] 'agreement among raft nodes before linearized reading' (duration: 187.658065ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:13.490975Z","caller":"traceutil/trace.go:171","msg":"trace[1367453034] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"201.72544ms","start":"2026-08-24T16:36:13.289185Z","end":"2026-08-24T16:36:13.490911Z","steps":["trace[1367453034] 'process raft request' (duration: 201.438963ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:20.195373Z","caller":"traceutil/trace.go:171","msg":"trace[73659727] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"169.778815ms","start":"2026-08-24T16:36:20.025543Z","end":"2026-08-24T16:36:20.195322Z","steps":["trace[73659727] 'process raft request' (duration: 103.152891ms)","trace[73659727] 'compare' (duration: 66.371548ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:20.195464Z","caller":"traceutil/trace.go:171","msg":"trace[2034930134] linearizableReadLoop","detail":"{readStateIndex:26675; appliedIndex:26670; }","duration":"137.929045ms","start":"2026-08-24T16:36:20.057505Z","end":"2026-08-24T16:36:20.195434Z","steps":["trace[2034930134] 'read index received' (duration: 24.997244ms)","trace[2034930134] 'applied index is now lower than readState.Index' (duration: 112.930111ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:20.195488Z","caller":"traceutil/trace.go:171","msg":"trace[862326379] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"164.519317ms","start":"2026-08-24T16:36:20.030919Z","end":"2026-08-24T16:36:20.195438Z","steps":["trace[862326379] 'process raft request' (duration: 164.327751ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:20.195538Z","caller":"traceutil/trace.go:171","msg":"trace[365490115] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"157.362512ms","start":"2026-08-24T16:36:20.038155Z","end":"2026-08-24T16:36:20.195517Z","steps":["trace[365490115] 'process raft request' (duration: 157.164056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:20.195766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.187141ms","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:8"} {"level":"info","ts":"2026-08-24T16:36:20.195836Z","caller":"traceutil/trace.go:171","msg":"trace[1534904276] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:25828; }","duration":"138.342895ms","start":"2026-08-24T16:36:20.057476Z","end":"2026-08-24T16:36:20.195818Z","steps":["trace[1534904276] 'agreement among raft nodes before linearized reading' (duration: 138.13275ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:20.554513Z","caller":"traceutil/trace.go:171","msg":"trace[21294360] linearizableReadLoop","detail":"{readStateIndex:26678; appliedIndex:26676; }","duration":"149.010407ms","start":"2026-08-24T16:36:20.405481Z","end":"2026-08-24T16:36:20.554491Z","steps":["trace[21294360] 'read index received' (duration: 121.478361ms)","trace[21294360] 'applied index is now lower than readState.Index' (duration: 27.530746ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:20.554818Z","caller":"traceutil/trace.go:171","msg":"trace[972321391] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"151.434176ms","start":"2026-08-24T16:36:20.40337Z","end":"2026-08-24T16:36:20.554804Z","steps":["trace[972321391] 'process raft request' (duration: 123.572452ms)","trace[972321391] 'compare' (duration: 27.359452ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:20.555057Z","caller":"traceutil/trace.go:171","msg":"trace[275875144] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"150.911764ms","start":"2026-08-24T16:36:20.40412Z","end":"2026-08-24T16:36:20.555032Z","steps":["trace[275875144] 'process raft request' (duration: 150.311599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:20.55515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.646613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-24T16:36:20.555213Z","caller":"traceutil/trace.go:171","msg":"trace[154710349] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25831; }","duration":"149.746226ms","start":"2026-08-24T16:36:20.405446Z","end":"2026-08-24T16:36:20.555192Z","steps":["trace[154710349] 'agreement among raft nodes before linearized reading' (duration: 149.53613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:20.56437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.180254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:20.564451Z","caller":"traceutil/trace.go:171","msg":"trace[610700342] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25832; }","duration":"139.303928ms","start":"2026-08-24T16:36:20.425124Z","end":"2026-08-24T16:36:20.564428Z","steps":["trace[610700342] 'agreement among raft nodes before linearized reading' (duration: 139.152804ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:20.570403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22559} {"level":"info","ts":"2026-08-24T16:36:20.6801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22559,"took":"106.511795ms","hash":3329443718,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":46198784,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-08-24T16:36:20.680154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3329443718,"revision":22559,"compact-revision":20185} {"level":"info","ts":"2026-08-24T16:36:24.408231Z","caller":"traceutil/trace.go:171","msg":"trace[1135361053] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"118.463416ms","start":"2026-08-24T16:36:24.289745Z","end":"2026-08-24T16:36:24.408208Z","steps":["trace[1135361053] 'process raft request' (duration: 118.237301ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:24.408688Z","caller":"traceutil/trace.go:171","msg":"trace[1335084132] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"118.827556ms","start":"2026-08-24T16:36:24.289851Z","end":"2026-08-24T16:36:24.408679Z","steps":["trace[1335084132] 'process raft request' (duration: 118.312002ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:26.259588Z","caller":"traceutil/trace.go:171","msg":"trace[1578779191] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"156.137932ms","start":"2026-08-24T16:36:26.103403Z","end":"2026-08-24T16:36:26.259541Z","steps":["trace[1578779191] 'process raft request' (duration: 155.520617ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:26.489869Z","caller":"traceutil/trace.go:171","msg":"trace[1943719099] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"147.016697ms","start":"2026-08-24T16:36:26.342818Z","end":"2026-08-24T16:36:26.489835Z","steps":["trace[1943719099] 'process raft request' (duration: 146.308ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:26.523502Z","caller":"traceutil/trace.go:171","msg":"trace[1179744842] transaction","detail":"{read_only:false; response_revision:25873; number_of_response:1; }","duration":"109.298022ms","start":"2026-08-24T16:36:26.414166Z","end":"2026-08-24T16:36:26.523464Z","steps":["trace[1179744842] 'process raft request' (duration: 109.017736ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:26.523792Z","caller":"traceutil/trace.go:171","msg":"trace[458005528] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"108.650837ms","start":"2026-08-24T16:36:26.415109Z","end":"2026-08-24T16:36:26.52376Z","steps":["trace[458005528] 'process raft request' (duration: 108.261426ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:26.728425Z","caller":"traceutil/trace.go:171","msg":"trace[1598242528] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26723; }","duration":"146.222849ms","start":"2026-08-24T16:36:26.582178Z","end":"2026-08-24T16:36:26.728401Z","steps":["trace[1598242528] 'read index received' (duration: 54.955449ms)","trace[1598242528] 'applied index is now lower than readState.Index' (duration: 91.26665ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:26.728561Z","caller":"traceutil/trace.go:171","msg":"trace[359933528] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"234.674818ms","start":"2026-08-24T16:36:26.493817Z","end":"2026-08-24T16:36:26.728492Z","steps":["trace[359933528] 'process raft request' (duration: 143.269095ms)","trace[359933528] 'compare' (duration: 91.001832ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:26.728775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.334806ms","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-08-24T16:36:26.728824Z","caller":"traceutil/trace.go:171","msg":"trace[51935736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25875; }","duration":"145.431659ms","start":"2026-08-24T16:36:26.583379Z","end":"2026-08-24T16:36:26.72881Z","steps":["trace[51935736] 'agreement among raft nodes before linearized reading' (duration: 145.230984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:26.728892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.729807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:26.729373Z","caller":"traceutil/trace.go:171","msg":"trace[1190428061] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25875; }","duration":"102.306751ms","start":"2026-08-24T16:36:26.627046Z","end":"2026-08-24T16:36:26.729352Z","steps":["trace[1190428061] 'agreement among raft nodes before linearized reading' (duration: 101.739247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:26.729662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.374066ms","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-08-24T16:36:26.729794Z","caller":"traceutil/trace.go:171","msg":"trace[493475386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25875; }","duration":"147.649953ms","start":"2026-08-24T16:36:26.582114Z","end":"2026-08-24T16:36:26.729764Z","steps":["trace[493475386] 'agreement among raft nodes before linearized reading' (duration: 146.370322ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:34.811573Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068a3.wal"} {"level":"info","ts":"2026-08-24T16:36:42.279082Z","caller":"traceutil/trace.go:171","msg":"trace[1703953339] linearizableReadLoop","detail":"{readStateIndex:26849; appliedIndex:26848; }","duration":"113.442934ms","start":"2026-08-24T16:36:42.165603Z","end":"2026-08-24T16:36:42.279046Z","steps":["trace[1703953339] 'read index received' (duration: 113.119246ms)","trace[1703953339] 'applied index is now lower than readState.Index' (duration: 322.508µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:42.279198Z","caller":"traceutil/trace.go:171","msg":"trace[1771082968] transaction","detail":"{read_only:false; response_revision:25997; number_of_response:1; }","duration":"123.633033ms","start":"2026-08-24T16:36:42.155506Z","end":"2026-08-24T16:36:42.279139Z","steps":["trace[1771082968] 'process raft request' (duration: 123.326976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:42.279478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.847804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:42.279576Z","caller":"traceutil/trace.go:171","msg":"trace[419196923] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25997; }","duration":"113.998667ms","start":"2026-08-24T16:36:42.165553Z","end":"2026-08-24T16:36:42.279552Z","steps":["trace[419196923] 'agreement among raft nodes before linearized reading' (duration: 113.6994ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:42.415879Z","caller":"traceutil/trace.go:171","msg":"trace[52409371] linearizableReadLoop","detail":"{readStateIndex:26850; appliedIndex:26849; }","duration":"136.57013ms","start":"2026-08-24T16:36:42.279269Z","end":"2026-08-24T16:36:42.415839Z","steps":["trace[52409371] 'read index received' (duration: 111.721871ms)","trace[52409371] 'applied index is now lower than readState.Index' (duration: 24.844258ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:42.415872Z","caller":"traceutil/trace.go:171","msg":"trace[1567407137] transaction","detail":"{read_only:false; response_revision:25998; number_of_response:1; }","duration":"169.875077ms","start":"2026-08-24T16:36:42.245945Z","end":"2026-08-24T16:36:42.41582Z","steps":["trace[1567407137] 'process raft request' (duration: 145.017768ms)","trace[1567407137] 'compare' (duration: 24.542941ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:42.416226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.146651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18557"} {"level":"warn","ts":"2026-08-24T16:36:42.416301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.390964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:42.416355Z","caller":"traceutil/trace.go:171","msg":"trace[1880143873] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25999; }","duration":"145.284225ms","start":"2026-08-24T16:36:42.271041Z","end":"2026-08-24T16:36:42.416326Z","steps":["trace[1880143873] 'agreement among raft nodes before linearized reading' (duration: 144.975098ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:42.416479Z","caller":"traceutil/trace.go:171","msg":"trace[640336184] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25999; }","duration":"155.530127ms","start":"2026-08-24T16:36:42.260816Z","end":"2026-08-24T16:36:42.416346Z","steps":["trace[640336184] 'agreement among raft nodes before linearized reading' (duration: 155.143637ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:47.99923Z","caller":"traceutil/trace.go:171","msg":"trace[39995391] linearizableReadLoop","detail":"{readStateIndex:26893; appliedIndex:26892; }","duration":"288.739146ms","start":"2026-08-24T16:36:47.710407Z","end":"2026-08-24T16:36:47.999147Z","steps":["trace[39995391] 'read index received' (duration: 288.630513ms)","trace[39995391] 'applied index is now lower than readState.Index' (duration: 107.533µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:47.99927Z","caller":"traceutil/trace.go:171","msg":"trace[1984654678] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"319.133321ms","start":"2026-08-24T16:36:47.680108Z","end":"2026-08-24T16:36:47.999242Z","steps":["trace[1984654678] 'process raft request' (duration: 318.876133ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:47.99939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:47.680089Z","time spent":"319.238124ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:36:47.999389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.964601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:47.999446Z","caller":"traceutil/trace.go:171","msg":"trace[1307100852] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26040; }","duration":"289.076114ms","start":"2026-08-24T16:36:47.710355Z","end":"2026-08-24T16:36:47.999431Z","steps":["trace[1307100852] 'agreement among raft nodes before linearized reading' (duration: 288.973532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:48.139629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.412165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:48.139679Z","caller":"traceutil/trace.go:171","msg":"trace[1161705630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26041; }","duration":"195.517247ms","start":"2026-08-24T16:36:47.944153Z","end":"2026-08-24T16:36:48.13967Z","steps":["trace[1161705630] 'agreement among raft nodes before linearized reading' (duration: 195.418215ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:48.139646Z","caller":"traceutil/trace.go:171","msg":"trace[1889217527] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"342.272709ms","start":"2026-08-24T16:36:47.79732Z","end":"2026-08-24T16:36:48.139593Z","steps":["trace[1889217527] 'process raft request' (duration: 243.373182ms)","trace[1889217527] 'compare' (duration: 98.650531ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:48.139838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:47.797304Z","time spent":"342.442764ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:36:48.139499Z","caller":"traceutil/trace.go:171","msg":"trace[903023364] linearizableReadLoop","detail":"{readStateIndex:26894; appliedIndex:26893; }","duration":"140.178759ms","start":"2026-08-24T16:36:47.999298Z","end":"2026-08-24T16:36:48.139477Z","steps":["trace[903023364] 'read index received' (duration: 41.379804ms)","trace[903023364] 'applied index is now lower than readState.Index' (duration: 98.798225ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:48.140169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.328219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:276085"} {"level":"info","ts":"2026-08-24T16:36:48.140279Z","caller":"traceutil/trace.go:171","msg":"trace[778591301] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26041; }","duration":"258.524103ms","start":"2026-08-24T16:36:47.881731Z","end":"2026-08-24T16:36:48.140255Z","steps":["trace[778591301] 'agreement among raft nodes before linearized reading' (duration: 257.920149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:48.532686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.770742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:36:48.532823Z","caller":"traceutil/trace.go:171","msg":"trace[79996806] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"229.600813ms","start":"2026-08-24T16:36:48.303165Z","end":"2026-08-24T16:36:48.532766Z","steps":["trace[79996806] 'process raft request' (duration: 104.654857ms)","trace[79996806] 'compare' (duration: 124.596888ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T16:36:48.561021Z","caller":"traceutil/trace.go:171","msg":"trace[7115639] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"127.557159ms","start":"2026-08-24T16:36:48.433441Z","end":"2026-08-24T16:36:48.560999Z","steps":["trace[7115639] 'process raft request' (duration: 127.405095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:48.879949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.305029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:36:48.88005Z","caller":"traceutil/trace.go:171","msg":"trace[1765843082] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"443.113093ms","start":"2026-08-24T16:36:48.43692Z","end":"2026-08-24T16:36:48.880033Z","steps":["trace[1765843082] 'process raft request' (duration: 283.641509ms)","trace[1765843082] 'compare' (duration: 159.073514ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:48.880108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.436902Z","time spent":"443.178545ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:36:49.157403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.963482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T16:36:49.15782Z","caller":"traceutil/trace.go:171","msg":"trace[1089733756] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"545.912695ms","start":"2026-08-24T16:36:48.611884Z","end":"2026-08-24T16:36:49.157797Z","steps":["trace[1089733756] 'process raft request' (duration: 545.637358ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.157832Z","caller":"traceutil/trace.go:171","msg":"trace[1230049266] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"583.445086ms","start":"2026-08-24T16:36:48.574366Z","end":"2026-08-24T16:36:49.157811Z","steps":["trace[1230049266] 'process raft request' (duration: 436.921201ms)","trace[1230049266] 'compare' (duration: 145.757527ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:49.15797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.611846Z","time spent":"546.029608ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:36:49.157973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.574348Z","time spent":"583.582779ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T16:36:49.158004Z","caller":"traceutil/trace.go:171","msg":"trace[1680186868] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"543.414164ms","start":"2026-08-24T16:36:48.614552Z","end":"2026-08-24T16:36:49.157967Z","steps":["trace[1680186868] 'process raft request' (duration: 543.066496ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.158176Z","caller":"traceutil/trace.go:171","msg":"trace[53847891] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"350.248755ms","start":"2026-08-24T16:36:48.807916Z","end":"2026-08-24T16:36:49.158165Z","steps":["trace[53847891] 'process raft request' (duration: 349.952618ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.158192Z","caller":"traceutil/trace.go:171","msg":"trace[977828094] linearizableReadLoop","detail":"{readStateIndex:26901; appliedIndex:26897; }","duration":"342.462203ms","start":"2026-08-24T16:36:48.815708Z","end":"2026-08-24T16:36:49.158171Z","steps":["trace[977828094] 'read index received' (duration: 195.645031ms)","trace[977828094] 'applied index is now lower than readState.Index' (duration: 146.814602ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:49.158187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.614524Z","time spent":"543.561258ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:36:49.158323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.625026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:49.158431Z","caller":"traceutil/trace.go:171","msg":"trace[657050952] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26048; }","duration":"342.860023ms","start":"2026-08-24T16:36:48.815561Z","end":"2026-08-24T16:36:49.158421Z","steps":["trace[657050952] 'agreement among raft nodes before linearized reading' (duration: 342.72181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:49.158581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.815515Z","time spent":"343.003806ms","remote":"127.0.0.1:54138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-08-24T16:36:49.158366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.80787Z","time spent":"350.359467ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T16:36:49.308537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.616132ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T16:36:49.308642Z","caller":"traceutil/trace.go:171","msg":"trace[156868586] linearizableReadLoop","detail":"{readStateIndex:26902; appliedIndex:26901; }","duration":"150.37635ms","start":"2026-08-24T16:36:49.158248Z","end":"2026-08-24T16:36:49.308625Z","steps":["trace[156868586] 'read index received' (duration: 2.555683ms)","trace[156868586] 'applied index is now lower than readState.Index' (duration: 147.819097ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T16:36:49.308988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.150634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-24T16:36:49.308995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.088601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-24T16:36:49.309031Z","caller":"traceutil/trace.go:171","msg":"trace[187665786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26048; }","duration":"441.232357ms","start":"2026-08-24T16:36:48.867788Z","end":"2026-08-24T16:36:49.30902Z","steps":["trace[187665786] 'agreement among raft nodes before linearized reading' (duration: 441.072263ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.309051Z","caller":"traceutil/trace.go:171","msg":"trace[1543806766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26048; }","duration":"326.169283ms","start":"2026-08-24T16:36:48.982865Z","end":"2026-08-24T16:36:49.309034Z","steps":["trace[1543806766] 'agreement among raft nodes before linearized reading' (duration: 325.991419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:49.309066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.867771Z","time spent":"441.286007ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T16:36:49.309095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.982851Z","time spent":"326.234644ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T16:36:49.309108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.409909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-24T16:36:49.309173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.616113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-24T16:36:49.309205Z","caller":"traceutil/trace.go:171","msg":"trace[1798370694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26048; }","duration":"425.559032ms","start":"2026-08-24T16:36:48.883626Z","end":"2026-08-24T16:36:49.309185Z","steps":["trace[1798370694] 'agreement among raft nodes before linearized reading' (duration: 425.247874ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.309261Z","caller":"traceutil/trace.go:171","msg":"trace[977205496] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26048; }","duration":"403.781367ms","start":"2026-08-24T16:36:48.905461Z","end":"2026-08-24T16:36:49.309243Z","steps":["trace[977205496] 'agreement among raft nodes before linearized reading' (duration: 403.465479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:49.30927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.883604Z","time spent":"425.654024ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T16:36:49.309315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.905443Z","time spent":"403.861149ms","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":185,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-24T16:36:49.309347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.127875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:36:49.309396Z","caller":"traceutil/trace.go:171","msg":"trace[1373128802] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26048; }","duration":"126.611126ms","start":"2026-08-24T16:36:49.182772Z","end":"2026-08-24T16:36:49.309383Z","steps":["trace[1373128802] 'agreement among raft nodes before linearized reading' (duration: 126.536995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:49.309458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.694802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-24T16:36:49.3096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.546248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-24T16:36:49.309604Z","caller":"traceutil/trace.go:171","msg":"trace[1272461157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26048; }","duration":"422.909317ms","start":"2026-08-24T16:36:48.886669Z","end":"2026-08-24T16:36:49.309578Z","steps":["trace[1272461157] 'agreement among raft nodes before linearized reading' (duration: 422.560459ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.309648Z","caller":"traceutil/trace.go:171","msg":"trace[2050803320] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26048; }","duration":"109.64869ms","start":"2026-08-24T16:36:49.199986Z","end":"2026-08-24T16:36:49.309635Z","steps":["trace[2050803320] 'agreement among raft nodes before linearized reading' (duration: 109.436035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T16:36:49.309657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T16:36:48.886643Z","time spent":"423.00368ms","remote":"127.0.0.1:51170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-24T16:36:49.310629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.048864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T16:36:49.310933Z","caller":"traceutil/trace.go:171","msg":"trace[693988866] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:26048; }","duration":"221.470705ms","start":"2026-08-24T16:36:49.089408Z","end":"2026-08-24T16:36:49.310878Z","steps":["trace[693988866] 'agreement among raft nodes before linearized reading' (duration: 219.579808ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.611979Z","caller":"traceutil/trace.go:171","msg":"trace[1908673398] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"295.008668ms","start":"2026-08-24T16:36:49.316939Z","end":"2026-08-24T16:36:49.611948Z","steps":["trace[1908673398] 'process raft request' (duration: 294.906516ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.612054Z","caller":"traceutil/trace.go:171","msg":"trace[1725673830] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"293.913611ms","start":"2026-08-24T16:36:49.318077Z","end":"2026-08-24T16:36:49.611991Z","steps":["trace[1725673830] 'process raft request' (duration: 293.797989ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:36:49.612169Z","caller":"traceutil/trace.go:171","msg":"trace[1692117324] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"297.027048ms","start":"2026-08-24T16:36:49.315128Z","end":"2026-08-24T16:36:49.612155Z","steps":["trace[1692117324] 'process raft request' (duration: 296.559347ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T16:41:20.579316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25832} {"level":"info","ts":"2026-08-24T16:41:20.744396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25832,"took":"161.073579ms","hash":733755216,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":36065280,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-24T16:41:20.744502Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":733755216,"revision":25832,"compact-revision":22559} {"level":"info","ts":"2026-08-24T16:42:09.3804Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T16:42:09.386127Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-24T16:42:09.386282Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-24T16:46:20.587433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28705} {"level":"info","ts":"2026-08-24T16:46:20.735189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28705,"took":"143.672219ms","hash":3457038931,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T16:46:20.735268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3457038931,"revision":28705,"compact-revision":25832} {"level":"info","ts":"2026-08-24T16:51:20.595644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30929} {"level":"info","ts":"2026-08-24T16:51:20.724884Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30929,"took":"124.66613ms","hash":2957291711,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T16:51:20.724978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2957291711,"revision":30929,"compact-revision":28705} {"level":"info","ts":"2026-08-24T16:56:20.602839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33188} {"level":"info","ts":"2026-08-24T16:56:20.755583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33188,"took":"146.946348ms","hash":1190388277,"current-db-size-bytes":59871232,"current-db-size":"60 MB","current-db-size-in-use-bytes":30568448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T16:56:20.755654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1190388277,"revision":33188,"compact-revision":30929} {"level":"info","ts":"2026-08-24T17:01:20.612322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35452} {"level":"info","ts":"2026-08-24T17:01:20.772107Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35452,"took":"153.990957ms","hash":3187796831,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":31862784,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T17:01:20.772197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3187796831,"revision":35452,"compact-revision":33188} {"level":"info","ts":"2026-08-24T17:03:33.890338Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T17:03:33.894933Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-24T17:03:33.895021Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-24T17:06:20.622831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37773} {"level":"info","ts":"2026-08-24T17:06:20.749853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37773,"took":"123.072247ms","hash":691613882,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":32997376,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-24T17:06:20.749921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":691613882,"revision":37773,"compact-revision":35452} {"level":"info","ts":"2026-08-24T17:11:20.629551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40082} {"level":"info","ts":"2026-08-24T17:11:20.756372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40082,"took":"122.887462ms","hash":4178429195,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":31367168,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T17:11:20.756435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4178429195,"revision":40082,"compact-revision":37773} {"level":"info","ts":"2026-08-24T17:12:09.866919Z","caller":"traceutil/trace.go:171","msg":"trace[1918796936] transaction","detail":"{read_only:false; response_revision:42678; number_of_response:1; }","duration":"117.206926ms","start":"2026-08-24T17:12:09.749692Z","end":"2026-08-24T17:12:09.866899Z","steps":["trace[1918796936] 'process raft request' (duration: 69.915528ms)","trace[1918796936] 'compare' (duration: 47.142125ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T17:12:09.86718Z","caller":"traceutil/trace.go:171","msg":"trace[1628956504] transaction","detail":"{read_only:false; response_revision:42679; number_of_response:1; }","duration":"114.261276ms","start":"2026-08-24T17:12:09.752889Z","end":"2026-08-24T17:12:09.86715Z","steps":["trace[1628956504] 'process raft request' (duration: 113.977619ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T17:12:09.867159Z","caller":"traceutil/trace.go:171","msg":"trace[200428551] linearizableReadLoop","detail":"{readStateIndex:43999; appliedIndex:43997; }","duration":"114.040441ms","start":"2026-08-24T17:12:09.753072Z","end":"2026-08-24T17:12:09.867112Z","steps":["trace[200428551] 'read index received' (duration: 66.520588ms)","trace[200428551] 'applied index is now lower than readState.Index' (duration: 47.517733ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T17:12:09.86746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.371799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/placement-ks-endpoints\" ","response":"range_response_count:1 size:550"} {"level":"info","ts":"2026-08-24T17:12:09.86754Z","caller":"traceutil/trace.go:171","msg":"trace[1193270251] range","detail":"{range_begin:/registry/serviceaccounts/openstack/placement-ks-endpoints; range_end:; response_count:1; response_revision:42679; }","duration":"114.457371ms","start":"2026-08-24T17:12:09.753063Z","end":"2026-08-24T17:12:09.867521Z","steps":["trace[1193270251] 'agreement among raft nodes before linearized reading' (duration: 114.242836ms)"],"step_count":1}