{"level":"warn","ts":"2026-08-06T19:08:21.012621Z","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-06T19:08:21.012711Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181: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.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-06T19:08:21.012777Z","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-06T19:08:21.012789Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-08-06T19:08:21.012812Z","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-06T19:08:21.013399Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-08-06T19:08:21.013472Z","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.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-06T19:08:21.016301Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.629438ms"} {"level":"info","ts":"2026-08-06T19:08:21.021379Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-08-06T19:08:21.021484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T19:08:21.02156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-08-06T19:08:21.021583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T19:08:21.021645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-08-06T19:08:21.02176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-08-06T19:08:21.027489Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T19:08:21.029598Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T19:08:21.031481Z","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-06T19:08:21.034353Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T19:08:21.034635Z","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-06T19:08:21.035357Z","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-06T19:08:21.03539Z","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-06T19:08:21.034481Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T19:08:21.036638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-08-06T19:08:21.036806Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-08-06T19:08:21.039707Z","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-06T19:08:21.039815Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-08-06T19:08:21.03992Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T19:08:21.039904Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-08-06T19:08:21.039951Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T19:08:21.822299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T19:08:21.822379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T19:08:21.822429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-08-06T19:08:21.822451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-08-06T19:08:21.822463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-08-06T19:08:21.822479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-08-06T19:08:21.822531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-08-06T19:08:21.850296Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T19:08:21.850384Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T19:08:21.850446Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T19:08:21.850699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T19:08:21.85077Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T19:08:21.850882Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:08:21.851855Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:08:21.851995Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:08:21.852047Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T19:08:21.854414Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"info","ts":"2026-08-06T19:08:21.855341Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T19:08:39.182217Z","caller":"traceutil/trace.go:171","msg":"trace[330817025] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"184.193486ms","start":"2026-08-06T19:08:38.997999Z","end":"2026-08-06T19:08:39.182192Z","steps":["trace[330817025] 'process raft request' (duration: 183.990621ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:08:44.92734Z","caller":"traceutil/trace.go:171","msg":"trace[2129454106] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"112.141432ms","start":"2026-08-06T19:08:44.815173Z","end":"2026-08-06T19:08:44.927315Z","steps":["trace[2129454106] 'read index received' (duration: 111.971008ms)","trace[2129454106] 'applied index is now lower than readState.Index' (duration: 169.104µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:08:44.92751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.314366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:08:44.927622Z","caller":"traceutil/trace.go:171","msg":"trace[477506538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"112.47737ms","start":"2026-08-06T19:08:44.815122Z","end":"2026-08-06T19:08:44.927599Z","steps":["trace[477506538] 'agreement among raft nodes before linearized reading' (duration: 112.315626ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:08:45.151676Z","caller":"traceutil/trace.go:171","msg":"trace[374272226] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"223.155679ms","start":"2026-08-06T19:08:44.928493Z","end":"2026-08-06T19:08:45.151648Z","steps":["trace[374272226] 'process raft request' (duration: 209.713974ms)","trace[374272226] 'compare' (duration: 13.284491ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:08:59.546463Z","caller":"traceutil/trace.go:171","msg":"trace[1061553006] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"280.133171ms","start":"2026-08-06T19:08:59.266302Z","end":"2026-08-06T19:08:59.546435Z","steps":["trace[1061553006] 'process raft request' (duration: 279.998928ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:08:59.546599Z","caller":"traceutil/trace.go:171","msg":"trace[1149887077] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:650; }","duration":"279.917326ms","start":"2026-08-06T19:08:59.266566Z","end":"2026-08-06T19:08:59.546483Z","steps":["trace[1149887077] 'read index received' (duration: 279.906956ms)","trace[1149887077] 'applied index is now lower than readState.Index' (duration: 8.44µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:08:59.546712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.117891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-06T19:08:59.546815Z","caller":"traceutil/trace.go:171","msg":"trace[218023615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"280.230303ms","start":"2026-08-06T19:08:59.266562Z","end":"2026-08-06T19:08:59.546793Z","steps":["trace[218023615] 'agreement among raft nodes before linearized reading' (duration: 279.938607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:09:05.853139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.003575ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:09:05.853347Z","caller":"traceutil/trace.go:171","msg":"trace[1392416445] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"152.427666ms","start":"2026-08-06T19:09:05.700893Z","end":"2026-08-06T19:09:05.853321Z","steps":["trace[1392416445] 'read index received' (duration: 46.811µs)","trace[1392416445] 'applied index is now lower than readState.Index' (duration: 152.378785ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:09:05.853425Z","caller":"traceutil/trace.go:171","msg":"trace[25700155] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"218.868902ms","start":"2026-08-06T19:09:05.634525Z","end":"2026-08-06T19:09:05.853394Z","steps":["trace[25700155] 'process raft request' (duration: 39.23922ms)","trace[25700155] 'compare' (duration: 177.77203ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:09:05.853634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.256962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T19:09:05.853736Z","caller":"traceutil/trace.go:171","msg":"trace[2107221189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"100.408686ms","start":"2026-08-06T19:09:05.753301Z","end":"2026-08-06T19:09:05.853709Z","steps":["trace[2107221189] 'agreement among raft nodes before linearized reading' (duration: 100.193681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:09:05.853634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.722292ms","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-06T19:09:05.853852Z","caller":"traceutil/trace.go:171","msg":"trace[1943931755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:691; }","duration":"152.989169ms","start":"2026-08-06T19:09:05.700833Z","end":"2026-08-06T19:09:05.853822Z","steps":["trace[1943931755] 'agreement among raft nodes before linearized reading' (duration: 152.59064ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:09:06.095634Z","caller":"traceutil/trace.go:171","msg":"trace[1511675383] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"237.921303ms","start":"2026-08-06T19:09:05.857682Z","end":"2026-08-06T19:09:06.095603Z","steps":["trace[1511675383] 'process raft request' (duration: 177.294949ms)","trace[1511675383] 'compare' (duration: 60.43304ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:09:06.095898Z","caller":"traceutil/trace.go:171","msg":"trace[1353694611] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"237.888213ms","start":"2026-08-06T19:09:05.857913Z","end":"2026-08-06T19:09:06.095801Z","steps":["trace[1353694611] 'process raft request' (duration: 237.644607ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:09:06.096301Z","caller":"traceutil/trace.go:171","msg":"trace[1228714274] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"237.912293ms","start":"2026-08-06T19:09:05.858357Z","end":"2026-08-06T19:09:06.09627Z","steps":["trace[1228714274] 'process raft request' (duration: 237.364761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:09:06.83933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.826504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-06T19:09:06.839419Z","caller":"traceutil/trace.go:171","msg":"trace[1503556049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"541.961807ms","start":"2026-08-06T19:09:06.297437Z","end":"2026-08-06T19:09:06.839398Z","steps":["trace[1503556049] 'range keys from in-memory index tree' (duration: 541.65678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:09:06.839468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:09:06.297417Z","time spent":"542.041798ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T19:09:22.476657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.709979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-06T19:09:22.47665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.360459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-06T19:09:22.476747Z","caller":"traceutil/trace.go:171","msg":"trace[1216380492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"312.848702ms","start":"2026-08-06T19:09:22.163874Z","end":"2026-08-06T19:09:22.476722Z","steps":["trace[1216380492] 'range keys from in-memory index tree' (duration: 312.505195ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:09:22.476763Z","caller":"traceutil/trace.go:171","msg":"trace[1223232227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"311.519183ms","start":"2026-08-06T19:09:22.165216Z","end":"2026-08-06T19:09:22.476735Z","steps":["trace[1223232227] 'range keys from in-memory index tree' (duration: 311.107284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:09:22.476792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:09:22.163845Z","time spent":"312.937025ms","remote":"127.0.0.1:44762","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-06T19:09:22.476813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:09:22.16519Z","time spent":"311.611295ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T19:09:22.476856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.606325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-06T19:09:22.476979Z","caller":"traceutil/trace.go:171","msg":"trace[400495035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"311.757568ms","start":"2026-08-06T19:09:22.165187Z","end":"2026-08-06T19:09:22.476945Z","steps":["trace[400495035] 'range keys from in-memory index tree' (duration: 310.918439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:09:22.477365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:09:22.165179Z","time spent":"312.167787ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T19:09:53.897854Z","caller":"traceutil/trace.go:171","msg":"trace[942012093] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"116.514119ms","start":"2026-08-06T19:09:53.781318Z","end":"2026-08-06T19:09:53.897832Z","steps":["trace[942012093] 'process raft request' (duration: 116.386626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:37.695339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.830038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:10:37.695466Z","caller":"traceutil/trace.go:171","msg":"trace[2026019018] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1879; }","duration":"171.012762ms","start":"2026-08-06T19:10:37.524424Z","end":"2026-08-06T19:10:37.695437Z","steps":["trace[2026019018] 'count revisions from in-memory index tree' (duration: 170.742956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:38.389651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.388762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-06T19:10:38.38976Z","caller":"traceutil/trace.go:171","msg":"trace[1195331633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1881; }","duration":"111.540394ms","start":"2026-08-06T19:10:38.278196Z","end":"2026-08-06T19:10:38.389737Z","steps":["trace[1195331633] 'range keys from in-memory index tree' (duration: 111.204807ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:10:54.334472Z","caller":"traceutil/trace.go:171","msg":"trace[1666024705] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"305.71607ms","start":"2026-08-06T19:10:54.028732Z","end":"2026-08-06T19:10:54.334448Z","steps":["trace[1666024705] 'process raft request' (duration: 305.528846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:10:54.334638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:10:54.02871Z","time spent":"305.831232ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:10:54.335129Z","caller":"traceutil/trace.go:171","msg":"trace[529891468] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"134.970782ms","start":"2026-08-06T19:10:54.200141Z","end":"2026-08-06T19:10:54.335112Z","steps":["trace[529891468] 'process raft request' (duration: 134.86365ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:27.275135Z","caller":"traceutil/trace.go:171","msg":"trace[1792240426] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2275; }","duration":"177.915374ms","start":"2026-08-06T19:11:27.097198Z","end":"2026-08-06T19:11:27.275114Z","steps":["trace[1792240426] 'read index received' (duration: 26.745088ms)","trace[1792240426] 'applied index is now lower than readState.Index' (duration: 151.169396ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:27.275176Z","caller":"traceutil/trace.go:171","msg":"trace[1948813600] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"188.233542ms","start":"2026-08-06T19:11:27.086914Z","end":"2026-08-06T19:11:27.275147Z","steps":["trace[1948813600] 'process raft request' (duration: 188.058298ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:27.275183Z","caller":"traceutil/trace.go:171","msg":"trace[1625583122] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"179.448149ms","start":"2026-08-06T19:11:27.095704Z","end":"2026-08-06T19:11:27.275153Z","steps":["trace[1625583122] 'process raft request' (duration: 179.377767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:27.275452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.163079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-06T19:11:27.275516Z","caller":"traceutil/trace.go:171","msg":"trace[1904193019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2196; }","duration":"178.246671ms","start":"2026-08-06T19:11:27.097235Z","end":"2026-08-06T19:11:27.275482Z","steps":["trace[1904193019] 'agreement among raft nodes before linearized reading' (duration: 178.097658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:27.275756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.543358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-06T19:11:27.275793Z","caller":"traceutil/trace.go:171","msg":"trace[833162893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2196; }","duration":"178.615249ms","start":"2026-08-06T19:11:27.09717Z","end":"2026-08-06T19:11:27.275785Z","steps":["trace[833162893] 'agreement among raft nodes before linearized reading' (duration: 178.054456ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:33.590507Z","caller":"traceutil/trace.go:171","msg":"trace[151465208] linearizableReadLoop","detail":"{readStateIndex:2312; appliedIndex:2311; }","duration":"290.447489ms","start":"2026-08-06T19:11:33.300034Z","end":"2026-08-06T19:11:33.590482Z","steps":["trace[151465208] 'read index received' (duration: 290.382677ms)","trace[151465208] 'applied index is now lower than readState.Index' (duration: 63.692µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:33.590714Z","caller":"traceutil/trace.go:171","msg":"trace[1540123050] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"294.607924ms","start":"2026-08-06T19:11:33.296069Z","end":"2026-08-06T19:11:33.590677Z","steps":["trace[1540123050] 'process raft request' (duration: 294.277447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:33.59093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.895008ms","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-06T19:11:33.590989Z","caller":"traceutil/trace.go:171","msg":"trace[996562418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2228; }","duration":"291.008591ms","start":"2026-08-06T19:11:33.299966Z","end":"2026-08-06T19:11:33.590974Z","steps":["trace[996562418] 'agreement among raft nodes before linearized reading' (duration: 290.839267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:33.608437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.82673ms","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-06T19:11:33.608459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.351984ms","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-06T19:11:33.608514Z","caller":"traceutil/trace.go:171","msg":"trace[2128489211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2229; }","duration":"247.445956ms","start":"2026-08-06T19:11:33.361055Z","end":"2026-08-06T19:11:33.608501Z","steps":["trace[2128489211] 'agreement among raft nodes before linearized reading' (duration: 247.292812ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:33.608509Z","caller":"traceutil/trace.go:171","msg":"trace[1933118236] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2229; }","duration":"291.955193ms","start":"2026-08-06T19:11:33.316535Z","end":"2026-08-06T19:11:33.60849Z","steps":["trace[1933118236] 'agreement among raft nodes before linearized reading' (duration: 291.709127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:33.608532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.689171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-06T19:11:33.608432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.365054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:11:33.608546Z","caller":"traceutil/trace.go:171","msg":"trace[705597362] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"182.487658ms","start":"2026-08-06T19:11:33.426021Z","end":"2026-08-06T19:11:33.608508Z","steps":["trace[705597362] 'process raft request' (duration: 182.101559ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:33.608601Z","caller":"traceutil/trace.go:171","msg":"trace[1711989768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2229; }","duration":"263.814384ms","start":"2026-08-06T19:11:33.344768Z","end":"2026-08-06T19:11:33.608582Z","steps":["trace[1711989768] 'agreement among raft nodes before linearized reading' (duration: 263.648ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:11:33.608655Z","caller":"traceutil/trace.go:171","msg":"trace[368692454] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2229; }","duration":"110.617851ms","start":"2026-08-06T19:11:33.497997Z","end":"2026-08-06T19:11:33.608615Z","steps":["trace[368692454] 'agreement among raft nodes before linearized reading' (duration: 110.295003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:33.924857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.686218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:11:33.925086Z","caller":"traceutil/trace.go:171","msg":"trace[907053753] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"330.42565ms","start":"2026-08-06T19:11:33.594622Z","end":"2026-08-06T19:11:33.925048Z","steps":["trace[907053753] 'process raft request' (duration: 129.416135ms)","trace[907053753] 'compare' (duration: 200.444442ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:33.925123Z","caller":"traceutil/trace.go:171","msg":"trace[1134849397] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2313; }","duration":"314.650346ms","start":"2026-08-06T19:11:33.610439Z","end":"2026-08-06T19:11:33.92509Z","steps":["trace[1134849397] 'read index received' (duration: 113.517158ms)","trace[1134849397] 'applied index is now lower than readState.Index' (duration: 201.129368ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:11:33.925163Z","caller":"traceutil/trace.go:171","msg":"trace[797742454] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"312.463165ms","start":"2026-08-06T19:11:33.612682Z","end":"2026-08-06T19:11:33.925145Z","steps":["trace[797742454] 'process raft request' (duration: 312.412134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:33.925237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:33.612661Z","time spent":"312.544178ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:33.92527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:33.594599Z","time spent":"330.573604ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:33.925393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.933813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-06T19:11:33.925443Z","caller":"traceutil/trace.go:171","msg":"trace[2054634044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2232; }","duration":"315.024966ms","start":"2026-08-06T19:11:33.610407Z","end":"2026-08-06T19:11:33.925432Z","steps":["trace[2054634044] 'agreement among raft nodes before linearized reading' (duration: 314.78951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:33.925495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:33.610392Z","time spent":"315.095057ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T19:11:33.925409Z","caller":"traceutil/trace.go:171","msg":"trace[1417610347] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"312.828915ms","start":"2026-08-06T19:11:33.612552Z","end":"2026-08-06T19:11:33.925381Z","steps":["trace[1417610347] 'process raft request' (duration: 312.436635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:11:33.925696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:11:33.612525Z","time spent":"313.05632ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:11:34.216492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.877211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:11:34.216629Z","caller":"traceutil/trace.go:171","msg":"trace[896963955] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"285.125856ms","start":"2026-08-06T19:11:33.931478Z","end":"2026-08-06T19:11:34.216604Z","steps":["trace[896963955] 'process raft request' (duration: 142.998028ms)","trace[896963955] 'compare' (duration: 141.632726ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:12:09.022717Z","caller":"traceutil/trace.go:171","msg":"trace[306223944] linearizableReadLoop","detail":"{readStateIndex:2694; appliedIndex:2693; }","duration":"206.488729ms","start":"2026-08-06T19:12:08.816203Z","end":"2026-08-06T19:12:09.022692Z","steps":["trace[306223944] 'read index received' (duration: 206.320845ms)","trace[306223944] 'applied index is now lower than readState.Index' (duration: 166.394µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:12:09.022852Z","caller":"traceutil/trace.go:171","msg":"trace[720743729] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"233.280706ms","start":"2026-08-06T19:12:08.789529Z","end":"2026-08-06T19:12:09.02281Z","steps":["trace[720743729] 'process raft request' (duration: 233.01426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:09.022967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.741934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:12:09.023048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.657902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:12:09.02309Z","caller":"traceutil/trace.go:171","msg":"trace[438128099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2596; }","duration":"206.882807ms","start":"2026-08-06T19:12:08.816183Z","end":"2026-08-06T19:12:09.023066Z","steps":["trace[438128099] 'agreement among raft nodes before linearized reading' (duration: 206.656142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:09.023122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.216255ms","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-06T19:12:09.023136Z","caller":"traceutil/trace.go:171","msg":"trace[1292960859] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2596; }","duration":"137.825636ms","start":"2026-08-06T19:12:08.885292Z","end":"2026-08-06T19:12:09.023117Z","steps":["trace[1292960859] 'agreement among raft nodes before linearized reading' (duration: 137.672052ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:09.023168Z","caller":"traceutil/trace.go:171","msg":"trace[250264517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2596; }","duration":"171.312857ms","start":"2026-08-06T19:12:08.851843Z","end":"2026-08-06T19:12:09.023156Z","steps":["trace[250264517] 'agreement among raft nodes before linearized reading' (duration: 171.052042ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:42.045176Z","caller":"traceutil/trace.go:171","msg":"trace[1414885653] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"112.647656ms","start":"2026-08-06T19:12:41.932477Z","end":"2026-08-06T19:12:42.045124Z","steps":["trace[1414885653] 'process raft request' (duration: 104.018247ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:12:42.673132Z","caller":"traceutil/trace.go:171","msg":"trace[2025555978] linearizableReadLoop","detail":"{readStateIndex:2905; appliedIndex:2904; }","duration":"384.495079ms","start":"2026-08-06T19:12:42.28861Z","end":"2026-08-06T19:12:42.673105Z","steps":["trace[2025555978] 'read index received' (duration: 384.371397ms)","trace[2025555978] 'applied index is now lower than readState.Index' (duration: 122.432µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:12:42.673244Z","caller":"traceutil/trace.go:171","msg":"trace[1702974932] transaction","detail":"{read_only:false; response_revision:2799; number_of_response:1; }","duration":"385.758959ms","start":"2026-08-06T19:12:42.287442Z","end":"2026-08-06T19:12:42.673201Z","steps":["trace[1702974932] 'process raft request' (duration: 385.484332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:42.673409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:42.287422Z","time spent":"385.903152ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:12:42.673523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.867899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T19:12:42.673624Z","caller":"traceutil/trace.go:171","msg":"trace[550618541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2799; }","duration":"385.000162ms","start":"2026-08-06T19:12:42.288603Z","end":"2026-08-06T19:12:42.673603Z","steps":["trace[550618541] 'agreement among raft nodes before linearized reading' (duration: 384.638314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:42.67368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:42.288557Z","time spent":"385.113434ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T19:12:42.678831Z","caller":"traceutil/trace.go:171","msg":"trace[775532729] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"128.997213ms","start":"2026-08-06T19:12:42.549817Z","end":"2026-08-06T19:12:42.678814Z","steps":["trace[775532729] 'process raft request' (duration: 128.8886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:42.679166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.817675ms","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-06T19:12:42.679263Z","caller":"traceutil/trace.go:171","msg":"trace[1069098723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2800; }","duration":"383.951477ms","start":"2026-08-06T19:12:42.295287Z","end":"2026-08-06T19:12:42.679239Z","steps":["trace[1069098723] 'agreement among raft nodes before linearized reading' (duration: 383.62532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:12:42.679337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:12:42.295275Z","time spent":"384.048779ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T19:14:35.725946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.221342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:14:35.726074Z","caller":"traceutil/trace.go:171","msg":"trace[1349620297] transaction","detail":"{read_only:false; response_revision:3562; number_of_response:1; }","duration":"119.04295ms","start":"2026-08-06T19:14:35.607012Z","end":"2026-08-06T19:14:35.726055Z","steps":["trace[1349620297] 'compare' (duration: 115.084519ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:14:35.727567Z","caller":"traceutil/trace.go:171","msg":"trace[561573623] transaction","detail":"{read_only:false; response_revision:3563; number_of_response:1; }","duration":"116.888511ms","start":"2026-08-06T19:14:35.609991Z","end":"2026-08-06T19:14:35.726879Z","steps":["trace[561573623] 'process raft request' (duration: 116.732047ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:15:07.548244Z","caller":"traceutil/trace.go:171","msg":"trace[962416929] linearizableReadLoop","detail":"{readStateIndex:3903; appliedIndex:3902; }","duration":"177.579558ms","start":"2026-08-06T19:15:07.370635Z","end":"2026-08-06T19:15:07.548214Z","steps":["trace[962416929] 'read index received' (duration: 177.375103ms)","trace[962416929] 'applied index is now lower than readState.Index' (duration: 203.165µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:15:07.548361Z","caller":"traceutil/trace.go:171","msg":"trace[1550154681] transaction","detail":"{read_only:false; response_revision:3762; number_of_response:1; }","duration":"178.945819ms","start":"2026-08-06T19:15:07.36938Z","end":"2026-08-06T19:15:07.548326Z","steps":["trace[1550154681] 'process raft request' (duration: 178.659353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:15:07.548522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.880365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T19:15:07.548568Z","caller":"traceutil/trace.go:171","msg":"trace[1604728416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3762; }","duration":"177.982477ms","start":"2026-08-06T19:15:07.370576Z","end":"2026-08-06T19:15:07.548558Z","steps":["trace[1604728416] 'agreement among raft nodes before linearized reading' (duration: 177.795752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:15:07.560333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.848044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-06T19:15:07.560354Z","caller":"traceutil/trace.go:171","msg":"trace[968633673] transaction","detail":"{read_only:false; response_revision:3763; number_of_response:1; }","duration":"189.155734ms","start":"2026-08-06T19:15:07.37116Z","end":"2026-08-06T19:15:07.560315Z","steps":["trace[968633673] 'process raft request' (duration: 188.842947ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:15:07.560423Z","caller":"traceutil/trace.go:171","msg":"trace[1954611866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3763; }","duration":"187.972257ms","start":"2026-08-06T19:15:07.372433Z","end":"2026-08-06T19:15:07.560405Z","steps":["trace[1954611866] 'agreement among raft nodes before linearized reading' (duration: 187.719531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:15:07.560632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.262147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:15:07.560719Z","caller":"traceutil/trace.go:171","msg":"trace[698016223] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:3763; }","duration":"186.412701ms","start":"2026-08-06T19:15:07.374288Z","end":"2026-08-06T19:15:07.560701Z","steps":["trace[698016223] 'agreement among raft nodes before linearized reading' (duration: 186.219126ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:15:07.703403Z","caller":"traceutil/trace.go:171","msg":"trace[1591714737] transaction","detail":"{read_only:false; response_revision:3764; number_of_response:1; }","duration":"149.063321ms","start":"2026-08-06T19:15:07.554312Z","end":"2026-08-06T19:15:07.703376Z","steps":["trace[1591714737] 'process raft request' (duration: 86.369408ms)","trace[1591714737] 'compare' (duration: 62.511499ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:15:07.703617Z","caller":"traceutil/trace.go:171","msg":"trace[1294983632] transaction","detail":"{read_only:false; response_revision:3765; number_of_response:1; }","duration":"138.267693ms","start":"2026-08-06T19:15:07.565302Z","end":"2026-08-06T19:15:07.703569Z","steps":["trace[1294983632] 'process raft request' (duration: 138.032677ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:30.0966Z","caller":"traceutil/trace.go:171","msg":"trace[753258877] linearizableReadLoop","detail":"{readStateIndex:4976; appliedIndex:4975; }","duration":"112.190577ms","start":"2026-08-06T19:16:29.984388Z","end":"2026-08-06T19:16:30.096578Z","steps":["trace[753258877] 'read index received' (duration: 111.992372ms)","trace[753258877] 'applied index is now lower than readState.Index' (duration: 197.185µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:16:30.096713Z","caller":"traceutil/trace.go:171","msg":"trace[1801833341] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"124.03718ms","start":"2026-08-06T19:16:29.972641Z","end":"2026-08-06T19:16:30.096678Z","steps":["trace[1801833341] 'process raft request' (duration: 123.803685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:16:30.096811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.413912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T19:16:30.09684Z","caller":"traceutil/trace.go:171","msg":"trace[204925522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4798; }","duration":"112.503894ms","start":"2026-08-06T19:16:29.984329Z","end":"2026-08-06T19:16:30.096833Z","steps":["trace[204925522] 'agreement among raft nodes before linearized reading' (duration: 112.33894ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:30.589844Z","caller":"traceutil/trace.go:171","msg":"trace[1650004484] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"100.299323ms","start":"2026-08-06T19:16:30.489517Z","end":"2026-08-06T19:16:30.589817Z","steps":["trace[1650004484] 'process raft request' (duration: 100.089398ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:32.552447Z","caller":"traceutil/trace.go:171","msg":"trace[1174474092] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"150.354018ms","start":"2026-08-06T19:16:32.402057Z","end":"2026-08-06T19:16:32.552411Z","steps":["trace[1174474092] 'read index received' (duration: 150.086172ms)","trace[1174474092] 'applied index is now lower than readState.Index' (duration: 266.316µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:16:32.552528Z","caller":"traceutil/trace.go:171","msg":"trace[1795543646] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"170.936511ms","start":"2026-08-06T19:16:32.381542Z","end":"2026-08-06T19:16:32.552478Z","steps":["trace[1795543646] 'process raft request' (duration: 170.668745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:16:32.552735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.658934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9082c37-0d29-41f1-b922-3e5b9fe4fa4f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-06T19:16:32.552805Z","caller":"traceutil/trace.go:171","msg":"trace[1409315475] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9082c37-0d29-41f1-b922-3e5b9fe4fa4f; range_end:; response_count:1; response_revision:4930; }","duration":"150.766886ms","start":"2026-08-06T19:16:32.402021Z","end":"2026-08-06T19:16:32.552788Z","steps":["trace[1409315475] 'agreement among raft nodes before linearized reading' (duration: 150.541241ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:35.090616Z","caller":"traceutil/trace.go:171","msg":"trace[1777738340] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"181.321711ms","start":"2026-08-06T19:16:34.909266Z","end":"2026-08-06T19:16:35.090588Z","steps":["trace[1777738340] 'process raft request' (duration: 181.034065ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:45.660437Z","caller":"traceutil/trace.go:171","msg":"trace[1508424394] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"104.320985ms","start":"2026-08-06T19:16:45.556097Z","end":"2026-08-06T19:16:45.660418Z","steps":["trace[1508424394] 'process raft request' (duration: 104.287214ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:45.660639Z","caller":"traceutil/trace.go:171","msg":"trace[221935379] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"105.485883ms","start":"2026-08-06T19:16:45.555145Z","end":"2026-08-06T19:16:45.660631Z","steps":["trace[221935379] 'process raft request' (duration: 105.201227ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:45.660651Z","caller":"traceutil/trace.go:171","msg":"trace[1338398760] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"134.156284ms","start":"2026-08-06T19:16:45.526469Z","end":"2026-08-06T19:16:45.660625Z","steps":["trace[1338398760] 'process raft request' (duration: 107.015278ms)","trace[1338398760] 'compare' (duration: 26.743127ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:16:46.262011Z","caller":"traceutil/trace.go:171","msg":"trace[1987318076] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"204.373032ms","start":"2026-08-06T19:16:46.057611Z","end":"2026-08-06T19:16:46.261984Z","steps":["trace[1987318076] 'process raft request' (duration: 203.588844ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:53.9079Z","caller":"traceutil/trace.go:171","msg":"trace[967039205] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"216.962222ms","start":"2026-08-06T19:16:53.69091Z","end":"2026-08-06T19:16:53.907873Z","steps":["trace[967039205] 'process raft request' (duration: 216.714427ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:16:54.055895Z","caller":"traceutil/trace.go:171","msg":"trace[1153943011] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"137.350677ms","start":"2026-08-06T19:16:53.918518Z","end":"2026-08-06T19:16:54.055869Z","steps":["trace[1153943011] 'process raft request' (duration: 136.518808ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:18:21.88237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-08-06T19:18:21.969563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"85.662817ms","hash":1683151596,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":32280576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-06T19:18:21.969629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1683151596,"revision":3014,"compact-revision":-1} {"level":"warn","ts":"2026-08-06T19:23:12.522114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.042872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:23:12.522192Z","caller":"traceutil/trace.go:171","msg":"trace[2135331048] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:8247; }","duration":"415.166934ms","start":"2026-08-06T19:23:12.107012Z","end":"2026-08-06T19:23:12.522178Z","steps":["trace[2135331048] 'count revisions from in-memory index tree' (duration: 414.96903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:23:12.522223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:23:12.106998Z","time spent":"415.218096ms","remote":"127.0.0.1:51522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-06T19:23:12.522262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.140635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T19:23:12.522352Z","caller":"traceutil/trace.go:171","msg":"trace[1636541997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8247; }","duration":"228.274629ms","start":"2026-08-06T19:23:12.29406Z","end":"2026-08-06T19:23:12.522334Z","steps":["trace[1636541997] 'range keys from in-memory index tree' (duration: 227.972352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:23:12.522348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.738265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T19:23:12.522432Z","caller":"traceutil/trace.go:171","msg":"trace[291852146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8247; }","duration":"401.867377ms","start":"2026-08-06T19:23:12.120543Z","end":"2026-08-06T19:23:12.522411Z","steps":["trace[291852146] 'range keys from in-memory index tree' (duration: 401.566681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:23:12.522471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:23:12.120526Z","time spent":"401.93644ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T19:23:12.522541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.445258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-06T19:23:12.52262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.69365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:23:12.522591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.65912ms","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-06T19:23:12.522654Z","caller":"traceutil/trace.go:171","msg":"trace[1512041416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8247; }","duration":"434.612682ms","start":"2026-08-06T19:23:12.088015Z","end":"2026-08-06T19:23:12.522628Z","steps":["trace[1512041416] 'range keys from in-memory index tree' (duration: 434.255814ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:12.522679Z","caller":"traceutil/trace.go:171","msg":"trace[1725920244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8247; }","duration":"311.828474ms","start":"2026-08-06T19:23:12.210828Z","end":"2026-08-06T19:23:12.522657Z","steps":["trace[1725920244] 'range keys from in-memory index tree' (duration: 311.432875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:23:12.522721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:23:12.088003Z","time spent":"434.701934ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T19:23:12.522641Z","caller":"traceutil/trace.go:171","msg":"trace[603489459] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:8247; }","duration":"222.768111ms","start":"2026-08-06T19:23:12.299867Z","end":"2026-08-06T19:23:12.522635Z","steps":["trace[603489459] 'count revisions from in-memory index tree' (duration: 222.594328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:23:12.522728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:23:12.2107Z","time spent":"312.019688ms","remote":"127.0.0.1:44762","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-06T19:23:12.731469Z","caller":"traceutil/trace.go:171","msg":"trace[2111756877] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"205.625828ms","start":"2026-08-06T19:23:12.52581Z","end":"2026-08-06T19:23:12.731435Z","steps":["trace[2111756877] 'process raft request' (duration: 205.430644ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:12.784175Z","caller":"traceutil/trace.go:171","msg":"trace[1857124728] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"257.471622ms","start":"2026-08-06T19:23:12.526672Z","end":"2026-08-06T19:23:12.784144Z","steps":["trace[1857124728] 'process raft request' (duration: 257.273386ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:12.78448Z","caller":"traceutil/trace.go:171","msg":"trace[1866092059] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"257.067483ms","start":"2026-08-06T19:23:12.527385Z","end":"2026-08-06T19:23:12.784453Z","steps":["trace[1866092059] 'process raft request' (duration: 256.716995ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:12.784577Z","caller":"traceutil/trace.go:171","msg":"trace[2141259459] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"214.525793ms","start":"2026-08-06T19:23:12.570025Z","end":"2026-08-06T19:23:12.784551Z","steps":["trace[2141259459] 'process raft request' (duration: 214.475222ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:12.784621Z","caller":"traceutil/trace.go:171","msg":"trace[1835879610] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"257.014371ms","start":"2026-08-06T19:23:12.527593Z","end":"2026-08-06T19:23:12.784608Z","steps":["trace[1835879610] 'process raft request' (duration: 256.757976ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:12.784713Z","caller":"traceutil/trace.go:171","msg":"trace[2133747982] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"255.514267ms","start":"2026-08-06T19:23:12.529162Z","end":"2026-08-06T19:23:12.784676Z","steps":["trace[2133747982] 'process raft request' (duration: 255.294972ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:21.890729Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6134} {"level":"info","ts":"2026-08-06T19:23:21.988721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6134,"took":"95.876509ms","hash":3759591726,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":23339008,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-06T19:23:21.988901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3759591726,"revision":6134,"compact-revision":3014} {"level":"warn","ts":"2026-08-06T19:23:24.026175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.533597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:23:24.026245Z","caller":"traceutil/trace.go:171","msg":"trace[795558135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8309; }","duration":"209.6659ms","start":"2026-08-06T19:23:23.81656Z","end":"2026-08-06T19:23:24.026226Z","steps":["trace[795558135] 'range keys from in-memory index tree' (duration: 209.352874ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:24.427237Z","caller":"traceutil/trace.go:171","msg":"trace[568225526] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"353.574506ms","start":"2026-08-06T19:23:24.073634Z","end":"2026-08-06T19:23:24.427208Z","steps":["trace[568225526] 'process raft request' (duration: 351.56985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:23:24.427397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:23:24.07361Z","time spent":"353.701508ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:23:24.474984Z","caller":"traceutil/trace.go:171","msg":"trace[1695552270] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"290.604735ms","start":"2026-08-06T19:23:24.184357Z","end":"2026-08-06T19:23:24.474962Z","steps":["trace[1695552270] 'process raft request' (duration: 290.461262ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:25.066352Z","caller":"traceutil/trace.go:171","msg":"trace[482603202] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"197.91023ms","start":"2026-08-06T19:23:24.868414Z","end":"2026-08-06T19:23:25.066325Z","steps":["trace[482603202] 'process raft request' (duration: 197.750987ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:25.401477Z","caller":"traceutil/trace.go:171","msg":"trace[449295825] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"509.736023ms","start":"2026-08-06T19:23:24.891711Z","end":"2026-08-06T19:23:25.401447Z","steps":["trace[449295825] 'process raft request' (duration: 460.820706ms)","trace[449295825] 'compare' (duration: 48.490767ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:23:25.401628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:23:24.891683Z","time spent":"509.865707ms","remote":"127.0.0.1:44560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:23:49.063985Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000224e.wal"} {"level":"info","ts":"2026-08-06T19:23:54.032883Z","caller":"traceutil/trace.go:171","msg":"trace[1298495418] linearizableReadLoop","detail":"{readStateIndex:8820; appliedIndex:8819; }","duration":"215.570045ms","start":"2026-08-06T19:23:53.817294Z","end":"2026-08-06T19:23:54.032864Z","steps":["trace[1298495418] 'read index received' (duration: 215.416312ms)","trace[1298495418] 'applied index is now lower than readState.Index' (duration: 153.123µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:23:54.032985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.689417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:23:54.033008Z","caller":"traceutil/trace.go:171","msg":"trace[1576594043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8525; }","duration":"215.771869ms","start":"2026-08-06T19:23:53.817228Z","end":"2026-08-06T19:23:54.033Z","steps":["trace[1576594043] 'agreement among raft nodes before linearized reading' (duration: 215.717818ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:54.032979Z","caller":"traceutil/trace.go:171","msg":"trace[913228534] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"241.250166ms","start":"2026-08-06T19:23:53.791682Z","end":"2026-08-06T19:23:54.032932Z","steps":["trace[913228534] 'process raft request' (duration: 241.069432ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:57.409557Z","caller":"traceutil/trace.go:171","msg":"trace[212276244] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"207.148921ms","start":"2026-08-06T19:23:57.20238Z","end":"2026-08-06T19:23:57.409529Z","steps":["trace[212276244] 'process raft request' (duration: 206.991417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:23:57.683774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.151935ms","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-06T19:23:57.683918Z","caller":"traceutil/trace.go:171","msg":"trace[921673008] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8545; }","duration":"205.339449ms","start":"2026-08-06T19:23:57.47855Z","end":"2026-08-06T19:23:57.683889Z","steps":["trace[921673008] 'range keys from in-memory index tree' (duration: 205.005721ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:23:57.687323Z","caller":"traceutil/trace.go:171","msg":"trace[610928587] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"162.921512ms","start":"2026-08-06T19:23:57.52437Z","end":"2026-08-06T19:23:57.687292Z","steps":["trace[610928587] 'process raft request' (duration: 162.683676ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:24:07.157079Z","caller":"traceutil/trace.go:171","msg":"trace[746649744] linearizableReadLoop","detail":"{readStateIndex:8939; appliedIndex:8938; }","duration":"161.304715ms","start":"2026-08-06T19:24:06.995748Z","end":"2026-08-06T19:24:07.157053Z","steps":["trace[746649744] 'read index received' (duration: 132.547633ms)","trace[746649744] 'applied index is now lower than readState.Index' (duration: 28.756042ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:24:07.157103Z","caller":"traceutil/trace.go:171","msg":"trace[388303592] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"184.215643ms","start":"2026-08-06T19:24:06.972855Z","end":"2026-08-06T19:24:07.15707Z","steps":["trace[388303592] 'process raft request' (duration: 155.396539ms)","trace[388303592] 'compare' (duration: 28.694411ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:24:07.15731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.560371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-06T19:24:07.157366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.440046ms","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-06T19:24:07.157387Z","caller":"traceutil/trace.go:171","msg":"trace[1456716101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8637; }","duration":"161.690924ms","start":"2026-08-06T19:24:06.995678Z","end":"2026-08-06T19:24:07.157369Z","steps":["trace[1456716101] 'agreement among raft nodes before linearized reading' (duration: 161.471729ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:24:07.157439Z","caller":"traceutil/trace.go:171","msg":"trace[110043026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8637; }","duration":"101.560838ms","start":"2026-08-06T19:24:07.05586Z","end":"2026-08-06T19:24:07.157421Z","steps":["trace[110043026] 'agreement among raft nodes before linearized reading' (duration: 101.343524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:24:07.157561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.338651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-06T19:24:07.157605Z","caller":"traceutil/trace.go:171","msg":"trace[540390865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8637; }","duration":"100.414312ms","start":"2026-08-06T19:24:07.05718Z","end":"2026-08-06T19:24:07.157594Z","steps":["trace[540390865] 'agreement among raft nodes before linearized reading' (duration: 100.255289ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:24:07.562584Z","caller":"traceutil/trace.go:171","msg":"trace[644222766] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"108.347035ms","start":"2026-08-06T19:24:07.454203Z","end":"2026-08-06T19:24:07.56255Z","steps":["trace[644222766] 'process raft request' (duration: 108.181592ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:24:07.826821Z","caller":"traceutil/trace.go:171","msg":"trace[1089695922] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"104.427045ms","start":"2026-08-06T19:24:07.722373Z","end":"2026-08-06T19:24:07.826801Z","steps":["trace[1089695922] 'process raft request' (duration: 103.962145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:24:08.341672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.920249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:24:08.341748Z","caller":"traceutil/trace.go:171","msg":"trace[1628925195] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8646; }","duration":"294.033211ms","start":"2026-08-06T19:24:08.047699Z","end":"2026-08-06T19:24:08.341732Z","steps":["trace[1628925195] 'count revisions from in-memory index tree' (duration: 293.847577ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:24:08.416722Z","caller":"traceutil/trace.go:171","msg":"trace[1437046994] linearizableReadLoop","detail":"{readStateIndex:8949; appliedIndex:8948; }","duration":"230.899578ms","start":"2026-08-06T19:24:08.185804Z","end":"2026-08-06T19:24:08.416704Z","steps":["trace[1437046994] 'read index received' (duration: 230.718624ms)","trace[1437046994] 'applied index is now lower than readState.Index' (duration: 180.324µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:24:08.417166Z","caller":"traceutil/trace.go:171","msg":"trace[371935004] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"341.858994ms","start":"2026-08-06T19:24:08.075297Z","end":"2026-08-06T19:24:08.417156Z","steps":["trace[371935004] 'process raft request' (duration: 341.286111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:24:08.417257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:24:08.075269Z","time spent":"341.929636ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:24:08.417961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.614907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:24:08.418031Z","caller":"traceutil/trace.go:171","msg":"trace[1523637638] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8647; }","duration":"189.738ms","start":"2026-08-06T19:24:08.228276Z","end":"2026-08-06T19:24:08.418014Z","steps":["trace[1523637638] 'agreement among raft nodes before linearized reading' (duration: 189.600697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:24:08.41851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.707159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-08-06T19:24:08.418552Z","caller":"traceutil/trace.go:171","msg":"trace[1167538269] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8647; }","duration":"232.801942ms","start":"2026-08-06T19:24:08.185739Z","end":"2026-08-06T19:24:08.418541Z","steps":["trace[1167538269] 'agreement among raft nodes before linearized reading' (duration: 231.710297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:25:10.342961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.640029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r4j4d\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-06T19:25:10.343071Z","caller":"traceutil/trace.go:171","msg":"trace[697278277] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r4j4d; range_end:; response_count:1; response_revision:9314; }","duration":"107.807363ms","start":"2026-08-06T19:25:10.235245Z","end":"2026-08-06T19:25:10.343053Z","steps":["trace[697278277] 'range keys from in-memory index tree' (duration: 107.432784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:25:10.343178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.932485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:25:10.343305Z","caller":"traceutil/trace.go:171","msg":"trace[1609540516] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:9314; }","duration":"121.196241ms","start":"2026-08-06T19:25:10.222088Z","end":"2026-08-06T19:25:10.343285Z","steps":["trace[1609540516] 'count revisions from in-memory index tree' (duration: 120.800471ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:25:23.192559Z","caller":"traceutil/trace.go:171","msg":"trace[1020879017] transaction","detail":"{read_only:false; response_revision:9386; number_of_response:1; }","duration":"116.206186ms","start":"2026-08-06T19:25:23.076327Z","end":"2026-08-06T19:25:23.192534Z","steps":["trace[1020879017] 'process raft request' (duration: 116.035922ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:25:23.192562Z","caller":"traceutil/trace.go:171","msg":"trace[947007278] linearizableReadLoop","detail":"{readStateIndex:9708; appliedIndex:9707; }","duration":"116.134184ms","start":"2026-08-06T19:25:23.076394Z","end":"2026-08-06T19:25:23.192529Z","steps":["trace[947007278] 'read index received' (duration: 116.117464ms)","trace[947007278] 'applied index is now lower than readState.Index' (duration: 14.07µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:25:23.19271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.290157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T19:25:23.19277Z","caller":"traceutil/trace.go:171","msg":"trace[582607734] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:9386; }","duration":"116.375419ms","start":"2026-08-06T19:25:23.076385Z","end":"2026-08-06T19:25:23.192761Z","steps":["trace[582607734] 'agreement among raft nodes before linearized reading' (duration: 116.242866ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:26:01.796819Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1a532052a1358225","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T19:26:01.802772Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-06T19:26:01.803001Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-06T19:28:21.896231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8300} {"level":"info","ts":"2026-08-06T19:28:21.973218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8300,"took":"75.111701ms","hash":3377984426,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":24186880,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-06T19:28:21.973284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3377984426,"revision":8300,"compact-revision":6134} {"level":"warn","ts":"2026-08-06T19:29:32.519403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.867554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-db-init-pnm2s\" ","response":"range_response_count:1 size:7097"} {"level":"info","ts":"2026-08-06T19:29:32.519503Z","caller":"traceutil/trace.go:171","msg":"trace[661925217] range","detail":"{range_begin:/registry/pods/openstack/staffeln-db-init-pnm2s; range_end:; response_count:1; response_revision:10990; }","duration":"101.012618ms","start":"2026-08-06T19:29:32.41847Z","end":"2026-08-06T19:29:32.519483Z","steps":["trace[661925217] 'range keys from in-memory index tree' (duration: 100.671839ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:30:58.495386Z","caller":"traceutil/trace.go:171","msg":"trace[604001071] transaction","detail":"{read_only:false; response_revision:11804; number_of_response:1; }","duration":"114.320044ms","start":"2026-08-06T19:30:58.381033Z","end":"2026-08-06T19:30:58.495353Z","steps":["trace[604001071] 'process raft request' (duration: 83.579135ms)","trace[604001071] 'compare' (duration: 30.632566ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:30:58.953491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.599051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T19:30:58.953607Z","caller":"traceutil/trace.go:171","msg":"trace[240810129] linearizableReadLoop","detail":"{readStateIndex:12204; appliedIndex:12203; }","duration":"234.490243ms","start":"2026-08-06T19:30:58.719098Z","end":"2026-08-06T19:30:58.953589Z","steps":["trace[240810129] 'read index received' (duration: 63.705157ms)","trace[240810129] 'applied index is now lower than readState.Index' (duration: 170.783696ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:30:58.953758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.658548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-r4j4d\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-06T19:30:58.953795Z","caller":"traceutil/trace.go:171","msg":"trace[899234387] range","detail":"{range_begin:/registry/pods/openstack/keepalived-r4j4d; range_end:; response_count:1; response_revision:11805; }","duration":"234.732449ms","start":"2026-08-06T19:30:58.719052Z","end":"2026-08-06T19:30:58.953785Z","steps":["trace[899234387] 'agreement among raft nodes before linearized reading' (duration: 234.587605ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:30:58.953638Z","caller":"traceutil/trace.go:171","msg":"trace[1363996278] transaction","detail":"{read_only:false; response_revision:11805; number_of_response:1; }","duration":"390.018787ms","start":"2026-08-06T19:30:58.563594Z","end":"2026-08-06T19:30:58.953613Z","steps":["trace[1363996278] 'process raft request' (duration: 219.19067ms)","trace[1363996278] 'compare' (duration: 170.249224ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:30:58.953978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:30:58.563568Z","time spent":"390.267753ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:30:59.42912Z","caller":"traceutil/trace.go:171","msg":"trace[103481459] transaction","detail":"{read_only:false; response_revision:11810; number_of_response:1; }","duration":"102.82566ms","start":"2026-08-06T19:30:59.32626Z","end":"2026-08-06T19:30:59.429085Z","steps":["trace[103481459] 'process raft request' (duration: 102.685317ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:33:21.903044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10526} {"level":"info","ts":"2026-08-06T19:33:21.993169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10526,"took":"87.215539ms","hash":924512624,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":26365952,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-06T19:33:21.993228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":924512624,"revision":10526,"compact-revision":8300} {"level":"info","ts":"2026-08-06T19:37:30.673379Z","caller":"traceutil/trace.go:171","msg":"trace[971863710] transaction","detail":"{read_only:false; response_revision:14415; number_of_response:1; }","duration":"171.957791ms","start":"2026-08-06T19:37:30.50139Z","end":"2026-08-06T19:37:30.673348Z","steps":["trace[971863710] 'process raft request' (duration: 171.728366ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:37:30.942987Z","caller":"traceutil/trace.go:171","msg":"trace[148287770] linearizableReadLoop","detail":"{readStateIndex:14907; appliedIndex:14906; }","duration":"163.870195ms","start":"2026-08-06T19:37:30.779087Z","end":"2026-08-06T19:37:30.942957Z","steps":["trace[148287770] 'read index received' (duration: 81.114768ms)","trace[148287770] 'applied index is now lower than readState.Index' (duration: 82.754427ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:37:30.943109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.010769ms","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-06T19:37:30.943139Z","caller":"traceutil/trace.go:171","msg":"trace[640517487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14416; }","duration":"164.08879ms","start":"2026-08-06T19:37:30.779043Z","end":"2026-08-06T19:37:30.943132Z","steps":["trace[640517487] 'agreement among raft nodes before linearized reading' (duration: 164.001798ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:37:30.943372Z","caller":"traceutil/trace.go:171","msg":"trace[817036174] transaction","detail":"{read_only:false; response_revision:14416; number_of_response:1; }","duration":"298.146068ms","start":"2026-08-06T19:37:30.645218Z","end":"2026-08-06T19:37:30.943364Z","steps":["trace[817036174] 'process raft request' (duration: 215.040143ms)","trace[817036174] 'compare' (duration: 82.370867ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:37:47.310217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.748215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:37:47.310375Z","caller":"traceutil/trace.go:171","msg":"trace[755339995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14512; }","duration":"494.935559ms","start":"2026-08-06T19:37:46.815386Z","end":"2026-08-06T19:37:47.310322Z","steps":["trace[755339995] 'range keys from in-memory index tree' (duration: 494.561721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:37:47.310421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:37:46.815368Z","time spent":"495.042822ms","remote":"127.0.0.1:44530","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-06T19:37:47.368132Z","caller":"traceutil/trace.go:171","msg":"trace[2098538628] linearizableReadLoop","detail":"{readStateIndex:15009; appliedIndex:15008; }","duration":"146.912915ms","start":"2026-08-06T19:37:47.22119Z","end":"2026-08-06T19:37:47.368103Z","steps":["trace[2098538628] 'read index received' (duration: 146.834663ms)","trace[2098538628] 'applied index is now lower than readState.Index' (duration: 76.872µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:37:47.368254Z","caller":"traceutil/trace.go:171","msg":"trace[689494822] transaction","detail":"{read_only:false; response_revision:14513; number_of_response:1; }","duration":"376.963513ms","start":"2026-08-06T19:37:46.991249Z","end":"2026-08-06T19:37:47.368213Z","steps":["trace[689494822] 'process raft request' (duration: 376.680787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:37:47.368399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.182291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-l4sfw\" ","response":"range_response_count:1 size:12364"} {"level":"warn","ts":"2026-08-06T19:37:47.368418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:37:46.991225Z","time spent":"377.111987ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:37:47.368446Z","caller":"traceutil/trace.go:171","msg":"trace[1198130140] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-l4sfw; range_end:; response_count:1; response_revision:14513; }","duration":"147.275932ms","start":"2026-08-06T19:37:47.221157Z","end":"2026-08-06T19:37:47.368433Z","steps":["trace[1198130140] 'agreement among raft nodes before linearized reading' (duration: 147.091188ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:37:57.238755Z","caller":"traceutil/trace.go:171","msg":"trace[692187801] linearizableReadLoop","detail":"{readStateIndex:15183; appliedIndex:15181; }","duration":"367.817542ms","start":"2026-08-06T19:37:56.870916Z","end":"2026-08-06T19:37:57.238733Z","steps":["trace[692187801] 'read index received' (duration: 1.225057ms)","trace[692187801] 'applied index is now lower than readState.Index' (duration: 366.591495ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:37:57.238797Z","caller":"traceutil/trace.go:171","msg":"trace[1340175653] transaction","detail":"{read_only:false; response_revision:14684; number_of_response:1; }","duration":"367.924555ms","start":"2026-08-06T19:37:56.870819Z","end":"2026-08-06T19:37:57.238744Z","steps":["trace[1340175653] 'process raft request' (duration: 360.799061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:37:57.238914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:37:56.870792Z","time spent":"368.061737ms","remote":"127.0.0.1:44592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T19:37:57.238925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.975236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:37:57.239005Z","caller":"traceutil/trace.go:171","msg":"trace[1950901134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14684; }","duration":"368.112278ms","start":"2026-08-06T19:37:56.870873Z","end":"2026-08-06T19:37:57.238985Z","steps":["trace[1950901134] 'agreement among raft nodes before linearized reading' (duration: 367.939505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:37:57.239042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:37:56.870855Z","time spent":"368.179671ms","remote":"127.0.0.1:44524","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-06T19:37:57.293657Z","caller":"traceutil/trace.go:171","msg":"trace[1295346107] transaction","detail":"{read_only:false; response_revision:14685; number_of_response:1; }","duration":"104.912357ms","start":"2026-08-06T19:37:57.188718Z","end":"2026-08-06T19:37:57.29363Z","steps":["trace[1295346107] 'process raft request' (duration: 104.661411ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:38:21.910451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12777} {"level":"info","ts":"2026-08-06T19:38:21.992797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12777,"took":"79.589194ms","hash":114311021,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":29249536,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-06T19:38:21.993013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":114311021,"revision":12777,"compact-revision":10526} {"level":"warn","ts":"2026-08-06T19:39:01.999142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.8996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-06T19:39:01.99923Z","caller":"traceutil/trace.go:171","msg":"trace[567295963] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15592; }","duration":"152.022283ms","start":"2026-08-06T19:39:01.847188Z","end":"2026-08-06T19:39:01.99921Z","steps":["trace[567295963] 'range keys from in-memory index tree' (duration: 151.688145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:01.99924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.73446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T19:39:01.999232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.231125ms","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-06T19:39:01.999269Z","caller":"traceutil/trace.go:171","msg":"trace[1247981209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15592; }","duration":"182.794101ms","start":"2026-08-06T19:39:01.816467Z","end":"2026-08-06T19:39:01.999262Z","steps":["trace[1247981209] 'range keys from in-memory index tree' (duration: 182.651867ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:39:01.999293Z","caller":"traceutil/trace.go:171","msg":"trace[509805541] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15592; }","duration":"158.319177ms","start":"2026-08-06T19:39:01.840958Z","end":"2026-08-06T19:39:01.999277Z","steps":["trace[509805541] 'range keys from in-memory index tree' (duration: 158.162014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:01.999331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.397836ms","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-06T19:39:01.999417Z","caller":"traceutil/trace.go:171","msg":"trace[1110153305] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15592; }","duration":"157.522788ms","start":"2026-08-06T19:39:01.84187Z","end":"2026-08-06T19:39:01.999393Z","steps":["trace[1110153305] 'range keys from in-memory index tree' (duration: 157.277372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:01.999479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.630002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rsgsr\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-08-06T19:39:01.999566Z","caller":"traceutil/trace.go:171","msg":"trace[519045151] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rsgsr; range_end:; response_count:1; response_revision:15592; }","duration":"164.753255ms","start":"2026-08-06T19:39:01.834794Z","end":"2026-08-06T19:39:01.999547Z","steps":["trace[519045151] 'range keys from in-memory index tree' (duration: 164.452659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:01.999929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.661889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-06T19:39:02.000024Z","caller":"traceutil/trace.go:171","msg":"trace[628843588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15592; }","duration":"163.809473ms","start":"2026-08-06T19:39:01.836195Z","end":"2026-08-06T19:39:02.000004Z","steps":["trace[628843588] 'range keys from in-memory index tree' (duration: 162.80684ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:39:30.245715Z","caller":"traceutil/trace.go:171","msg":"trace[1526451852] linearizableReadLoop","detail":"{readStateIndex:16271; appliedIndex:16270; }","duration":"102.156464ms","start":"2026-08-06T19:39:30.143532Z","end":"2026-08-06T19:39:30.245688Z","steps":["trace[1526451852] 'read index received' (duration: 101.963319ms)","trace[1526451852] 'applied index is now lower than readState.Index' (duration: 192.015µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:39:30.246044Z","caller":"traceutil/trace.go:171","msg":"trace[541041475] transaction","detail":"{read_only:false; response_revision:15741; number_of_response:1; }","duration":"122.182904ms","start":"2026-08-06T19:39:30.123831Z","end":"2026-08-06T19:39:30.246014Z","steps":["trace[541041475] 'process raft request' (duration: 121.692163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:30.24611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.551403ms","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-06T19:39:30.246231Z","caller":"traceutil/trace.go:171","msg":"trace[1966592730] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15741; }","duration":"102.689636ms","start":"2026-08-06T19:39:30.143528Z","end":"2026-08-06T19:39:30.246218Z","steps":["trace[1966592730] 'agreement among raft nodes before linearized reading' (duration: 102.270026ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:39:35.932963Z","caller":"traceutil/trace.go:171","msg":"trace[784392007] transaction","detail":"{read_only:false; response_revision:15767; number_of_response:1; }","duration":"176.314871ms","start":"2026-08-06T19:39:35.756621Z","end":"2026-08-06T19:39:35.932936Z","steps":["trace[784392007] 'process raft request' (duration: 176.086906ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:39:36.020963Z","caller":"traceutil/trace.go:171","msg":"trace[1836375355] linearizableReadLoop","detail":"{readStateIndex:16300; appliedIndex:16298; }","duration":"259.795744ms","start":"2026-08-06T19:39:35.761133Z","end":"2026-08-06T19:39:36.020929Z","steps":["trace[1836375355] 'read index received' (duration: 171.545712ms)","trace[1836375355] 'applied index is now lower than readState.Index' (duration: 88.248722ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:39:36.021047Z","caller":"traceutil/trace.go:171","msg":"trace[1795290065] transaction","detail":"{read_only:false; response_revision:15768; number_of_response:1; }","duration":"261.065123ms","start":"2026-08-06T19:39:35.759944Z","end":"2026-08-06T19:39:36.021009Z","steps":["trace[1795290065] 'process raft request' (duration: 229.397974ms)","trace[1795290065] 'compare' (duration: 31.188398ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:39:36.021356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.388761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T19:39:36.021443Z","caller":"traceutil/trace.go:171","msg":"trace[620521048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15768; }","duration":"159.507084ms","start":"2026-08-06T19:39:35.861912Z","end":"2026-08-06T19:39:36.021419Z","steps":["trace[620521048] 'agreement among raft nodes before linearized reading' (duration: 159.282028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.021616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.219627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T19:39:36.021651Z","caller":"traceutil/trace.go:171","msg":"trace[1404063300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15768; }","duration":"119.275248ms","start":"2026-08-06T19:39:35.902365Z","end":"2026-08-06T19:39:36.02164Z","steps":["trace[1404063300] 'agreement among raft nodes before linearized reading' (duration: 119.191966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.021763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.958613ms","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-06T19:39:36.021793Z","caller":"traceutil/trace.go:171","msg":"trace[163878800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15768; }","duration":"123.030965ms","start":"2026-08-06T19:39:35.898754Z","end":"2026-08-06T19:39:36.021785Z","steps":["trace[163878800] 'agreement among raft nodes before linearized reading' (duration: 122.955083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.021902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.417446ms","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-06T19:39:36.02193Z","caller":"traceutil/trace.go:171","msg":"trace[1260249219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15768; }","duration":"127.516188ms","start":"2026-08-06T19:39:35.894406Z","end":"2026-08-06T19:39:36.021922Z","steps":["trace[1260249219] 'agreement among raft nodes before linearized reading' (duration: 127.446386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.021982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.016429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769810"} {"level":"info","ts":"2026-08-06T19:39:36.022064Z","caller":"traceutil/trace.go:171","msg":"trace[1362801525] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15768; }","duration":"260.129152ms","start":"2026-08-06T19:39:35.761914Z","end":"2026-08-06T19:39:36.022043Z","steps":["trace[1362801525] 'agreement among raft nodes before linearized reading' (duration: 259.255482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.022186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.123656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:39:36.022235Z","caller":"traceutil/trace.go:171","msg":"trace[1033895540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15768; }","duration":"205.204537ms","start":"2026-08-06T19:39:35.817017Z","end":"2026-08-06T19:39:36.022221Z","steps":["trace[1033895540] 'agreement among raft nodes before linearized reading' (duration: 205.107004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.022225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.070854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769810"} {"level":"info","ts":"2026-08-06T19:39:36.022281Z","caller":"traceutil/trace.go:171","msg":"trace[1743311413] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15768; }","duration":"261.179157ms","start":"2026-08-06T19:39:35.761087Z","end":"2026-08-06T19:39:36.022266Z","steps":["trace[1743311413] 'agreement among raft nodes before linearized reading' (duration: 259.970558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.022274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.107391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-06T19:39:36.022342Z","caller":"traceutil/trace.go:171","msg":"trace[1841311574] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15768; }","duration":"227.176623ms","start":"2026-08-06T19:39:35.795147Z","end":"2026-08-06T19:39:36.022323Z","steps":["trace[1841311574] 'agreement among raft nodes before linearized reading' (duration: 226.974628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.022311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.184933ms","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-06T19:39:36.022442Z","caller":"traceutil/trace.go:171","msg":"trace[561543109] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15768; }","duration":"227.330697ms","start":"2026-08-06T19:39:35.795072Z","end":"2026-08-06T19:39:36.022403Z","steps":["trace[561543109] 'agreement among raft nodes before linearized reading' (duration: 227.117212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:36.022637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.910024ms","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-06T19:39:36.022671Z","caller":"traceutil/trace.go:171","msg":"trace[330219304] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15768; }","duration":"228.973494ms","start":"2026-08-06T19:39:35.793688Z","end":"2026-08-06T19:39:36.022662Z","steps":["trace[330219304] 'agreement among raft nodes before linearized reading' (duration: 228.872252ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:39:37.340149Z","caller":"traceutil/trace.go:171","msg":"trace[1623133270] linearizableReadLoop","detail":"{readStateIndex:16307; appliedIndex:16306; }","duration":"111.008176ms","start":"2026-08-06T19:39:37.22912Z","end":"2026-08-06T19:39:37.340128Z","steps":["trace[1623133270] 'read index received' (duration: 110.828562ms)","trace[1623133270] 'applied index is now lower than readState.Index' (duration: 178.604µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:39:37.340319Z","caller":"traceutil/trace.go:171","msg":"trace[724613798] transaction","detail":"{read_only:false; response_revision:15775; number_of_response:1; }","duration":"116.228317ms","start":"2026-08-06T19:39:37.224031Z","end":"2026-08-06T19:39:37.340259Z","steps":["trace[724613798] 'process raft request' (duration: 115.960181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:37.340431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.842615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"warn","ts":"2026-08-06T19:39:37.340437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.419386ms","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-06T19:39:37.340482Z","caller":"traceutil/trace.go:171","msg":"trace[785762650] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15775; }","duration":"104.917726ms","start":"2026-08-06T19:39:37.23555Z","end":"2026-08-06T19:39:37.340467Z","steps":["trace[785762650] 'agreement among raft nodes before linearized reading' (duration: 104.782143ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:39:37.340524Z","caller":"traceutil/trace.go:171","msg":"trace[1523622427] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15775; }","duration":"108.539609ms","start":"2026-08-06T19:39:37.231964Z","end":"2026-08-06T19:39:37.340504Z","steps":["trace[1523622427] 'agreement among raft nodes before linearized reading' (duration: 108.372365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:37.340552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.224933ms","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-06T19:39:37.340614Z","caller":"traceutil/trace.go:171","msg":"trace[453665236] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15775; }","duration":"105.310855ms","start":"2026-08-06T19:39:37.235285Z","end":"2026-08-06T19:39:37.340596Z","steps":["trace[453665236] 'agreement among raft nodes before linearized reading' (duration: 105.158061ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:37.3408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.721404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T19:39:37.340842Z","caller":"traceutil/trace.go:171","msg":"trace[898882059] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15775; }","duration":"108.772475ms","start":"2026-08-06T19:39:37.23206Z","end":"2026-08-06T19:39:37.340833Z","steps":["trace[898882059] 'agreement among raft nodes before linearized reading' (duration: 108.707824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:39:37.341432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.300616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-06T19:39:37.341507Z","caller":"traceutil/trace.go:171","msg":"trace[560404249] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15775; }","duration":"112.43444ms","start":"2026-08-06T19:39:37.229042Z","end":"2026-08-06T19:39:37.341477Z","steps":["trace[560404249] 'agreement among raft nodes before linearized reading' (duration: 111.205751ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:43:21.916557Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15048} {"level":"info","ts":"2026-08-06T19:43:22.018407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15048,"took":"99.02809ms","hash":1570112114,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":30826496,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-06T19:43:22.018534Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1570112114,"revision":15048,"compact-revision":12777} {"level":"warn","ts":"2026-08-06T19:45:17.778832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.520791ms","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-06T19:45:17.77889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.569199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-06T19:45:17.778948Z","caller":"traceutil/trace.go:171","msg":"trace[1856106555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18296; }","duration":"102.678195ms","start":"2026-08-06T19:45:17.676246Z","end":"2026-08-06T19:45:17.778924Z","steps":["trace[1856106555] 'range keys from in-memory index tree' (duration: 102.358188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:45:17.778994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.363735ms","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-06T19:45:17.77909Z","caller":"traceutil/trace.go:171","msg":"trace[1586057286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18296; }","duration":"111.490437ms","start":"2026-08-06T19:45:17.667571Z","end":"2026-08-06T19:45:17.779061Z","steps":["trace[1586057286] 'range keys from in-memory index tree' (duration: 111.185441ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:45:17.779099Z","caller":"traceutil/trace.go:171","msg":"trace[1425337327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18296; }","duration":"111.724983ms","start":"2026-08-06T19:45:17.667262Z","end":"2026-08-06T19:45:17.778987Z","steps":["trace[1425337327] 'range keys from in-memory index tree' (duration: 111.323654ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:45:22.928138Z","caller":"traceutil/trace.go:171","msg":"trace[227527215] transaction","detail":"{read_only:false; response_revision:18383; number_of_response:1; }","duration":"126.619106ms","start":"2026-08-06T19:45:22.801498Z","end":"2026-08-06T19:45:22.928117Z","steps":["trace[227527215] 'process raft request' (duration: 126.501513ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:45:23.61678Z","caller":"traceutil/trace.go:171","msg":"trace[104134573] linearizableReadLoop","detail":"{readStateIndex:19002; appliedIndex:19001; }","duration":"168.48277ms","start":"2026-08-06T19:45:23.448268Z","end":"2026-08-06T19:45:23.616751Z","steps":["trace[104134573] 'read index received' (duration: 130.178968ms)","trace[104134573] 'applied index is now lower than readState.Index' (duration: 38.302762ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:45:23.616815Z","caller":"traceutil/trace.go:171","msg":"trace[2126127585] transaction","detail":"{read_only:false; response_revision:18385; number_of_response:1; }","duration":"401.371494ms","start":"2026-08-06T19:45:23.21542Z","end":"2026-08-06T19:45:23.616792Z","steps":["trace[2126127585] 'process raft request' (duration: 363.047351ms)","trace[2126127585] 'compare' (duration: 37.713529ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:45:23.616982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:45:23.215405Z","time spent":"401.485826ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:45:23.617001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.721305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3299"} {"level":"warn","ts":"2026-08-06T19:45:23.617062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.775724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fade818c-3138-4ba6-9402-3f3643533153\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-08-06T19:45:23.617117Z","caller":"traceutil/trace.go:171","msg":"trace[1922734324] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18385; }","duration":"168.883889ms","start":"2026-08-06T19:45:23.448219Z","end":"2026-08-06T19:45:23.617103Z","steps":["trace[1922734324] 'agreement among raft nodes before linearized reading' (duration: 168.665324ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:45:23.617137Z","caller":"traceutil/trace.go:171","msg":"trace[291143188] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fade818c-3138-4ba6-9402-3f3643533153; range_end:; response_count:1; response_revision:18385; }","duration":"114.889226ms","start":"2026-08-06T19:45:23.502227Z","end":"2026-08-06T19:45:23.617116Z","steps":["trace[291143188] 'agreement among raft nodes before linearized reading' (duration: 114.691542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:45:23.944945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.886965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T19:45:23.945024Z","caller":"traceutil/trace.go:171","msg":"trace[277723946] linearizableReadLoop","detail":"{readStateIndex:19003; appliedIndex:19002; }","duration":"311.872872ms","start":"2026-08-06T19:45:23.633139Z","end":"2026-08-06T19:45:23.945012Z","steps":["trace[277723946] 'read index received' (duration: 101.832485ms)","trace[277723946] 'applied index is now lower than readState.Index' (duration: 210.039397ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:45:23.945064Z","caller":"traceutil/trace.go:171","msg":"trace[1232994577] transaction","detail":"{read_only:false; response_revision:18386; number_of_response:1; }","duration":"313.790247ms","start":"2026-08-06T19:45:23.631268Z","end":"2026-08-06T19:45:23.945059Z","steps":["trace[1232994577] 'process raft request' (duration: 103.691848ms)","trace[1232994577] 'compare' (duration: 209.70333ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:45:23.945318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.139778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9566"} {"level":"info","ts":"2026-08-06T19:45:23.94541Z","caller":"traceutil/trace.go:171","msg":"trace[646452550] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18386; }","duration":"312.267472ms","start":"2026-08-06T19:45:23.633121Z","end":"2026-08-06T19:45:23.945389Z","steps":["trace[646452550] 'agreement among raft nodes before linearized reading' (duration: 312.030057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:45:23.945428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:45:23.631256Z","time spent":"313.824058ms","remote":"127.0.0.1:44592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:45:23.945473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:45:23.633114Z","time spent":"312.345844ms","remote":"127.0.0.1:44940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":9589,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" "} {"level":"warn","ts":"2026-08-06T19:45:23.946405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.119839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:45:23.94647Z","caller":"traceutil/trace.go:171","msg":"trace[1384139800] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:18387; }","duration":"183.2232ms","start":"2026-08-06T19:45:23.763229Z","end":"2026-08-06T19:45:23.946452Z","steps":["trace[1384139800] 'agreement among raft nodes before linearized reading' (duration: 183.108328ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:45:23.946784Z","caller":"traceutil/trace.go:171","msg":"trace[1774691467] transaction","detail":"{read_only:false; response_revision:18387; number_of_response:1; }","duration":"304.196206ms","start":"2026-08-06T19:45:23.642574Z","end":"2026-08-06T19:45:23.94677Z","steps":["trace[1774691467] 'process raft request' (duration: 303.654163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:45:23.946921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:45:23.642547Z","time spent":"304.283499ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:45:23.94775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.611559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:45:23.947797Z","caller":"traceutil/trace.go:171","msg":"trace[865064274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18387; }","duration":"130.761192ms","start":"2026-08-06T19:45:23.817025Z","end":"2026-08-06T19:45:23.947786Z","steps":["trace[865064274] 'agreement among raft nodes before linearized reading' (duration: 130.619439ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:47:04.583911Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1a532052a1358225","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-06T19:47:04.625439Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-06T19:47:04.625537Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-06T19:48:21.92326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17469} {"level":"info","ts":"2026-08-06T19:48:22.029658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17469,"took":"102.153513ms","hash":1997240443,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":34516992,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-06T19:48:22.029724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1997240443,"revision":17469,"compact-revision":15048} {"level":"info","ts":"2026-08-06T19:51:07.530482Z","caller":"traceutil/trace.go:171","msg":"trace[468918851] transaction","detail":"{read_only:false; response_revision:21003; number_of_response:1; }","duration":"311.902013ms","start":"2026-08-06T19:51:07.218553Z","end":"2026-08-06T19:51:07.530455Z","steps":["trace[468918851] 'process raft request' (duration: 311.7507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:07.530677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:07.218536Z","time spent":"312.063176ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:51:24.254424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.373505ms","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-06T19:51:24.254513Z","caller":"traceutil/trace.go:171","msg":"trace[690831765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21086; }","duration":"368.501978ms","start":"2026-08-06T19:51:23.885989Z","end":"2026-08-06T19:51:24.254491Z","steps":["trace[690831765] 'range keys from in-memory index tree' (duration: 368.092228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:24.254554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:23.885971Z","time spent":"368.572799ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T19:51:24.254561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.753091ms","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-06T19:51:24.254631Z","caller":"traceutil/trace.go:171","msg":"trace[358106189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21086; }","duration":"364.837312ms","start":"2026-08-06T19:51:23.889777Z","end":"2026-08-06T19:51:24.254614Z","steps":["trace[358106189] 'range keys from in-memory index tree' (duration: 364.637028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:24.254556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.274511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-06T19:51:24.254926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:23.889769Z","time spent":"365.144789ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T19:51:24.254939Z","caller":"traceutil/trace.go:171","msg":"trace[1104036387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21086; }","duration":"361.7118ms","start":"2026-08-06T19:51:23.893214Z","end":"2026-08-06T19:51:24.254926Z","steps":["trace[1104036387] 'range keys from in-memory index tree' (duration: 361.095147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:24.254979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:23.893194Z","time spent":"361.772942ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T19:51:24.267651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.834688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T19:51:24.267706Z","caller":"traceutil/trace.go:171","msg":"trace[433686528] linearizableReadLoop","detail":"{readStateIndex:21797; appliedIndex:21796; }","duration":"277.298147ms","start":"2026-08-06T19:51:23.990399Z","end":"2026-08-06T19:51:24.267697Z","steps":["trace[433686528] 'read index received' (duration: 82.408938ms)","trace[433686528] 'applied index is now lower than readState.Index' (duration: 194.888449ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:51:24.267753Z","caller":"traceutil/trace.go:171","msg":"trace[1626041496] transaction","detail":"{read_only:false; response_revision:21087; number_of_response:1; }","duration":"292.912427ms","start":"2026-08-06T19:51:23.974832Z","end":"2026-08-06T19:51:24.267745Z","steps":["trace[1626041496] 'process raft request' (duration: 97.935656ms)","trace[1626041496] 'compare' (duration: 181.220644ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:51:24.267831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.43508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-06T19:51:24.267865Z","caller":"traceutil/trace.go:171","msg":"trace[1214394638] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21087; }","duration":"277.497731ms","start":"2026-08-06T19:51:23.990359Z","end":"2026-08-06T19:51:24.267856Z","steps":["trace[1214394638] 'agreement among raft nodes before linearized reading' (duration: 277.414069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:24.67448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.904328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T19:51:24.674628Z","caller":"traceutil/trace.go:171","msg":"trace[883695080] linearizableReadLoop","detail":"{readStateIndex:21800; appliedIndex:21798; }","duration":"406.890861ms","start":"2026-08-06T19:51:24.267725Z","end":"2026-08-06T19:51:24.674616Z","steps":["trace[883695080] 'read index received' (duration: 60.018912ms)","trace[883695080] 'applied index is now lower than readState.Index' (duration: 346.871309ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T19:51:24.674681Z","caller":"traceutil/trace.go:171","msg":"trace[1244494961] transaction","detail":"{read_only:false; response_revision:21090; number_of_response:1; }","duration":"413.997145ms","start":"2026-08-06T19:51:24.260677Z","end":"2026-08-06T19:51:24.674674Z","steps":["trace[1244494961] 'process raft request' (duration: 413.874552ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:51:24.674685Z","caller":"traceutil/trace.go:171","msg":"trace[589250517] transaction","detail":"{read_only:false; response_revision:21089; number_of_response:1; }","duration":"414.365744ms","start":"2026-08-06T19:51:24.2603Z","end":"2026-08-06T19:51:24.674665Z","steps":["trace[589250517] 'process raft request' (duration: 183.2217ms)","trace[589250517] 'compare' (duration: 230.725824ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:51:24.674727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:24.260664Z","time spent":"414.038257ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:51:24.67477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:24.260281Z","time spent":"414.449925ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:51:24.675001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.326054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:51:24.675042Z","caller":"traceutil/trace.go:171","msg":"trace[1091845981] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:21090; }","duration":"672.396926ms","start":"2026-08-06T19:51:24.002636Z","end":"2026-08-06T19:51:24.675033Z","steps":["trace[1091845981] 'agreement among raft nodes before linearized reading' (duration: 672.308984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:24.675065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:24.002624Z","time spent":"672.434157ms","remote":"127.0.0.1:59776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-08-06T19:51:24.763072Z","caller":"traceutil/trace.go:171","msg":"trace[141548616] transaction","detail":"{read_only:false; response_revision:21091; number_of_response:1; }","duration":"358.172758ms","start":"2026-08-06T19:51:24.404876Z","end":"2026-08-06T19:51:24.763048Z","steps":["trace[141548616] 'process raft request' (duration: 358.058025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:24.763225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:24.404831Z","time spent":"358.324061ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:51:25.037081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.226436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T19:51:25.03731Z","caller":"traceutil/trace.go:171","msg":"trace[639557021] transaction","detail":"{read_only:false; response_revision:21093; number_of_response:1; }","duration":"199.975166ms","start":"2026-08-06T19:51:24.837311Z","end":"2026-08-06T19:51:25.037286Z","steps":["trace[639557021] 'process raft request' (duration: 199.899064ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:51:25.037444Z","caller":"traceutil/trace.go:171","msg":"trace[1546814267] linearizableReadLoop","detail":"{readStateIndex:21802; appliedIndex:21800; }","duration":"362.747274ms","start":"2026-08-06T19:51:24.674651Z","end":"2026-08-06T19:51:25.037399Z","steps":["trace[1546814267] 'read index received' (duration: 88.371155ms)","trace[1546814267] 'applied index is now lower than readState.Index' (duration: 274.370759ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:51:25.037573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.703292ms","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":"warn","ts":"2026-08-06T19:51:25.037624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.06112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:51:25.037649Z","caller":"traceutil/trace.go:171","msg":"trace[109921935] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:21093; }","duration":"655.828395ms","start":"2026-08-06T19:51:24.381804Z","end":"2026-08-06T19:51:25.037632Z","steps":["trace[109921935] 'agreement among raft nodes before linearized reading' (duration: 655.62095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:25.037675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.600835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-08-06T19:51:25.037705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:24.38176Z","time spent":"655.934817ms","remote":"127.0.0.1:50254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":27,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-08-06T19:51:25.037691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.154969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:51:25.037659Z","caller":"traceutil/trace.go:171","msg":"trace[1618387223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21093; }","duration":"221.128422ms","start":"2026-08-06T19:51:24.816521Z","end":"2026-08-06T19:51:25.037649Z","steps":["trace[1618387223] 'agreement among raft nodes before linearized reading' (duration: 221.0467ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:51:25.037752Z","caller":"traceutil/trace.go:171","msg":"trace[1329714724] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21093; }","duration":"766.677547ms","start":"2026-08-06T19:51:24.271052Z","end":"2026-08-06T19:51:25.03773Z","steps":["trace[1329714724] 'agreement among raft nodes before linearized reading' (duration: 766.433541ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:51:25.037769Z","caller":"traceutil/trace.go:171","msg":"trace[424457245] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:21093; }","duration":"101.295823ms","start":"2026-08-06T19:51:24.936451Z","end":"2026-08-06T19:51:25.037747Z","steps":["trace[424457245] 'agreement among raft nodes before linearized reading' (duration: 101.14473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:25.037801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:24.271019Z","time spent":"766.769089ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-06T19:51:25.037991Z","caller":"traceutil/trace.go:171","msg":"trace[58451096] transaction","detail":"{read_only:false; response_revision:21092; number_of_response:1; }","duration":"449.285018ms","start":"2026-08-06T19:51:24.588031Z","end":"2026-08-06T19:51:25.037316Z","steps":["trace[58451096] 'process raft request' (duration: 296.710974ms)","trace[58451096] 'compare' (duration: 152.085513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:51:25.03822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:24.588005Z","time spent":"450.081576ms","remote":"127.0.0.1:44762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T19:51:25.438974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.262315ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-06T19:51:25.43905Z","caller":"traceutil/trace.go:171","msg":"trace[190410464] linearizableReadLoop","detail":"{readStateIndex:21804; appliedIndex:21803; }","duration":"399.715766ms","start":"2026-08-06T19:51:25.03932Z","end":"2026-08-06T19:51:25.439036Z","steps":["trace[190410464] 'read index received' (duration: 175.310808ms)","trace[190410464] 'applied index is now lower than readState.Index' (duration: 224.403788ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:51:25.439215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.88999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18465"} {"level":"info","ts":"2026-08-06T19:51:25.43924Z","caller":"traceutil/trace.go:171","msg":"trace[1582593730] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21093; }","duration":"399.956792ms","start":"2026-08-06T19:51:25.039277Z","end":"2026-08-06T19:51:25.439233Z","steps":["trace[1582593730] 'agreement among raft nodes before linearized reading' (duration: 399.828589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:25.439263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:25.03926Z","time spent":"399.998432ms","remote":"127.0.0.1:44688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18488,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-06T19:51:25.439421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.372968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:51:25.439448Z","caller":"traceutil/trace.go:171","msg":"trace[1899892932] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:21093; }","duration":"234.439879ms","start":"2026-08-06T19:51:25.205001Z","end":"2026-08-06T19:51:25.439441Z","steps":["trace[1899892932] 'agreement among raft nodes before linearized reading' (duration: 234.395348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:25.439063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:25.038988Z","time spent":"400.068365ms","remote":"127.0.0.1:44560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-06T19:51:25.43975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.489524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-06T19:51:25.439784Z","caller":"traceutil/trace.go:171","msg":"trace[88983974] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:21093; }","duration":"176.568776ms","start":"2026-08-06T19:51:25.263205Z","end":"2026-08-06T19:51:25.439774Z","steps":["trace[88983974] 'agreement among raft nodes before linearized reading' (duration: 176.435453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:25.858537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.758235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-06T19:51:25.8587Z","caller":"traceutil/trace.go:171","msg":"trace[781574527] transaction","detail":"{read_only:false; response_revision:21094; number_of_response:1; }","duration":"417.397943ms","start":"2026-08-06T19:51:25.441276Z","end":"2026-08-06T19:51:25.858674Z","steps":["trace[781574527] 'process raft request' (duration: 199.377411ms)","trace[781574527] 'compare' (duration: 217.396837ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:51:25.858805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:25.44126Z","time spent":"417.494595ms","remote":"127.0.0.1:44560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T19:51:25.858815Z","caller":"traceutil/trace.go:171","msg":"trace[1152793230] transaction","detail":"{read_only:false; response_revision:21095; number_of_response:1; }","duration":"247.377348ms","start":"2026-08-06T19:51:25.611419Z","end":"2026-08-06T19:51:25.858796Z","steps":["trace[1152793230] 'process raft request' (duration: 247.250155ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:51:25.858819Z","caller":"traceutil/trace.go:171","msg":"trace[762582675] linearizableReadLoop","detail":"{readStateIndex:21805; appliedIndex:21804; }","duration":"407.23753ms","start":"2026-08-06T19:51:25.45155Z","end":"2026-08-06T19:51:25.858787Z","steps":["trace[762582675] 'read index received' (duration: 189.125636ms)","trace[762582675] 'applied index is now lower than readState.Index' (duration: 218.108174ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:51:25.85898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.418024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:51:25.859032Z","caller":"traceutil/trace.go:171","msg":"trace[520068397] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:21095; }","duration":"407.516096ms","start":"2026-08-06T19:51:25.451497Z","end":"2026-08-06T19:51:25.859013Z","steps":["trace[520068397] 'agreement among raft nodes before linearized reading' (duration: 407.423254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:25.859085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:25.451472Z","time spent":"407.600078ms","remote":"127.0.0.1:35180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-08-06T19:51:25.859161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.617496ms","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-06T19:51:25.859202Z","caller":"traceutil/trace.go:171","msg":"trace[1204005023] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21095; }","duration":"360.690107ms","start":"2026-08-06T19:51:25.498501Z","end":"2026-08-06T19:51:25.859191Z","steps":["trace[1204005023] 'agreement among raft nodes before linearized reading' (duration: 360.584855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:51:25.859278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T19:51:25.498486Z","time spent":"360.77814ms","remote":"127.0.0.1:44936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-06T19:51:25.859471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.864289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-06T19:51:25.859537Z","caller":"traceutil/trace.go:171","msg":"trace[666763542] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:21095; }","duration":"237.967851ms","start":"2026-08-06T19:51:25.621554Z","end":"2026-08-06T19:51:25.859522Z","steps":["trace[666763542] 'agreement among raft nodes before linearized reading' (duration: 237.593093ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:53:21.930466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19859} {"level":"info","ts":"2026-08-06T19:53:22.0427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19859,"took":"108.648182ms","hash":3064088305,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":29794304,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-06T19:53:22.042778Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3064088305,"revision":19859,"compact-revision":17469} {"level":"info","ts":"2026-08-06T19:55:22.921378Z","caller":"traceutil/trace.go:171","msg":"trace[1192790044] transaction","detail":"{read_only:false; response_revision:23567; number_of_response:1; }","duration":"257.509741ms","start":"2026-08-06T19:55:22.663779Z","end":"2026-08-06T19:55:22.921289Z","steps":["trace[1192790044] 'process raft request' (duration: 256.905157ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:55:22.99746Z","caller":"traceutil/trace.go:171","msg":"trace[1460467494] linearizableReadLoop","detail":"{readStateIndex:24351; appliedIndex:24348; }","duration":"212.311239ms","start":"2026-08-06T19:55:22.785121Z","end":"2026-08-06T19:55:22.997433Z","steps":["trace[1460467494] 'read index received' (duration: 135.536501ms)","trace[1460467494] 'applied index is now lower than readState.Index' (duration: 76.773458ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T19:55:22.997643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.508993ms","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":"info","ts":"2026-08-06T19:55:22.997692Z","caller":"traceutil/trace.go:171","msg":"trace[104785641] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:23569; }","duration":"212.624956ms","start":"2026-08-06T19:55:22.785053Z","end":"2026-08-06T19:55:22.997678Z","steps":["trace[104785641] 'agreement among raft nodes before linearized reading' (duration: 212.527974ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:55:22.997533Z","caller":"traceutil/trace.go:171","msg":"trace[1801399668] transaction","detail":"{read_only:false; response_revision:23568; number_of_response:1; }","duration":"256.140399ms","start":"2026-08-06T19:55:22.741355Z","end":"2026-08-06T19:55:22.997495Z","steps":["trace[1801399668] 'process raft request' (duration: 255.863972ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:55:22.997706Z","caller":"traceutil/trace.go:171","msg":"trace[380949089] transaction","detail":"{read_only:false; response_revision:23569; number_of_response:1; }","duration":"224.593393ms","start":"2026-08-06T19:55:22.773033Z","end":"2026-08-06T19:55:22.997627Z","steps":["trace[380949089] 'process raft request' (duration: 224.336247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T19:55:22.997962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.271935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-06T19:55:22.998032Z","caller":"traceutil/trace.go:171","msg":"trace[1451929351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23569; }","duration":"181.391138ms","start":"2026-08-06T19:55:22.816627Z","end":"2026-08-06T19:55:22.998018Z","steps":["trace[1451929351] 'agreement among raft nodes before linearized reading' (duration: 181.004938ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:56:53.583509Z","caller":"traceutil/trace.go:171","msg":"trace[772277243] transaction","detail":"{read_only:false; response_revision:24513; number_of_response:1; }","duration":"141.673104ms","start":"2026-08-06T19:56:53.441799Z","end":"2026-08-06T19:56:53.583472Z","steps":["trace[772277243] 'process raft request' (duration: 141.453059ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T19:58:21.936057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22047} {"level":"info","ts":"2026-08-06T19:58:22.042752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22047,"took":"102.655595ms","hash":2086563861,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":43732992,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-06T19:58:22.042996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2086563861,"revision":22047,"compact-revision":19859} {"level":"info","ts":"2026-08-06T20:00:29.392596Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069be.wal"} {"level":"info","ts":"2026-08-06T20:03:21.940852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25187} {"level":"info","ts":"2026-08-06T20:03:22.073825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25187,"took":"128.985392ms","hash":1385922038,"current-db-size-bytes":59015168,"current-db-size":"59 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-06T20:03:22.073877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1385922038,"revision":25187,"compact-revision":22047}