{"level":"warn","ts":"2026-10-15T06:35:59.463631Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:35:59.463742Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.192: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.192:2380","--initial-cluster=instance=https://199.19.213.192:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.192:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.192: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-10-15T06:35:59.463816Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T06:35:59.463827Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-10-15T06:35:59.46385Z","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-10-15T06:35:59.464525Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"]} {"level":"info","ts":"2026-10-15T06:35:59.464606Z","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.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.192: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-10-15T06:35:59.508921Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"44.086908ms"} {"level":"info","ts":"2026-10-15T06:35:59.576752Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"341a3703c39c1f5b","cluster-id":"65341987fa712ba2"} {"level":"info","ts":"2026-10-15T06:35:59.57688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T06:35:59.576905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 0"} {"level":"info","ts":"2026-10-15T06:35:59.576918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 341a3703c39c1f5b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T06:35:59.576929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 1"} {"level":"info","ts":"2026-10-15T06:35:59.576969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"warn","ts":"2026-10-15T06:35:59.594476Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T06:35:59.596257Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T06:35:59.597636Z","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-10-15T06:35:59.60018Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"341a3703c39c1f5b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T06:35:59.600355Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"341a3703c39c1f5b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T06:35:59.600529Z","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-10-15T06:35:59.600643Z","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-10-15T06:35:59.600653Z","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-10-15T06:35:59.602525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"info","ts":"2026-10-15T06:35:59.603253Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","added-peer-id":"341a3703c39c1f5b","added-peer-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-10-15T06:35:59.604747Z","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-10-15T06:35:59.604961Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-10-15T06:35:59.605063Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-10-15T06:35:59.605097Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"341a3703c39c1f5b","initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T06:35:59.605154Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T06:35:59.977878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T06:35:59.977952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T06:35:59.97804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgPreVoteResp from 341a3703c39c1f5b at term 1"} {"level":"info","ts":"2026-10-15T06:35:59.978062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became candidate at term 2"} {"level":"info","ts":"2026-10-15T06:35:59.978073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgVoteResp from 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-10-15T06:35:59.978094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became leader at term 2"} {"level":"info","ts":"2026-10-15T06:35:59.978106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 341a3703c39c1f5b elected leader 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-10-15T06:35:59.979271Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:35:59.980223Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"341a3703c39c1f5b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.192:2379]}","request-path":"/0/members/341a3703c39c1f5b/attributes","cluster-id":"65341987fa712ba2","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T06:35:59.980232Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:35:59.980308Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T06:35:59.980626Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T06:35:59.980718Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T06:35:59.981117Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:35:59.981235Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:35:59.981273Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T06:35:59.982903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T06:35:59.983136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.192:2379"} {"level":"info","ts":"2026-10-15T06:36:24.970501Z","caller":"traceutil/trace.go:171","msg":"trace[983267782] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"153.279809ms","start":"2026-10-15T06:36:24.817192Z","end":"2026-10-15T06:36:24.970472Z","steps":["trace[983267782] 'process raft request' (duration: 153.107686ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.020211Z","caller":"traceutil/trace.go:171","msg":"trace[621971918] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"222.638757ms","start":"2026-10-15T06:36:47.797542Z","end":"2026-10-15T06:36:48.02018Z","steps":["trace[621971918] 'process raft request' (duration: 222.470084ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.030314Z","caller":"traceutil/trace.go:171","msg":"trace[352972220] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"222.367302ms","start":"2026-10-15T06:36:47.807921Z","end":"2026-10-15T06:36:48.030288Z","steps":["trace[352972220] 'process raft request' (duration: 222.117316ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.030677Z","caller":"traceutil/trace.go:171","msg":"trace[2090010077] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"221.621785ms","start":"2026-10-15T06:36:47.809033Z","end":"2026-10-15T06:36:48.030655Z","steps":["trace[2090010077] 'process raft request' (duration: 221.157945ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.031365Z","caller":"traceutil/trace.go:171","msg":"trace[534154672] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"200.491522ms","start":"2026-10-15T06:36:47.830846Z","end":"2026-10-15T06:36:48.031338Z","steps":["trace[534154672] 'process raft request' (duration: 199.760836ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.031515Z","caller":"traceutil/trace.go:171","msg":"trace[401144069] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"193.3909ms","start":"2026-10-15T06:36:47.838109Z","end":"2026-10-15T06:36:48.031499Z","steps":["trace[401144069] 'process raft request' (duration: 193.148225ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.031633Z","caller":"traceutil/trace.go:171","msg":"trace[568899993] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"187.315749ms","start":"2026-10-15T06:36:47.844302Z","end":"2026-10-15T06:36:48.031618Z","steps":["trace[568899993] 'process raft request' (duration: 187.009682ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.032048Z","caller":"traceutil/trace.go:171","msg":"trace[534722876] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"186.677526ms","start":"2026-10-15T06:36:47.845359Z","end":"2026-10-15T06:36:48.032037Z","steps":["trace[534722876] 'process raft request' (duration: 186.443531ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.032203Z","caller":"traceutil/trace.go:171","msg":"trace[824740213] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"185.886909ms","start":"2026-10-15T06:36:47.846308Z","end":"2026-10-15T06:36:48.032194Z","steps":["trace[824740213] 'process raft request' (duration: 185.644183ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.032281Z","caller":"traceutil/trace.go:171","msg":"trace[951159206] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"176.364594ms","start":"2026-10-15T06:36:47.855884Z","end":"2026-10-15T06:36:48.032249Z","steps":["trace[951159206] 'process raft request' (duration: 176.15626ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.032406Z","caller":"traceutil/trace.go:171","msg":"trace[681925679] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"174.740679ms","start":"2026-10-15T06:36:47.857632Z","end":"2026-10-15T06:36:48.032373Z","steps":["trace[681925679] 'process raft request' (duration: 174.535715ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:48.032675Z","caller":"traceutil/trace.go:171","msg":"trace[899675735] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"154.985136ms","start":"2026-10-15T06:36:47.877681Z","end":"2026-10-15T06:36:48.032666Z","steps":["trace[899675735] 'process raft request' (duration: 154.543985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:52.505351Z","caller":"traceutil/trace.go:171","msg":"trace[1562056212] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"206.298836ms","start":"2026-10-15T06:36:52.299011Z","end":"2026-10-15T06:36:52.50531Z","steps":["trace[1562056212] 'process raft request' (duration: 205.585972ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:54.879311Z","caller":"traceutil/trace.go:171","msg":"trace[1332290392] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"309.43943ms","start":"2026-10-15T06:36:54.569842Z","end":"2026-10-15T06:36:54.879282Z","steps":["trace[1332290392] 'read index received' (duration: 308.315235ms)","trace[1332290392] 'applied index is now lower than readState.Index' (duration: 1.123405ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:54.879403Z","caller":"traceutil/trace.go:171","msg":"trace[1128936220] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"310.202435ms","start":"2026-10-15T06:36:54.569164Z","end":"2026-10-15T06:36:54.879366Z","steps":["trace[1128936220] 'process raft request' (duration: 309.383828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:54.879537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.660865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x8n87\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T06:36:54.879648Z","caller":"traceutil/trace.go:171","msg":"trace[1909641460] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x8n87; range_end:; response_count:1; response_revision:657; }","duration":"309.821837ms","start":"2026-10-15T06:36:54.569813Z","end":"2026-10-15T06:36:54.879635Z","steps":["trace[1909641460] 'agreement among raft nodes before linearized reading' (duration: 309.642714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:54.87975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:54.569798Z","time spent":"309.880189ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-x8n87\" "} {"level":"warn","ts":"2026-10-15T06:36:54.879849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.973938ms","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-10-15T06:36:54.879933Z","caller":"traceutil/trace.go:171","msg":"trace[728719929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"225.108129ms","start":"2026-10-15T06:36:54.654803Z","end":"2026-10-15T06:36:54.879911Z","steps":["trace[728719929] 'agreement among raft nodes before linearized reading' (duration: 224.831593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:54.880173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:54.569127Z","time spent":"310.349449ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:36:55.054415Z","caller":"traceutil/trace.go:171","msg":"trace[1616594460] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"170.650062ms","start":"2026-10-15T06:36:54.883727Z","end":"2026-10-15T06:36:55.054377Z","steps":["trace[1616594460] 'process raft request' (duration: 124.810688ms)","trace[1616594460] 'compare' (duration: 45.071967ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:55.068483Z","caller":"traceutil/trace.go:171","msg":"trace[653013744] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"163.898796ms","start":"2026-10-15T06:36:54.904548Z","end":"2026-10-15T06:36:55.068447Z","steps":["trace[653013744] 'process raft request' (duration: 163.654711ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:36:55.068672Z","caller":"traceutil/trace.go:171","msg":"trace[1437271040] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"122.368285ms","start":"2026-10-15T06:36:54.946247Z","end":"2026-10-15T06:36:55.068616Z","steps":["trace[1437271040] 'process raft request' (duration: 122.13037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:55.819126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.021337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-15T06:36:55.819745Z","caller":"traceutil/trace.go:171","msg":"trace[404290526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"636.729722ms","start":"2026-10-15T06:36:55.182961Z","end":"2026-10-15T06:36:55.819691Z","steps":["trace[404290526] 'range keys from in-memory index tree' (duration: 634.899204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:36:55.81983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:36:55.182938Z","time spent":"636.876976ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T06:36:55.823862Z","caller":"traceutil/trace.go:171","msg":"trace[1245450785] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"111.766008ms","start":"2026-10-15T06:36:55.712029Z","end":"2026-10-15T06:36:55.823795Z","steps":["trace[1245450785] 'read index received' (duration: 49.202106ms)","trace[1245450785] 'applied index is now lower than readState.Index' (duration: 62.562152ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:36:55.823886Z","caller":"traceutil/trace.go:171","msg":"trace[446312662] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"283.514384ms","start":"2026-10-15T06:36:55.540303Z","end":"2026-10-15T06:36:55.823818Z","steps":["trace[446312662] 'process raft request' (duration: 221.213947ms)","trace[446312662] 'compare' (duration: 60.482867ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:36:55.824147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.099084ms","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-10-15T06:36:55.824206Z","caller":"traceutil/trace.go:171","msg":"trace[1326508727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"112.250039ms","start":"2026-10-15T06:36:55.711942Z","end":"2026-10-15T06:36:55.824192Z","steps":["trace[1326508727] 'agreement among raft nodes before linearized reading' (duration: 112.020994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:37:12.355292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.087409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:37:12.355414Z","caller":"traceutil/trace.go:171","msg":"trace[2121627941] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"293.290674ms","start":"2026-10-15T06:37:12.0621Z","end":"2026-10-15T06:37:12.35539Z","steps":["trace[2121627941] 'range keys from in-memory index tree' (duration: 292.967566ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:16.16122Z","caller":"traceutil/trace.go:171","msg":"trace[2127460857] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"118.86195ms","start":"2026-10-15T06:37:16.042326Z","end":"2026-10-15T06:37:16.161187Z","steps":["trace[2127460857] 'process raft request' (duration: 118.479673ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:42.310997Z","caller":"traceutil/trace.go:171","msg":"trace[125362867] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"102.687377ms","start":"2026-10-15T06:37:42.208269Z","end":"2026-10-15T06:37:42.310956Z","steps":["trace[125362867] 'process raft request' (duration: 102.526953ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:37:42.466819Z","caller":"traceutil/trace.go:171","msg":"trace[1982585051] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"102.964243ms","start":"2026-10-15T06:37:42.363813Z","end":"2026-10-15T06:37:42.466778Z","steps":["trace[1982585051] 'process raft request' (duration: 85.90834ms)","trace[1982585051] 'compare' (duration: 16.099043ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:38:01.768443Z","caller":"traceutil/trace.go:171","msg":"trace[1089229381] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"109.690996ms","start":"2026-10-15T06:38:01.658719Z","end":"2026-10-15T06:38:01.76841Z","steps":["trace[1089229381] 'process raft request' (duration: 109.486352ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:28.408845Z","caller":"traceutil/trace.go:171","msg":"trace[645358438] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"285.070101ms","start":"2026-10-15T06:38:28.123706Z","end":"2026-10-15T06:38:28.408776Z","steps":["trace[645358438] 'process raft request' (duration: 284.833086ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:28.776585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.296864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:38:28.777242Z","caller":"traceutil/trace.go:171","msg":"trace[184526196] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"407.948349ms","start":"2026-10-15T06:38:28.369251Z","end":"2026-10-15T06:38:28.777199Z","steps":["trace[184526196] 'process raft request' (duration: 169.775176ms)","trace[184526196] 'compare' (duration: 237.151081ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:38:28.777345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.369219Z","time spent":"408.089402ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:38:29.082096Z","caller":"traceutil/trace.go:171","msg":"trace[1131892038] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"666.371513ms","start":"2026-10-15T06:38:28.415684Z","end":"2026-10-15T06:38:29.082055Z","steps":["trace[1131892038] 'process raft request' (duration: 651.248531ms)","trace[1131892038] 'compare' (duration: 14.53744ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:38:29.082366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.415648Z","time spent":"666.593378ms","remote":"127.0.0.1:47924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:38:29.082539Z","caller":"traceutil/trace.go:171","msg":"trace[308071559] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"665.858182ms","start":"2026-10-15T06:38:28.41667Z","end":"2026-10-15T06:38:29.082528Z","steps":["trace[308071559] 'process raft request' (duration: 665.233399ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.082599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.416639Z","time spent":"665.931793ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:38:29.082576Z","caller":"traceutil/trace.go:171","msg":"trace[1921523664] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"665.802151ms","start":"2026-10-15T06:38:28.416733Z","end":"2026-10-15T06:38:29.082535Z","steps":["trace[1921523664] 'process raft request' (duration: 665.660929ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:29.082762Z","caller":"traceutil/trace.go:171","msg":"trace[830590497] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1944; }","duration":"513.912315ms","start":"2026-10-15T06:38:28.568819Z","end":"2026-10-15T06:38:29.082731Z","steps":["trace[830590497] 'read index received' (duration: 498.073178ms)","trace[830590497] 'applied index is now lower than readState.Index' (duration: 15.837307ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:38:29.082801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.416711Z","time spent":"665.971505ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1513,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:38:29.08291Z","caller":"traceutil/trace.go:171","msg":"trace[491109] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"666.052196ms","start":"2026-10-15T06:38:28.416828Z","end":"2026-10-15T06:38:29.082881Z","steps":["trace[491109] 'process raft request' (duration: 665.678589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.08308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.259773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-10-15T06:38:29.083073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.416815Z","time spent":"666.183999ms","remote":"127.0.0.1:47924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":961,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:38:29.083156Z","caller":"traceutil/trace.go:171","msg":"trace[1706205222] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1888; }","duration":"514.379506ms","start":"2026-10-15T06:38:28.568755Z","end":"2026-10-15T06:38:29.083134Z","steps":["trace[1706205222] 'agreement among raft nodes before linearized reading' (duration: 514.112041ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.083211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.568728Z","time spent":"514.469507ms","remote":"127.0.0.1:37326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-15T06:38:29.090733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.032688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-15T06:38:29.090788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.898462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-10-15T06:38:29.090782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.087471ms","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-10-15T06:38:29.09083Z","caller":"traceutil/trace.go:171","msg":"trace[1349345196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1888; }","duration":"483.217743ms","start":"2026-10-15T06:38:28.607592Z","end":"2026-10-15T06:38:29.090809Z","steps":["trace[1349345196] 'agreement among raft nodes before linearized reading' (duration: 482.973458ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:38:29.090855Z","caller":"traceutil/trace.go:171","msg":"trace[946355186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1888; }","duration":"137.209293ms","start":"2026-10-15T06:38:28.953627Z","end":"2026-10-15T06:38:29.090837Z","steps":["trace[946355186] 'agreement among raft nodes before linearized reading' (duration: 136.956757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.090881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.607562Z","time spent":"483.310294ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T06:38:29.090886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.232625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ea1f351-8dec-4de6-82db-2e417c7b3573\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T06:38:29.090925Z","caller":"traceutil/trace.go:171","msg":"trace[1897420804] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0ea1f351-8dec-4de6-82db-2e417c7b3573; range_end:; response_count:1; response_revision:1888; }","duration":"454.324337ms","start":"2026-10-15T06:38:28.636589Z","end":"2026-10-15T06:38:29.090914Z","steps":["trace[1897420804] 'agreement among raft nodes before linearized reading' (duration: 454.237004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.090952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.636568Z","time spent":"454.377598ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0ea1f351-8dec-4de6-82db-2e417c7b3573\" "} {"level":"info","ts":"2026-10-15T06:38:29.090839Z","caller":"traceutil/trace.go:171","msg":"trace[2052446409] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1888; }","duration":"279.988843ms","start":"2026-10-15T06:38:28.810837Z","end":"2026-10-15T06:38:29.090826Z","steps":["trace[2052446409] 'agreement among raft nodes before linearized reading' (duration: 279.848471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.090787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.418914ms","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-10-15T06:38:29.091074Z","caller":"traceutil/trace.go:171","msg":"trace[950905035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1888; }","duration":"312.75408ms","start":"2026-10-15T06:38:28.778307Z","end":"2026-10-15T06:38:29.091061Z","steps":["trace[950905035] 'agreement among raft nodes before linearized reading' (duration: 312.329432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:38:29.09112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:38:28.778284Z","time spent":"312.810222ms","remote":"127.0.0.1:48010","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":"info","ts":"2026-10-15T06:38:37.859099Z","caller":"traceutil/trace.go:171","msg":"trace[212461728] transaction","detail":"{read_only:false; response_revision:1989; number_of_response:1; }","duration":"102.79904ms","start":"2026-10-15T06:38:37.756269Z","end":"2026-10-15T06:38:37.859068Z","steps":["trace[212461728] 'process raft request' (duration: 102.567735ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:17.104554Z","caller":"traceutil/trace.go:171","msg":"trace[979122744] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"115.394069ms","start":"2026-10-15T06:39:16.989119Z","end":"2026-10-15T06:39:17.104513Z","steps":["trace[979122744] 'process raft request' (duration: 115.143184ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:33.695526Z","caller":"traceutil/trace.go:171","msg":"trace[1094049178] linearizableReadLoop","detail":"{readStateIndex:2463; appliedIndex:2462; }","duration":"106.900988ms","start":"2026-10-15T06:39:33.588584Z","end":"2026-10-15T06:39:33.695485Z","steps":["trace[1094049178] 'read index received' (duration: 43.296354ms)","trace[1094049178] 'applied index is now lower than readState.Index' (duration: 63.603084ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:39:33.69554Z","caller":"traceutil/trace.go:171","msg":"trace[188227405] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"142.122208ms","start":"2026-10-15T06:39:33.55338Z","end":"2026-10-15T06:39:33.695502Z","steps":["trace[188227405] 'process raft request' (duration: 78.521043ms)","trace[188227405] 'compare' (duration: 63.408791ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:39:33.695827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.230065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-10-15T06:39:33.695884Z","caller":"traceutil/trace.go:171","msg":"trace[1257146031] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2374; }","duration":"107.342297ms","start":"2026-10-15T06:39:33.588527Z","end":"2026-10-15T06:39:33.69587Z","steps":["trace[1257146031] 'agreement among raft nodes before linearized reading' (duration: 107.116362ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:49.38592Z","caller":"traceutil/trace.go:171","msg":"trace[814541014] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"261.99418ms","start":"2026-10-15T06:39:49.123885Z","end":"2026-10-15T06:39:49.385879Z","steps":["trace[814541014] 'process raft request' (duration: 261.711144ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:39:49.397041Z","caller":"traceutil/trace.go:171","msg":"trace[1690323673] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"103.269007ms","start":"2026-10-15T06:39:49.293501Z","end":"2026-10-15T06:39:49.39677Z","steps":["trace[1690323673] 'process raft request' (duration: 103.079794ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:40:24.276193Z","caller":"traceutil/trace.go:171","msg":"trace[1249790242] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"155.511644ms","start":"2026-10-15T06:40:24.120645Z","end":"2026-10-15T06:40:24.276156Z","steps":["trace[1249790242] 'process raft request' (duration: 155.28457ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:41:47.265682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.470378ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:41:47.26588Z","caller":"traceutil/trace.go:171","msg":"trace[2002642686] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:3502; }","duration":"167.718153ms","start":"2026-10-15T06:41:47.09812Z","end":"2026-10-15T06:41:47.265839Z","steps":["trace[2002642686] 'range keys from in-memory index tree' (duration: 167.437707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:41:47.265814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.691206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-10-15T06:41:47.266179Z","caller":"traceutil/trace.go:171","msg":"trace[1097406491] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3502; }","duration":"111.237487ms","start":"2026-10-15T06:41:47.154901Z","end":"2026-10-15T06:41:47.266138Z","steps":["trace[1097406491] 'range keys from in-memory index tree' (duration: 110.391088ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:42:59.453468Z","caller":"traceutil/trace.go:171","msg":"trace[932662945] transaction","detail":"{read_only:false; response_revision:4411; number_of_response:1; }","duration":"110.385906ms","start":"2026-10-15T06:42:59.343047Z","end":"2026-10-15T06:42:59.453433Z","steps":["trace[932662945] 'process raft request' (duration: 110.176401ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:07.338422Z","caller":"traceutil/trace.go:171","msg":"trace[1718969358] linearizableReadLoop","detail":"{readStateIndex:4829; appliedIndex:4828; }","duration":"127.433939ms","start":"2026-10-15T06:43:07.210959Z","end":"2026-10-15T06:43:07.338393Z","steps":["trace[1718969358] 'read index received' (duration: 127.347688ms)","trace[1718969358] 'applied index is now lower than readState.Index' (duration: 84.901µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:07.338467Z","caller":"traceutil/trace.go:171","msg":"trace[1001519001] transaction","detail":"{read_only:false; response_revision:4626; number_of_response:1; }","duration":"136.557514ms","start":"2026-10-15T06:43:07.201882Z","end":"2026-10-15T06:43:07.338439Z","steps":["trace[1001519001] 'process raft request' (duration: 136.339849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:07.338641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.650484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-10-15T06:43:07.338673Z","caller":"traceutil/trace.go:171","msg":"trace[821947963] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:4626; }","duration":"127.744466ms","start":"2026-10-15T06:43:07.210919Z","end":"2026-10-15T06:43:07.338663Z","steps":["trace[821947963] 'agreement among raft nodes before linearized reading' (duration: 127.578682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:07.343747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.140957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-15T06:43:07.343841Z","caller":"traceutil/trace.go:171","msg":"trace[1838431801] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4627; }","duration":"107.30325ms","start":"2026-10-15T06:43:07.236515Z","end":"2026-10-15T06:43:07.343819Z","steps":["trace[1838431801] 'agreement among raft nodes before linearized reading' (duration: 106.979683ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:09.716392Z","caller":"traceutil/trace.go:171","msg":"trace[2008597102] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"208.11592ms","start":"2026-10-15T06:43:09.508223Z","end":"2026-10-15T06:43:09.716339Z","steps":["trace[2008597102] 'process raft request' (duration: 207.531758ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:09.830947Z","caller":"traceutil/trace.go:171","msg":"trace[1604105594] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"104.423228ms","start":"2026-10-15T06:43:09.726474Z","end":"2026-10-15T06:43:09.830897Z","steps":["trace[1604105594] 'process raft request' (duration: 101.199919ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:12.017226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.438157ms","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-10-15T06:43:12.017319Z","caller":"traceutil/trace.go:171","msg":"trace[1178566314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4762; }","duration":"170.593249ms","start":"2026-10-15T06:43:11.846703Z","end":"2026-10-15T06:43:12.017296Z","steps":["trace[1178566314] 'range keys from in-memory index tree' (duration: 170.234302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:12.017959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.804828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-15T06:43:12.018078Z","caller":"traceutil/trace.go:171","msg":"trace[937029321] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4762; }","duration":"106.031312ms","start":"2026-10-15T06:43:11.912018Z","end":"2026-10-15T06:43:12.018049Z","steps":["trace[937029321] 'range keys from in-memory index tree' (duration: 105.45595ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:12.359721Z","caller":"traceutil/trace.go:171","msg":"trace[1612137628] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"337.151015ms","start":"2026-10-15T06:43:12.02254Z","end":"2026-10-15T06:43:12.359691Z","steps":["trace[1612137628] 'process raft request' (duration: 336.882889ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:12.359959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:12.022521Z","time spent":"337.299987ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:12.359999Z","caller":"traceutil/trace.go:171","msg":"trace[303639230] linearizableReadLoop","detail":"{readStateIndex:4974; appliedIndex:4972; }","duration":"189.319511ms","start":"2026-10-15T06:43:12.170633Z","end":"2026-10-15T06:43:12.359953Z","steps":["trace[303639230] 'read index received' (duration: 188.790829ms)","trace[303639230] 'applied index is now lower than readState.Index' (duration: 525.672µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:12.360143Z","caller":"traceutil/trace.go:171","msg":"trace[627443005] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"337.450991ms","start":"2026-10-15T06:43:12.022654Z","end":"2026-10-15T06:43:12.360105Z","steps":["trace[627443005] 'process raft request' (duration: 336.986122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:12.360267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.602536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-15T06:43:12.360344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:12.022623Z","time spent":"337.603315ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:12.360365Z","caller":"traceutil/trace.go:171","msg":"trace[2002248793] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4765; }","duration":"189.775149ms","start":"2026-10-15T06:43:12.170566Z","end":"2026-10-15T06:43:12.360341Z","steps":["trace[2002248793] 'agreement among raft nodes before linearized reading' (duration: 189.546744ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:12.360652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.726973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:790543"} {"level":"info","ts":"2026-10-15T06:43:12.360685Z","caller":"traceutil/trace.go:171","msg":"trace[394600536] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4765; }","duration":"102.816615ms","start":"2026-10-15T06:43:12.257858Z","end":"2026-10-15T06:43:12.360674Z","steps":["trace[394600536] 'agreement among raft nodes before linearized reading' (duration: 102.281593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:12.832961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.387307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-06cbdebb-829d-482b-bd63-296f605a124e\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-10-15T06:43:12.833044Z","caller":"traceutil/trace.go:171","msg":"trace[2104734074] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-06cbdebb-829d-482b-bd63-296f605a124e; range_end:; response_count:1; response_revision:4765; }","duration":"410.573142ms","start":"2026-10-15T06:43:12.422451Z","end":"2026-10-15T06:43:12.833024Z","steps":["trace[2104734074] 'range keys from in-memory index tree' (duration: 410.110672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:12.833089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:12.422423Z","time spent":"410.652843ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3929,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-06cbdebb-829d-482b-bd63-296f605a124e\" "} {"level":"info","ts":"2026-10-15T06:43:12.833367Z","caller":"traceutil/trace.go:171","msg":"trace[855122966] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"391.893972ms","start":"2026-10-15T06:43:12.441455Z","end":"2026-10-15T06:43:12.833349Z","steps":["trace[855122966] 'process raft request' (duration: 324.671108ms)","trace[855122966] 'compare' (duration: 66.609501ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:12.833375Z","caller":"traceutil/trace.go:171","msg":"trace[242874590] linearizableReadLoop","detail":"{readStateIndex:4975; appliedIndex:4974; }","duration":"126.339745ms","start":"2026-10-15T06:43:12.706974Z","end":"2026-10-15T06:43:12.833313Z","steps":["trace[242874590] 'read index received' (duration: 59.148432ms)","trace[242874590] 'applied index is now lower than readState.Index' (duration: 67.188823ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:12.83347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:12.44142Z","time spent":"391.988035ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:12.83368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.685893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p9xj2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-15T06:43:12.833753Z","caller":"traceutil/trace.go:171","msg":"trace[644404432] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p9xj2; range_end:; response_count:1; response_revision:4766; }","duration":"126.827096ms","start":"2026-10-15T06:43:12.706905Z","end":"2026-10-15T06:43:12.833732Z","steps":["trace[644404432] 'agreement among raft nodes before linearized reading' (duration: 126.564631ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:25.959099Z","caller":"traceutil/trace.go:171","msg":"trace[1502740225] linearizableReadLoop","detail":"{readStateIndex:5218; appliedIndex:5215; }","duration":"107.004333ms","start":"2026-10-15T06:43:25.852057Z","end":"2026-10-15T06:43:25.959061Z","steps":["trace[1502740225] 'read index received' (duration: 22.409938ms)","trace[1502740225] 'applied index is now lower than readState.Index' (duration: 84.592405ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:25.95925Z","caller":"traceutil/trace.go:171","msg":"trace[139024246] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"149.414149ms","start":"2026-10-15T06:43:25.809799Z","end":"2026-10-15T06:43:25.959213Z","steps":["trace[139024246] 'process raft request' (duration: 148.922878ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:25.959515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.445063ms","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-10-15T06:43:25.959576Z","caller":"traceutil/trace.go:171","msg":"trace[1126502492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5000; }","duration":"107.575596ms","start":"2026-10-15T06:43:25.851985Z","end":"2026-10-15T06:43:25.959561Z","steps":["trace[1126502492] 'agreement among raft nodes before linearized reading' (duration: 107.229599ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:25.959554Z","caller":"traceutil/trace.go:171","msg":"trace[183688743] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"148.632661ms","start":"2026-10-15T06:43:25.810885Z","end":"2026-10-15T06:43:25.959518Z","steps":["trace[183688743] 'process raft request' (duration: 148.07138ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:28.156384Z","caller":"traceutil/trace.go:171","msg":"trace[574212432] linearizableReadLoop","detail":"{readStateIndex:5267; appliedIndex:5266; }","duration":"357.846787ms","start":"2026-10-15T06:43:27.798505Z","end":"2026-10-15T06:43:28.156352Z","steps":["trace[574212432] 'read index received' (duration: 260.642752ms)","trace[574212432] 'applied index is now lower than readState.Index' (duration: 97.202585ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:28.156531Z","caller":"traceutil/trace.go:171","msg":"trace[1282353041] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"361.263699ms","start":"2026-10-15T06:43:27.795246Z","end":"2026-10-15T06:43:28.15651Z","steps":["trace[1282353041] 'process raft request' (duration: 263.903411ms)","trace[1282353041] 'compare' (duration: 97.041841ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:43:28.156675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:27.795218Z","time spent":"361.354001ms","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T06:43:28.156817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.255445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-10-15T06:43:28.156896Z","caller":"traceutil/trace.go:171","msg":"trace[402915891] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5048; }","duration":"358.405327ms","start":"2026-10-15T06:43:27.798474Z","end":"2026-10-15T06:43:28.156879Z","steps":["trace[402915891] 'agreement among raft nodes before linearized reading' (duration: 358.163912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:28.156967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:27.79846Z","time spent":"358.49638ms","remote":"127.0.0.1:47908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1803,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-10-15T06:43:28.235107Z","caller":"traceutil/trace.go:171","msg":"trace[173436763] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"435.035883ms","start":"2026-10-15T06:43:27.800022Z","end":"2026-10-15T06:43:28.235058Z","steps":["trace[173436763] 'process raft request' (duration: 434.742456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:28.235143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.991132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T06:43:28.235337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.653763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-15T06:43:28.235517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:43:27.800005Z","time spent":"435.339669ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8036,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:43:28.235575Z","caller":"traceutil/trace.go:171","msg":"trace[2139647724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5049; }","duration":"173.34289ms","start":"2026-10-15T06:43:28.062049Z","end":"2026-10-15T06:43:28.235392Z","steps":["trace[2139647724] 'agreement among raft nodes before linearized reading' (duration: 172.990572ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:28.235481Z","caller":"traceutil/trace.go:171","msg":"trace[814507705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5049; }","duration":"261.798207ms","start":"2026-10-15T06:43:27.973601Z","end":"2026-10-15T06:43:28.235399Z","steps":["trace[814507705] 'agreement among raft nodes before linearized reading' (duration: 261.590892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.690564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.446191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-15T06:43:34.690769Z","caller":"traceutil/trace.go:171","msg":"trace[1906205725] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5207; }","duration":"202.726727ms","start":"2026-10-15T06:43:34.488019Z","end":"2026-10-15T06:43:34.690746Z","steps":["trace[1906205725] 'range keys from in-memory index tree' (duration: 202.226025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:43:34.69079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.420184ms","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-10-15T06:43:34.691006Z","caller":"traceutil/trace.go:171","msg":"trace[60781296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5207; }","duration":"183.610658ms","start":"2026-10-15T06:43:34.507291Z","end":"2026-10-15T06:43:34.690902Z","steps":["trace[60781296] 'range keys from in-memory index tree' (duration: 183.142478ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:34.852825Z","caller":"traceutil/trace.go:171","msg":"trace[1180352921] transaction","detail":"{read_only:false; response_revision:5209; number_of_response:1; }","duration":"155.519868ms","start":"2026-10-15T06:43:34.697262Z","end":"2026-10-15T06:43:34.852782Z","steps":["trace[1180352921] 'process raft request' (duration: 155.296323ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:35.030592Z","caller":"traceutil/trace.go:171","msg":"trace[146124348] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"153.69645ms","start":"2026-10-15T06:43:34.876867Z","end":"2026-10-15T06:43:35.030564Z","steps":["trace[146124348] 'process raft request' (duration: 153.527876ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:43:35.159819Z","caller":"traceutil/trace.go:171","msg":"trace[2124867347] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"108.397602ms","start":"2026-10-15T06:43:35.051388Z","end":"2026-10-15T06:43:35.159786Z","steps":["trace[2124867347] 'process raft request' (duration: 72.788183ms)","trace[2124867347] 'compare' (duration: 35.469727ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:46.764449Z","caller":"traceutil/trace.go:171","msg":"trace[132751047] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"183.082737ms","start":"2026-10-15T06:43:46.581331Z","end":"2026-10-15T06:43:46.764414Z","steps":["trace[132751047] 'process raft request' (duration: 96.328676ms)","trace[132751047] 'compare' (duration: 86.470866ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:43:46.7706Z","caller":"traceutil/trace.go:171","msg":"trace[1639676443] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"184.952916ms","start":"2026-10-15T06:43:46.58563Z","end":"2026-10-15T06:43:46.770582Z","steps":["trace[1639676443] 'process raft request' (duration: 184.850694ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:44:25.455586Z","caller":"traceutil/trace.go:171","msg":"trace[1579275603] transaction","detail":"{read_only:false; response_revision:5749; number_of_response:1; }","duration":"105.016784ms","start":"2026-10-15T06:44:25.350539Z","end":"2026-10-15T06:44:25.455556Z","steps":["trace[1579275603] 'process raft request' (duration: 104.326499ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:46:00.027087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3011} {"level":"info","ts":"2026-10-15T06:46:00.129592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3011,"took":"100.369974ms","hash":1471980117,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":33832960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T06:46:00.129722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1471980117,"revision":3011,"compact-revision":-1} {"level":"info","ts":"2026-10-15T06:49:32.634208Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000226b.wal"} {"level":"info","ts":"2026-10-15T06:49:35.263034Z","caller":"traceutil/trace.go:171","msg":"trace[105564756] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"101.525461ms","start":"2026-10-15T06:49:35.161462Z","end":"2026-10-15T06:49:35.262987Z","steps":["trace[105564756] 'process raft request' (duration: 15.778896ms)","trace[105564756] 'compare' (duration: 85.432309ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:49:36.949975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.337617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T06:49:36.950082Z","caller":"traceutil/trace.go:171","msg":"trace[85388836] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8465; }","duration":"201.501019ms","start":"2026-10-15T06:49:36.748555Z","end":"2026-10-15T06:49:36.950056Z","steps":["trace[85388836] 'range keys from in-memory index tree' (duration: 201.054759ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:49:36.950446Z","caller":"traceutil/trace.go:171","msg":"trace[43346375] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"163.747806ms","start":"2026-10-15T06:49:36.786663Z","end":"2026-10-15T06:49:36.95041Z","steps":["trace[43346375] 'process raft request' (duration: 98.025986ms)","trace[43346375] 'compare' (duration: 65.24927ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:49:48.965301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.953503ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-15T06:49:48.965467Z","caller":"traceutil/trace.go:171","msg":"trace[2021493054] linearizableReadLoop","detail":"{readStateIndex:8932; appliedIndex:8931; }","duration":"286.623211ms","start":"2026-10-15T06:49:48.67882Z","end":"2026-10-15T06:49:48.965444Z","steps":["trace[2021493054] 'read index received' (duration: 28.11µs)","trace[2021493054] 'applied index is now lower than readState.Index' (duration: 286.593281ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:49:48.965622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.793745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-15T06:49:48.965658Z","caller":"traceutil/trace.go:171","msg":"trace[550972237] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8544; }","duration":"286.883738ms","start":"2026-10-15T06:49:48.678764Z","end":"2026-10-15T06:49:48.965648Z","steps":["trace[550972237] 'agreement among raft nodes before linearized reading' (duration: 286.728674ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:49:49.131425Z","caller":"traceutil/trace.go:171","msg":"trace[1300916662] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"160.745201ms","start":"2026-10-15T06:49:48.970649Z","end":"2026-10-15T06:49:49.131394Z","steps":["trace[1300916662] 'process raft request' (duration: 160.542797ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:49:49.141529Z","caller":"traceutil/trace.go:171","msg":"trace[625482067] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"148.606163ms","start":"2026-10-15T06:49:48.992897Z","end":"2026-10-15T06:49:49.141503Z","steps":["trace[625482067] 'process raft request' (duration: 148.424459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:18.037256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.437236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-15T06:50:18.037358Z","caller":"traceutil/trace.go:171","msg":"trace[1372104097] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8791; }","duration":"347.610529ms","start":"2026-10-15T06:50:17.689725Z","end":"2026-10-15T06:50:18.037336Z","steps":["trace[1372104097] 'range keys from in-memory index tree' (duration: 347.22558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:18.037393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:17.689699Z","time spent":"347.685341ms","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T06:50:32.773538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.44304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T06:50:32.773624Z","caller":"traceutil/trace.go:171","msg":"trace[239808233] linearizableReadLoop","detail":"{readStateIndex:9334; appliedIndex:9333; }","duration":"417.610707ms","start":"2026-10-15T06:50:32.356Z","end":"2026-10-15T06:50:32.773611Z","steps":["trace[239808233] 'read index received' (duration: 63.010954ms)","trace[239808233] 'applied index is now lower than readState.Index' (duration: 354.598173ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:50:32.77405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.030656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3028"} {"level":"warn","ts":"2026-10-15T06:50:32.774071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.907133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5lplp\" ","response":"range_response_count:1 size:4595"} {"level":"info","ts":"2026-10-15T06:50:32.77421Z","caller":"traceutil/trace.go:171","msg":"trace[1129933978] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5lplp; range_end:; response_count:1; response_revision:8926; }","duration":"418.110707ms","start":"2026-10-15T06:50:32.356066Z","end":"2026-10-15T06:50:32.774176Z","steps":["trace[1129933978] 'agreement among raft nodes before linearized reading' (duration: 417.766469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.774315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.356054Z","time spent":"418.244639ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4617,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5lplp\" "} {"level":"info","ts":"2026-10-15T06:50:32.774347Z","caller":"traceutil/trace.go:171","msg":"trace[1017825945] transaction","detail":"{read_only:false; response_revision:8926; number_of_response:1; }","duration":"419.124919ms","start":"2026-10-15T06:50:32.355209Z","end":"2026-10-15T06:50:32.774334Z","steps":["trace[1017825945] 'process raft request' (duration: 63.821872ms)","trace[1017825945] 'compare' (duration: 354.337476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:50:32.774408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.355175Z","time spent":"419.20158ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T06:50:32.774156Z","caller":"traceutil/trace.go:171","msg":"trace[773293428] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8926; }","duration":"418.20825ms","start":"2026-10-15T06:50:32.355924Z","end":"2026-10-15T06:50:32.774132Z","steps":["trace[773293428] 'agreement among raft nodes before linearized reading' (duration: 417.796941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.774544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.182307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-10-15T06:50:32.774572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.386324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-10-15T06:50:32.774632Z","caller":"traceutil/trace.go:171","msg":"trace[2038424989] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8926; }","duration":"274.32025ms","start":"2026-10-15T06:50:32.500287Z","end":"2026-10-15T06:50:32.774607Z","steps":["trace[2038424989] 'agreement among raft nodes before linearized reading' (duration: 274.045554ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:32.774639Z","caller":"traceutil/trace.go:171","msg":"trace[2105788111] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8926; }","duration":"418.476875ms","start":"2026-10-15T06:50:32.356144Z","end":"2026-10-15T06:50:32.774621Z","steps":["trace[2105788111] 'agreement among raft nodes before linearized reading' (duration: 418.250691ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.774559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.355865Z","time spent":"418.680919ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":3050,"request content":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-10-15T06:50:32.774699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.356132Z","time spent":"418.555686ms","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":553,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" "} {"level":"warn","ts":"2026-10-15T06:50:32.774915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.600845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-mon\" ","response":"range_response_count:1 size:713"} {"level":"info","ts":"2026-10-15T06:50:32.774982Z","caller":"traceutil/trace.go:171","msg":"trace[2128478361] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-mon; range_end:; response_count:1; response_revision:8926; }","duration":"408.720768ms","start":"2026-10-15T06:50:32.366241Z","end":"2026-10-15T06:50:32.774962Z","steps":["trace[2128478361] 'agreement among raft nodes before linearized reading' (duration: 408.124635ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:32.775025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T06:50:32.366228Z","time spent":"408.783678ms","remote":"127.0.0.1:47830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":735,"request content":"key:\"/registry/secrets/openstack/rook-ceph-mon\" "} {"level":"warn","ts":"2026-10-15T06:50:32.775464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.922762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5lplp\" ","response":"range_response_count:1 size:4595"} {"level":"info","ts":"2026-10-15T06:50:32.775534Z","caller":"traceutil/trace.go:171","msg":"trace[776514258] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5lplp; range_end:; response_count:1; response_revision:8926; }","duration":"153.192327ms","start":"2026-10-15T06:50:32.622321Z","end":"2026-10-15T06:50:32.775514Z","steps":["trace[776514258] 'agreement among raft nodes before linearized reading' (duration: 152.046383ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:33.643315Z","caller":"traceutil/trace.go:171","msg":"trace[1399090016] transaction","detail":"{read_only:false; response_revision:8938; number_of_response:1; }","duration":"143.00079ms","start":"2026-10-15T06:50:33.500274Z","end":"2026-10-15T06:50:33.643275Z","steps":["trace[1399090016] 'process raft request' (duration: 142.715985ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:50:33.910035Z","caller":"traceutil/trace.go:171","msg":"trace[421629974] linearizableReadLoop","detail":"{readStateIndex:9352; appliedIndex:9351; }","duration":"116.492935ms","start":"2026-10-15T06:50:33.793504Z","end":"2026-10-15T06:50:33.909997Z","steps":["trace[421629974] 'read index received' (duration: 116.229759ms)","trace[421629974] 'applied index is now lower than readState.Index' (duration: 261.956µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:50:33.910096Z","caller":"traceutil/trace.go:171","msg":"trace[365000089] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"124.363212ms","start":"2026-10-15T06:50:33.785708Z","end":"2026-10-15T06:50:33.910071Z","steps":["trace[365000089] 'process raft request' (duration: 124.081606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T06:50:33.910221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.679259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T06:50:33.910334Z","caller":"traceutil/trace.go:171","msg":"trace[1518280405] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:8942; }","duration":"116.869772ms","start":"2026-10-15T06:50:33.793446Z","end":"2026-10-15T06:50:33.910315Z","steps":["trace[1518280405] 'agreement among raft nodes before linearized reading' (duration: 116.687349ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:00.036486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6496} {"level":"info","ts":"2026-10-15T06:51:00.157691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6496,"took":"117.482016ms","hash":17453742,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":26406912,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T06:51:00.157797Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":17453742,"revision":6496,"compact-revision":3011} {"level":"info","ts":"2026-10-15T06:51:29.231969Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T06:51:29.240053Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T06:51:29.240225Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T06:51:50.053864Z","caller":"traceutil/trace.go:171","msg":"trace[715721194] transaction","detail":"{read_only:false; response_revision:9729; number_of_response:1; }","duration":"138.794081ms","start":"2026-10-15T06:51:49.915036Z","end":"2026-10-15T06:51:50.05383Z","steps":["trace[715721194] 'process raft request' (duration: 138.597436ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:50.209649Z","caller":"traceutil/trace.go:171","msg":"trace[930672471] linearizableReadLoop","detail":"{readStateIndex:10177; appliedIndex:10176; }","duration":"118.791654ms","start":"2026-10-15T06:51:50.090823Z","end":"2026-10-15T06:51:50.209614Z","steps":["trace[930672471] 'read index received' (duration: 84.984093ms)","trace[930672471] 'applied index is now lower than readState.Index' (duration: 33.806161ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:51:50.209723Z","caller":"traceutil/trace.go:171","msg":"trace[799650909] transaction","detail":"{read_only:false; response_revision:9730; number_of_response:1; }","duration":"145.981964ms","start":"2026-10-15T06:51:50.06371Z","end":"2026-10-15T06:51:50.209692Z","steps":["trace[799650909] 'process raft request' (duration: 112.089561ms)","trace[799650909] 'compare' (duration: 33.545636ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T06:51:50.209978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.06898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-15T06:51:50.210057Z","caller":"traceutil/trace.go:171","msg":"trace[1297487139] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9730; }","duration":"119.287865ms","start":"2026-10-15T06:51:50.090748Z","end":"2026-10-15T06:51:50.210036Z","steps":["trace[1297487139] 'agreement among raft nodes before linearized reading' (duration: 118.965348ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T06:51:50.357344Z","caller":"traceutil/trace.go:171","msg":"trace[283205488] transaction","detail":"{read_only:false; response_revision:9732; number_of_response:1; }","duration":"141.975978ms","start":"2026-10-15T06:51:50.215293Z","end":"2026-10-15T06:51:50.357269Z","steps":["trace[283205488] 'process raft request' (duration: 128.92933ms)","trace[283205488] 'compare' (duration: 12.448886ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T06:56:00.04474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9268} {"level":"info","ts":"2026-10-15T06:56:00.148362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9268,"took":"100.270751ms","hash":1966160189,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":27910144,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T06:56:00.148433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1966160189,"revision":9268,"compact-revision":6496} {"level":"info","ts":"2026-10-15T07:01:00.051951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12162} {"level":"info","ts":"2026-10-15T07:01:00.164808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12162,"took":"110.187442ms","hash":2738213287,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":25563136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T07:01:00.164885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2738213287,"revision":12162,"compact-revision":9268} {"level":"info","ts":"2026-10-15T07:01:56.069491Z","caller":"traceutil/trace.go:171","msg":"trace[1917496295] transaction","detail":"{read_only:false; response_revision:15016; number_of_response:1; }","duration":"144.145225ms","start":"2026-10-15T07:01:55.925314Z","end":"2026-10-15T07:01:56.069459Z","steps":["trace[1917496295] 'process raft request' (duration: 143.93254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:01:56.185087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.866453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:01:56.185189Z","caller":"traceutil/trace.go:171","msg":"trace[1558101946] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:15016; }","duration":"108.013896ms","start":"2026-10-15T07:01:56.077149Z","end":"2026-10-15T07:01:56.185163Z","steps":["trace[1558101946] 'count revisions from in-memory index tree' (duration: 107.779981ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:02:08.714369Z","caller":"traceutil/trace.go:171","msg":"trace[1864357062] transaction","detail":"{read_only:false; response_revision:15120; number_of_response:1; }","duration":"131.530466ms","start":"2026-10-15T07:02:08.582809Z","end":"2026-10-15T07:02:08.714339Z","steps":["trace[1864357062] 'process raft request' (duration: 131.288271ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:02:14.705626Z","caller":"traceutil/trace.go:171","msg":"trace[1703021955] linearizableReadLoop","detail":"{readStateIndex:15874; appliedIndex:15873; }","duration":"377.378503ms","start":"2026-10-15T07:02:14.328218Z","end":"2026-10-15T07:02:14.705596Z","steps":["trace[1703021955] 'read index received' (duration: 377.22099ms)","trace[1703021955] 'applied index is now lower than readState.Index' (duration: 156.143µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:02:14.705702Z","caller":"traceutil/trace.go:171","msg":"trace[501760603] transaction","detail":"{read_only:false; response_revision:15157; number_of_response:1; }","duration":"538.167321ms","start":"2026-10-15T07:02:14.167494Z","end":"2026-10-15T07:02:14.705661Z","steps":["trace[501760603] 'process raft request' (duration: 537.880555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:14.705914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.68075ms","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-10-15T07:02:14.705957Z","caller":"traceutil/trace.go:171","msg":"trace[1393953864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15157; }","duration":"377.751352ms","start":"2026-10-15T07:02:14.328195Z","end":"2026-10-15T07:02:14.705947Z","steps":["trace[1393953864] 'agreement among raft nodes before linearized reading' (duration: 377.539076ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:14.705975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:02:14.167476Z","time spent":"538.335055ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:02:14.706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:02:14.328129Z","time spent":"377.862443ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T07:02:14.757868Z","caller":"traceutil/trace.go:171","msg":"trace[412880141] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"289.447123ms","start":"2026-10-15T07:02:14.468401Z","end":"2026-10-15T07:02:14.757848Z","steps":["trace[412880141] 'process raft request' (duration: 289.118775ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:14.758028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.614963ms","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-10-15T07:02:14.758092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.144563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-p9xj2\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-15T07:02:14.758133Z","caller":"traceutil/trace.go:171","msg":"trace[1615559760] range","detail":"{range_begin:/registry/pods/openstack/keepalived-p9xj2; range_end:; response_count:1; response_revision:15158; }","duration":"333.244435ms","start":"2026-10-15T07:02:14.424878Z","end":"2026-10-15T07:02:14.758123Z","steps":["trace[1615559760] 'agreement among raft nodes before linearized reading' (duration: 333.114861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:14.758093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.692769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-10-15T07:02:14.758172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:02:14.424848Z","time spent":"333.305747ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-p9xj2\" "} {"level":"info","ts":"2026-10-15T07:02:14.758095Z","caller":"traceutil/trace.go:171","msg":"trace[1023755673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15158; }","duration":"184.528923ms","start":"2026-10-15T07:02:14.573551Z","end":"2026-10-15T07:02:14.758079Z","steps":["trace[1023755673] 'agreement among raft nodes before linearized reading' (duration: 184.233736ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:02:14.758208Z","caller":"traceutil/trace.go:171","msg":"trace[1627405650] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:15158; }","duration":"424.918633ms","start":"2026-10-15T07:02:14.333264Z","end":"2026-10-15T07:02:14.758182Z","steps":["trace[1627405650] 'agreement among raft nodes before linearized reading' (duration: 424.485074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:14.758174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.201346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T07:02:14.758351Z","caller":"traceutil/trace.go:171","msg":"trace[1296209839] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:15158; }","duration":"287.442921ms","start":"2026-10-15T07:02:14.47088Z","end":"2026-10-15T07:02:14.758323Z","steps":["trace[1296209839] 'agreement among raft nodes before linearized reading' (duration: 287.085464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:14.758594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:02:14.33324Z","time spent":"425.008145ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-10-15T07:02:15.005713Z","caller":"traceutil/trace.go:171","msg":"trace[972358405] linearizableReadLoop","detail":"{readStateIndex:15876; appliedIndex:15875; }","duration":"242.788691ms","start":"2026-10-15T07:02:14.76288Z","end":"2026-10-15T07:02:15.005669Z","steps":["trace[972358405] 'read index received' (duration: 242.699679ms)","trace[972358405] 'applied index is now lower than readState.Index' (duration: 87.322µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:02:15.005876Z","caller":"traceutil/trace.go:171","msg":"trace[1440935157] transaction","detail":"{read_only:false; response_revision:15159; number_of_response:1; }","duration":"294.888969ms","start":"2026-10-15T07:02:14.710919Z","end":"2026-10-15T07:02:15.005808Z","steps":["trace[1440935157] 'process raft request' (duration: 294.346738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:15.00664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.735821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:567538"} {"level":"info","ts":"2026-10-15T07:02:15.006725Z","caller":"traceutil/trace.go:171","msg":"trace[1804556466] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:15159; }","duration":"243.852885ms","start":"2026-10-15T07:02:14.762851Z","end":"2026-10-15T07:02:15.006704Z","steps":["trace[1804556466] 'agreement among raft nodes before linearized reading' (duration: 242.955425ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:02:15.007259Z","caller":"traceutil/trace.go:171","msg":"trace[73014721] transaction","detail":"{read_only:false; response_revision:15160; number_of_response:1; }","duration":"244.320284ms","start":"2026-10-15T07:02:14.7629Z","end":"2026-10-15T07:02:15.00722Z","steps":["trace[73014721] 'process raft request' (duration: 244.107769ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:02:15.007296Z","caller":"traceutil/trace.go:171","msg":"trace[1613438333] transaction","detail":"{read_only:false; response_revision:15161; number_of_response:1; }","duration":"243.713331ms","start":"2026-10-15T07:02:14.763532Z","end":"2026-10-15T07:02:15.007245Z","steps":["trace[1613438333] 'process raft request' (duration: 243.605739ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:02:15.007369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.2879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-15T07:02:15.007429Z","caller":"traceutil/trace.go:171","msg":"trace[81492174] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:15161; }","duration":"225.396382ms","start":"2026-10-15T07:02:14.782018Z","end":"2026-10-15T07:02:15.007414Z","steps":["trace[81492174] 'agreement among raft nodes before linearized reading' (duration: 225.171337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:00.134254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.343506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:04:00.134425Z","caller":"traceutil/trace.go:171","msg":"trace[1211127118] linearizableReadLoop","detail":"{readStateIndex:17173; appliedIndex:17171; }","duration":"323.515523ms","start":"2026-10-15T07:03:59.81088Z","end":"2026-10-15T07:04:00.134395Z","steps":["trace[1211127118] 'read index received' (duration: 62.883104ms)","trace[1211127118] 'applied index is now lower than readState.Index' (duration: 260.630279ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:04:00.134555Z","caller":"traceutil/trace.go:171","msg":"trace[503110468] transaction","detail":"{read_only:false; response_revision:16399; number_of_response:1; }","duration":"335.070028ms","start":"2026-10-15T07:03:59.799471Z","end":"2026-10-15T07:04:00.134541Z","steps":["trace[503110468] 'process raft request' (duration: 194.325573ms)","trace[503110468] 'compare' (duration: 140.06958ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:04:00.134627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:03:59.799444Z","time spent":"335.140999ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:04:00.134696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.802839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:04:00.134748Z","caller":"traceutil/trace.go:171","msg":"trace[1598173936] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16399; }","duration":"323.880819ms","start":"2026-10-15T07:03:59.81085Z","end":"2026-10-15T07:04:00.13473Z","steps":["trace[1598173936] 'agreement among raft nodes before linearized reading' (duration: 323.778707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:00.134781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:03:59.810836Z","time spent":"323.936791ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-10-15T07:04:00.134776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.84553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T07:04:00.134853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.545284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T07:04:00.134866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.808988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:04:00.134928Z","caller":"traceutil/trace.go:171","msg":"trace[1403647495] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16399; }","duration":"324.044973ms","start":"2026-10-15T07:03:59.810851Z","end":"2026-10-15T07:04:00.134896Z","steps":["trace[1403647495] 'agreement among raft nodes before linearized reading' (duration: 323.787797ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:00.134944Z","caller":"traceutil/trace.go:171","msg":"trace[608588268] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:16399; }","duration":"250.703618ms","start":"2026-10-15T07:03:59.884207Z","end":"2026-10-15T07:04:00.13491Z","steps":["trace[608588268] 'agreement among raft nodes before linearized reading' (duration: 250.516664ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:04:00.134968Z","caller":"traceutil/trace.go:171","msg":"trace[678769855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16399; }","duration":"241.016852ms","start":"2026-10-15T07:03:59.893924Z","end":"2026-10-15T07:04:00.134941Z","steps":["trace[678769855] 'agreement among raft nodes before linearized reading' (duration: 240.775677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:00.134997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.379244ms","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-10-15T07:04:00.135057Z","caller":"traceutil/trace.go:171","msg":"trace[2077064817] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16399; }","duration":"319.488757ms","start":"2026-10-15T07:03:59.815549Z","end":"2026-10-15T07:04:00.135038Z","steps":["trace[2077064817] 'agreement among raft nodes before linearized reading' (duration: 319.335244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:00.135095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:03:59.815509Z","time spent":"319.574758ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-15T07:04:00.134996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:03:59.810836Z","time spent":"324.145395ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-10-15T07:04:00.134778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.156531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:04:00.135284Z","caller":"traceutil/trace.go:171","msg":"trace[2133767936] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16399; }","duration":"320.734083ms","start":"2026-10-15T07:03:59.814525Z","end":"2026-10-15T07:04:00.135259Z","steps":["trace[2133767936] 'agreement among raft nodes before linearized reading' (duration: 320.163801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:04:00.13533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:03:59.814495Z","time spent":"320.820414ms","remote":"127.0.0.1:47986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-10-15T07:06:00.069058Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14574} {"level":"info","ts":"2026-10-15T07:06:00.174768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14574,"took":"102.636967ms","hash":2207752656,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":29245440,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T07:06:00.174884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2207752656,"revision":14574,"compact-revision":12162} {"level":"info","ts":"2026-10-15T07:06:18.886829Z","caller":"traceutil/trace.go:171","msg":"trace[1204387941] linearizableReadLoop","detail":"{readStateIndex:18463; appliedIndex:18462; }","duration":"117.668175ms","start":"2026-10-15T07:06:18.76914Z","end":"2026-10-15T07:06:18.886809Z","steps":["trace[1204387941] 'read index received' (duration: 117.620175ms)","trace[1204387941] 'applied index is now lower than readState.Index' (duration: 46.83µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:06:18.886948Z","caller":"traceutil/trace.go:171","msg":"trace[298115860] transaction","detail":"{read_only:false; response_revision:17627; number_of_response:1; }","duration":"144.708039ms","start":"2026-10-15T07:06:18.742197Z","end":"2026-10-15T07:06:18.886905Z","steps":["trace[298115860] 'process raft request' (duration: 144.468214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:06:18.887009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.849529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3641"} {"level":"info","ts":"2026-10-15T07:06:18.887251Z","caller":"traceutil/trace.go:171","msg":"trace[1136835596] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:17627; }","duration":"118.060455ms","start":"2026-10-15T07:06:18.769086Z","end":"2026-10-15T07:06:18.887147Z","steps":["trace[1136835596] 'agreement among raft nodes before linearized reading' (duration: 117.806849ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:09:02.027852Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:09:02.036377Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T07:09:02.03652Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-10-15T07:09:39.022416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.481048ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-15T07:09:39.022731Z","caller":"traceutil/trace.go:171","msg":"trace[65204725] transaction","detail":"{read_only:false; response_revision:19428; number_of_response:1; }","duration":"379.466544ms","start":"2026-10-15T07:09:38.643245Z","end":"2026-10-15T07:09:39.022712Z","steps":["trace[65204725] 'process raft request' (duration: 379.28007ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:09:39.022742Z","caller":"traceutil/trace.go:171","msg":"trace[1551433287] linearizableReadLoop","detail":"{readStateIndex:20350; appliedIndex:20349; }","duration":"412.447593ms","start":"2026-10-15T07:09:38.61023Z","end":"2026-10-15T07:09:39.022678Z","steps":["trace[1551433287] 'read index received' (duration: 90.75439ms)","trace[1551433287] 'applied index is now lower than readState.Index' (duration: 321.685223ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:09:39.022866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:09:38.643209Z","time spent":"379.586176ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:09:39.023043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.791121ms","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:6"} {"level":"info","ts":"2026-10-15T07:09:39.023095Z","caller":"traceutil/trace.go:171","msg":"trace[1889739972] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:19428; }","duration":"412.933943ms","start":"2026-10-15T07:09:38.610146Z","end":"2026-10-15T07:09:39.02308Z","steps":["trace[1889739972] 'agreement among raft nodes before linearized reading' (duration: 412.80524ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:09:39.023141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:09:38.610115Z","time spent":"413.014275ms","remote":"127.0.0.1:53316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-10-15T07:09:39.175434Z","caller":"traceutil/trace.go:171","msg":"trace[1245121492] transaction","detail":"{read_only:false; response_revision:19429; number_of_response:1; }","duration":"108.140183ms","start":"2026-10-15T07:09:39.067255Z","end":"2026-10-15T07:09:39.175395Z","steps":["trace[1245121492] 'process raft request' (duration: 76.114406ms)","trace[1245121492] 'compare' (duration: 31.856594ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:11:00.077518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17418} {"level":"info","ts":"2026-10-15T07:11:00.197567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17418,"took":"115.713127ms","hash":4033023994,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":35147776,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T07:11:00.197647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4033023994,"revision":17418,"compact-revision":14574} {"level":"info","ts":"2026-10-15T07:12:38.508662Z","caller":"traceutil/trace.go:171","msg":"trace[292449597] transaction","detail":"{read_only:false; response_revision:21459; number_of_response:1; }","duration":"356.977398ms","start":"2026-10-15T07:12:38.151632Z","end":"2026-10-15T07:12:38.508609Z","steps":["trace[292449597] 'process raft request' (duration: 355.955536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:12:38.509033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:12:38.151607Z","time spent":"357.293375ms","remote":"127.0.0.1:47932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7169,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:15:21.162108Z","caller":"traceutil/trace.go:171","msg":"trace[1656929225] linearizableReadLoop","detail":"{readStateIndex:23937; appliedIndex:23936; }","duration":"100.920532ms","start":"2026-10-15T07:15:21.061168Z","end":"2026-10-15T07:15:21.162088Z","steps":["trace[1656929225] 'read index received' (duration: 100.715737ms)","trace[1656929225] 'applied index is now lower than readState.Index' (duration: 204.135µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:15:21.162337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.149277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:15:21.162389Z","caller":"traceutil/trace.go:171","msg":"trace[681152687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22862; }","duration":"101.248558ms","start":"2026-10-15T07:15:21.06113Z","end":"2026-10-15T07:15:21.162378Z","steps":["trace[681152687] 'agreement among raft nodes before linearized reading' (duration: 101.079325ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:21.162451Z","caller":"traceutil/trace.go:171","msg":"trace[256507655] transaction","detail":"{read_only:false; response_revision:22862; number_of_response:1; }","duration":"148.430573ms","start":"2026-10-15T07:15:21.013935Z","end":"2026-10-15T07:15:21.162366Z","steps":["trace[256507655] 'process raft request' (duration: 148.018025ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:34.081135Z","caller":"traceutil/trace.go:171","msg":"trace[1853680285] linearizableReadLoop","detail":"{readStateIndex:24027; appliedIndex:24026; }","duration":"106.700415ms","start":"2026-10-15T07:15:33.974405Z","end":"2026-10-15T07:15:34.081106Z","steps":["trace[1853680285] 'read index received' (duration: 106.446789ms)","trace[1853680285] 'applied index is now lower than readState.Index' (duration: 252.876µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:15:34.081746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.320447ms","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-10-15T07:15:34.081803Z","caller":"traceutil/trace.go:171","msg":"trace[517235814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22946; }","duration":"107.44032ms","start":"2026-10-15T07:15:33.97435Z","end":"2026-10-15T07:15:34.08179Z","steps":["trace[517235814] 'agreement among raft nodes before linearized reading' (duration: 107.238785ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:34.081987Z","caller":"traceutil/trace.go:171","msg":"trace[2138141696] transaction","detail":"{read_only:false; response_revision:22946; number_of_response:1; }","duration":"135.808885ms","start":"2026-10-15T07:15:33.946168Z","end":"2026-10-15T07:15:34.081977Z","steps":["trace[2138141696] 'process raft request' (duration: 134.772922ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:15:49.850561Z","caller":"traceutil/trace.go:171","msg":"trace[1957923317] linearizableReadLoop","detail":"{readStateIndex:24217; appliedIndex:24216; }","duration":"112.982598ms","start":"2026-10-15T07:15:49.737552Z","end":"2026-10-15T07:15:49.850534Z","steps":["trace[1957923317] 'read index received' (duration: 112.61623ms)","trace[1957923317] 'applied index is now lower than readState.Index' (duration: 365.458µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:15:49.850701Z","caller":"traceutil/trace.go:171","msg":"trace[1068869147] transaction","detail":"{read_only:false; response_revision:23124; number_of_response:1; }","duration":"164.605848ms","start":"2026-10-15T07:15:49.686075Z","end":"2026-10-15T07:15:49.850681Z","steps":["trace[1068869147] 'process raft request' (duration: 164.297381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:15:49.850857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.277905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-15T07:15:49.850908Z","caller":"traceutil/trace.go:171","msg":"trace[570483041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23124; }","duration":"113.385486ms","start":"2026-10-15T07:15:49.737509Z","end":"2026-10-15T07:15:49.850894Z","steps":["trace[570483041] 'agreement among raft nodes before linearized reading' (duration: 113.168002ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:16:00.084198Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20402} {"level":"info","ts":"2026-10-15T07:16:00.312311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20402,"took":"223.772149ms","hash":1616303896,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":33320960,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:16:00.312389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1616303896,"revision":20402,"compact-revision":17418} {"level":"info","ts":"2026-10-15T07:17:43.750944Z","caller":"traceutil/trace.go:171","msg":"trace[1158335009] linearizableReadLoop","detail":"{readStateIndex:25127; appliedIndex:25125; }","duration":"163.895274ms","start":"2026-10-15T07:17:43.587009Z","end":"2026-10-15T07:17:43.750905Z","steps":["trace[1158335009] 'read index received' (duration: 64.043285ms)","trace[1158335009] 'applied index is now lower than readState.Index' (duration: 99.850679ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:17:43.751243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.19285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-15T07:17:43.751286Z","caller":"traceutil/trace.go:171","msg":"trace[2080241803] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:23988; }","duration":"164.299902ms","start":"2026-10-15T07:17:43.586974Z","end":"2026-10-15T07:17:43.751274Z","steps":["trace[2080241803] 'agreement among raft nodes before linearized reading' (duration: 164.088478ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:43.751213Z","caller":"traceutil/trace.go:171","msg":"trace[1104736064] transaction","detail":"{read_only:false; response_revision:23988; number_of_response:1; }","duration":"172.042537ms","start":"2026-10-15T07:17:43.579118Z","end":"2026-10-15T07:17:43.751161Z","steps":["trace[1104736064] 'process raft request' (duration: 155.7775ms)","trace[1104736064] 'compare' (duration: 15.825618ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:17:44.05393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.952918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:17:44.054219Z","caller":"traceutil/trace.go:171","msg":"trace[322505848] linearizableReadLoop","detail":"{readStateIndex:25130; appliedIndex:25128; }","duration":"180.268862ms","start":"2026-10-15T07:17:43.873932Z","end":"2026-10-15T07:17:44.054201Z","steps":["trace[322505848] 'read index received' (duration: 43.913455ms)","trace[322505848] 'applied index is now lower than readState.Index' (duration: 136.353867ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:44.054352Z","caller":"traceutil/trace.go:171","msg":"trace[282233844] transaction","detail":"{read_only:false; response_revision:23989; number_of_response:1; }","duration":"272.927176ms","start":"2026-10-15T07:17:43.781414Z","end":"2026-10-15T07:17:44.054341Z","steps":["trace[282233844] 'process raft request' (duration: 136.440297ms)","trace[282233844] 'compare' (duration: 135.523909ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:44.054743Z","caller":"traceutil/trace.go:171","msg":"trace[1262665026] transaction","detail":"{read_only:false; response_revision:23990; number_of_response:1; }","duration":"268.776398ms","start":"2026-10-15T07:17:43.785954Z","end":"2026-10-15T07:17:44.05473Z","steps":["trace[1262665026] 'process raft request' (duration: 268.125134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:44.054882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.910496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:17:44.055004Z","caller":"traceutil/trace.go:171","msg":"trace[237219231] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:23990; }","duration":"181.123561ms","start":"2026-10-15T07:17:43.873852Z","end":"2026-10-15T07:17:44.054976Z","steps":["trace[237219231] 'agreement among raft nodes before linearized reading' (duration: 180.855925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:44.05499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.776359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"} {"level":"info","ts":"2026-10-15T07:17:44.055123Z","caller":"traceutil/trace.go:171","msg":"trace[887993623] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23990; }","duration":"139.978393ms","start":"2026-10-15T07:17:43.915093Z","end":"2026-10-15T07:17:44.055071Z","steps":["trace[887993623] 'agreement among raft nodes before linearized reading' (duration: 139.580655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:44.459929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.438412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:17:44.460053Z","caller":"traceutil/trace.go:171","msg":"trace[1567267822] linearizableReadLoop","detail":"{readStateIndex:25132; appliedIndex:25131; }","duration":"316.325822ms","start":"2026-10-15T07:17:44.143709Z","end":"2026-10-15T07:17:44.460035Z","steps":["trace[1567267822] 'read index received' (duration: 215.419981ms)","trace[1567267822] 'applied index is now lower than readState.Index' (duration: 100.903421ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:44.460088Z","caller":"traceutil/trace.go:171","msg":"trace[1150813210] transaction","detail":"{read_only:false; response_revision:23991; number_of_response:1; }","duration":"316.588078ms","start":"2026-10-15T07:17:44.143459Z","end":"2026-10-15T07:17:44.460047Z","steps":["trace[1150813210] 'process raft request' (duration: 215.914722ms)","trace[1150813210] 'compare' (duration: 100.187315ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:17:44.46031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:44.143435Z","time spent":"316.764341ms","remote":"127.0.0.1:47810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:17:44.460464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.726431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16953"} {"level":"warn","ts":"2026-10-15T07:17:44.460303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.943953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-15T07:17:44.460573Z","caller":"traceutil/trace.go:171","msg":"trace[943489301] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23991; }","duration":"316.868405ms","start":"2026-10-15T07:17:44.143678Z","end":"2026-10-15T07:17:44.460547Z","steps":["trace[943489301] 'agreement among raft nodes before linearized reading' (duration: 316.430094ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:44.460601Z","caller":"traceutil/trace.go:171","msg":"trace[2021103884] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:23991; }","duration":"315.29929ms","start":"2026-10-15T07:17:44.145289Z","end":"2026-10-15T07:17:44.460589Z","steps":["trace[2021103884] 'agreement among raft nodes before linearized reading' (duration: 314.84367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:44.46063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:44.143653Z","time spent":"316.963964ms","remote":"127.0.0.1:47926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":16975,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-15T07:17:44.460648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:44.14526Z","time spent":"315.376801ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-10-15T07:17:44.460656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.412187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:17:44.460692Z","caller":"traceutil/trace.go:171","msg":"trace[1936705061] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:23991; }","duration":"311.484739ms","start":"2026-10-15T07:17:44.149192Z","end":"2026-10-15T07:17:44.460676Z","steps":["trace[1936705061] 'agreement among raft nodes before linearized reading' (duration: 311.435588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:44.46072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:44.149176Z","time spent":"311.53432ms","remote":"127.0.0.1:53848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:17:44.460605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.224142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:17:44.460877Z","caller":"traceutil/trace.go:171","msg":"trace[214520814] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:23991; }","duration":"166.55938ms","start":"2026-10-15T07:17:44.294304Z","end":"2026-10-15T07:17:44.460864Z","steps":["trace[214520814] 'agreement among raft nodes before linearized reading' (duration: 166.266823ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:44.666022Z","caller":"traceutil/trace.go:171","msg":"trace[650720047] transaction","detail":"{read_only:false; response_revision:23993; number_of_response:1; }","duration":"196.812654ms","start":"2026-10-15T07:17:44.469168Z","end":"2026-10-15T07:17:44.665981Z","steps":["trace[650720047] 'process raft request' (duration: 178.812211ms)","trace[650720047] 'compare' (duration: 17.811299ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:17:44.972789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.322026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:17:44.972974Z","caller":"traceutil/trace.go:171","msg":"trace[1273510257] linearizableReadLoop","detail":"{readStateIndex:25135; appliedIndex:25134; }","duration":"302.515948ms","start":"2026-10-15T07:17:44.670426Z","end":"2026-10-15T07:17:44.972942Z","steps":["trace[1273510257] 'read index received' (duration: 180.882346ms)","trace[1273510257] 'applied index is now lower than readState.Index' (duration: 121.631672ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:44.973125Z","caller":"traceutil/trace.go:171","msg":"trace[202517083] transaction","detail":"{read_only:false; response_revision:23994; number_of_response:1; }","duration":"434.137142ms","start":"2026-10-15T07:17:44.538941Z","end":"2026-10-15T07:17:44.973078Z","steps":["trace[202517083] 'process raft request' (duration: 312.362207ms)","trace[202517083] 'compare' (duration: 121.08737ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:17:44.973245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.822724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-10-15T07:17:44.97329Z","caller":"traceutil/trace.go:171","msg":"trace[1547540784] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23994; }","duration":"302.864145ms","start":"2026-10-15T07:17:44.670408Z","end":"2026-10-15T07:17:44.973272Z","steps":["trace[1547540784] 'agreement among raft nodes before linearized reading' (duration: 302.688641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:44.973255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.326703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T07:17:44.973334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:44.670386Z","time spent":"302.938977ms","remote":"127.0.0.1:48010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T07:17:44.973343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:44.538886Z","time spent":"434.335997ms","remote":"127.0.0.1:48224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T07:17:44.973362Z","caller":"traceutil/trace.go:171","msg":"trace[1198842207] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:23994; }","duration":"104.522008ms","start":"2026-10-15T07:17:44.868815Z","end":"2026-10-15T07:17:44.973337Z","steps":["trace[1198842207] 'agreement among raft nodes before linearized reading' (duration: 104.304833ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.197362Z","caller":"traceutil/trace.go:171","msg":"trace[95706227] transaction","detail":"{read_only:false; number_of_response:0; response_revision:23995; }","duration":"213.241094ms","start":"2026-10-15T07:17:44.984088Z","end":"2026-10-15T07:17:45.197329Z","steps":["trace[95706227] 'process raft request' (duration: 212.880766ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.522668Z","caller":"traceutil/trace.go:171","msg":"trace[1678287545] transaction","detail":"{read_only:false; response_revision:23997; number_of_response:1; }","duration":"311.917867ms","start":"2026-10-15T07:17:45.210691Z","end":"2026-10-15T07:17:45.522609Z","steps":["trace[1678287545] 'process raft request' (duration: 225.26724ms)","trace[1678287545] 'compare' (duration: 86.391662ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:45.522824Z","caller":"traceutil/trace.go:171","msg":"trace[74124779] transaction","detail":"{read_only:false; response_revision:23998; number_of_response:1; }","duration":"215.477522ms","start":"2026-10-15T07:17:45.307335Z","end":"2026-10-15T07:17:45.522812Z","steps":["trace[74124779] 'process raft request' (duration: 215.227507ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.522681Z","caller":"traceutil/trace.go:171","msg":"trace[1891404051] linearizableReadLoop","detail":"{readStateIndex:25139; appliedIndex:25138; }","duration":"220.512689ms","start":"2026-10-15T07:17:45.302141Z","end":"2026-10-15T07:17:45.522653Z","steps":["trace[1891404051] 'read index received' (duration: 133.893773ms)","trace[1891404051] 'applied index is now lower than readState.Index' (duration: 86.617806ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:17:45.522954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:17:45.210669Z","time spent":"312.149743ms","remote":"127.0.0.1:48224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T07:17:45.523127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.748523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-15T07:17:45.523208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.246526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:17:45.52331Z","caller":"traceutil/trace.go:171","msg":"trace[1520483283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23998; }","duration":"235.008749ms","start":"2026-10-15T07:17:45.288267Z","end":"2026-10-15T07:17:45.523276Z","steps":["trace[1520483283] 'agreement among raft nodes before linearized reading' (duration: 234.392497ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.52334Z","caller":"traceutil/trace.go:171","msg":"trace[2480058] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:23998; }","duration":"252.463141ms","start":"2026-10-15T07:17:45.27084Z","end":"2026-10-15T07:17:45.523303Z","steps":["trace[2480058] 'agreement among raft nodes before linearized reading' (duration: 251.925149ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.566426Z","caller":"traceutil/trace.go:171","msg":"trace[264906153] transaction","detail":"{read_only:false; response_revision:23999; number_of_response:1; }","duration":"108.942311ms","start":"2026-10-15T07:17:45.457465Z","end":"2026-10-15T07:17:45.566407Z","steps":["trace[264906153] 'process raft request' (duration: 108.754818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:45.56685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.004421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-15T07:17:45.566988Z","caller":"traceutil/trace.go:171","msg":"trace[888854532] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:23999; }","duration":"145.222095ms","start":"2026-10-15T07:17:45.42173Z","end":"2026-10-15T07:17:45.566952Z","steps":["trace[888854532] 'agreement among raft nodes before linearized reading' (duration: 144.817567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:45.566996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.308407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-10-15T07:17:45.567056Z","caller":"traceutil/trace.go:171","msg":"trace[777873404] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23999; }","duration":"198.594153ms","start":"2026-10-15T07:17:45.368446Z","end":"2026-10-15T07:17:45.56704Z","steps":["trace[777873404] 'agreement among raft nodes before linearized reading' (duration: 198.162903ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:45.566773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.00798ms","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-10-15T07:17:45.567146Z","caller":"traceutil/trace.go:171","msg":"trace[387531320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23999; }","duration":"198.448029ms","start":"2026-10-15T07:17:45.368673Z","end":"2026-10-15T07:17:45.567121Z","steps":["trace[387531320] 'agreement among raft nodes before linearized reading' (duration: 197.810445ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.710878Z","caller":"traceutil/trace.go:171","msg":"trace[714677197] linearizableReadLoop","detail":"{readStateIndex:25142; appliedIndex:25141; }","duration":"144.467209ms","start":"2026-10-15T07:17:45.566374Z","end":"2026-10-15T07:17:45.710841Z","steps":["trace[714677197] 'read index received' (duration: 121.956959ms)","trace[714677197] 'applied index is now lower than readState.Index' (duration: 22.50813ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:17:45.711057Z","caller":"traceutil/trace.go:171","msg":"trace[712553980] transaction","detail":"{read_only:false; response_revision:24000; number_of_response:1; }","duration":"181.832236ms","start":"2026-10-15T07:17:45.529173Z","end":"2026-10-15T07:17:45.711005Z","steps":["trace[712553980] 'process raft request' (duration: 159.150502ms)","trace[712553980] 'compare' (duration: 22.233574ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:17:45.711235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.738928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"} {"level":"info","ts":"2026-10-15T07:17:45.711284Z","caller":"traceutil/trace.go:171","msg":"trace[109682750] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24000; }","duration":"154.859651ms","start":"2026-10-15T07:17:45.556411Z","end":"2026-10-15T07:17:45.711271Z","steps":["trace[109682750] 'agreement among raft nodes before linearized reading' (duration: 154.586916ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.784157Z","caller":"traceutil/trace.go:171","msg":"trace[1918893297] transaction","detail":"{read_only:false; response_revision:24001; number_of_response:1; }","duration":"211.214751ms","start":"2026-10-15T07:17:45.572902Z","end":"2026-10-15T07:17:45.784116Z","steps":["trace[1918893297] 'process raft request' (duration: 210.944826ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.784431Z","caller":"traceutil/trace.go:171","msg":"trace[1552667947] transaction","detail":"{read_only:false; response_revision:24002; number_of_response:1; }","duration":"211.213992ms","start":"2026-10-15T07:17:45.573195Z","end":"2026-10-15T07:17:45.784409Z","steps":["trace[1552667947] 'process raft request' (duration: 210.844874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:45.784487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.877198ms","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:6"} {"level":"warn","ts":"2026-10-15T07:17:45.784543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.222541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T07:17:45.784599Z","caller":"traceutil/trace.go:171","msg":"trace[1287743269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24002; }","duration":"127.336673ms","start":"2026-10-15T07:17:45.657248Z","end":"2026-10-15T07:17:45.784584Z","steps":["trace[1287743269] 'agreement among raft nodes before linearized reading' (duration: 127.14397ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:17:45.784599Z","caller":"traceutil/trace.go:171","msg":"trace[717712235] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:24002; }","duration":"114.00694ms","start":"2026-10-15T07:17:45.670561Z","end":"2026-10-15T07:17:45.784568Z","steps":["trace[717712235] 'agreement among raft nodes before linearized reading' (duration: 113.793426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:17:45.784711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.96471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9de92a27-bec4-48fb-9bee-de5e82085384\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-10-15T07:17:45.784776Z","caller":"traceutil/trace.go:171","msg":"trace[1865292679] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9de92a27-bec4-48fb-9bee-de5e82085384; range_end:; response_count:1; response_revision:24002; }","duration":"213.05187ms","start":"2026-10-15T07:17:45.571687Z","end":"2026-10-15T07:17:45.784739Z","steps":["trace[1865292679] 'agreement among raft nodes before linearized reading' (duration: 212.864946ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:21:00.091697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23253} {"level":"info","ts":"2026-10-15T07:21:00.215661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23253,"took":"118.885377ms","hash":806944591,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":33243136,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:21:00.215793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":806944591,"revision":23253,"compact-revision":20402} {"level":"info","ts":"2026-10-15T07:21:33.869782Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c92.wal"} {"level":"warn","ts":"2026-10-15T07:21:41.980691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.998172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:21:41.980828Z","caller":"traceutil/trace.go:171","msg":"trace[1546778537] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:26797; }","duration":"371.214297ms","start":"2026-10-15T07:21:41.609591Z","end":"2026-10-15T07:21:41.980805Z","steps":["trace[1546778537] 'count revisions from in-memory index tree' (duration: 370.89038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:21:41.980885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:21:41.609567Z","time spent":"371.299529ms","remote":"127.0.0.1:53280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:21:41.981451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.177433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:21:41.981527Z","caller":"traceutil/trace.go:171","msg":"trace[1020213874] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26797; }","duration":"316.298795ms","start":"2026-10-15T07:21:41.665212Z","end":"2026-10-15T07:21:41.981511Z","steps":["trace[1020213874] 'count revisions from in-memory index tree' (duration: 316.073772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:21:41.98158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:21:41.665189Z","time spent":"316.376247ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":89,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-10-15T07:21:41.982254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.774219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:21:41.982416Z","caller":"traceutil/trace.go:171","msg":"trace[2141661750] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:26797; }","duration":"194.015835ms","start":"2026-10-15T07:21:41.788377Z","end":"2026-10-15T07:21:41.982393Z","steps":["trace[2141661750] 'count revisions from in-memory index tree' (duration: 193.647128ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:21:41.990292Z","caller":"traceutil/trace.go:171","msg":"trace[928218872] transaction","detail":"{read_only:false; response_revision:26798; number_of_response:1; }","duration":"151.066971ms","start":"2026-10-15T07:21:41.839171Z","end":"2026-10-15T07:21:41.990238Z","steps":["trace[928218872] 'process raft request' (duration: 150.788665ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:21:41.992659Z","caller":"traceutil/trace.go:171","msg":"trace[528522471] transaction","detail":"{read_only:false; response_revision:26799; number_of_response:1; }","duration":"107.321149ms","start":"2026-10-15T07:21:41.885306Z","end":"2026-10-15T07:21:41.992627Z","steps":["trace[528522471] 'process raft request' (duration: 107.100404ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:24:29.417198Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:24:29.424268Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T07:24:29.424404Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"warn","ts":"2026-10-15T07:25:06.730469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.079769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:25:06.730847Z","caller":"traceutil/trace.go:171","msg":"trace[275578177] transaction","detail":"{read_only:false; response_revision:29134; number_of_response:1; }","duration":"183.737194ms","start":"2026-10-15T07:25:06.546996Z","end":"2026-10-15T07:25:06.730734Z","steps":["trace[275578177] 'process raft request' (duration: 47.233466ms)","trace[275578177] 'compare' (duration: 135.70953ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:25:07.021401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.433455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T07:25:07.02153Z","caller":"traceutil/trace.go:171","msg":"trace[189197738] linearizableReadLoop","detail":"{readStateIndex:30484; appliedIndex:30483; }","duration":"177.840837ms","start":"2026-10-15T07:25:06.843662Z","end":"2026-10-15T07:25:07.021503Z","steps":["trace[189197738] 'read index received' (duration: 55.252249ms)","trace[189197738] 'applied index is now lower than readState.Index' (duration: 122.584078ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:25:07.021725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.087652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T07:25:07.021685Z","caller":"traceutil/trace.go:171","msg":"trace[1575964455] transaction","detail":"{read_only:false; response_revision:29135; number_of_response:1; }","duration":"184.887128ms","start":"2026-10-15T07:25:06.83672Z","end":"2026-10-15T07:25:07.021607Z","steps":["trace[1575964455] 'process raft request' (duration: 62.138847ms)","trace[1575964455] 'compare' (duration: 121.824421ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:25:07.021783Z","caller":"traceutil/trace.go:171","msg":"trace[934122183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29135; }","duration":"178.164555ms","start":"2026-10-15T07:25:06.843584Z","end":"2026-10-15T07:25:07.021748Z","steps":["trace[934122183] 'agreement among raft nodes before linearized reading' (duration: 178.022031ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:25:07.021998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.366064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-10-15T07:25:07.022052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.262693ms","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-10-15T07:25:07.022105Z","caller":"traceutil/trace.go:171","msg":"trace[1805464184] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29135; }","duration":"103.512377ms","start":"2026-10-15T07:25:06.918566Z","end":"2026-10-15T07:25:07.022078Z","steps":["trace[1805464184] 'agreement among raft nodes before linearized reading' (duration: 103.236891ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:25:07.022131Z","caller":"traceutil/trace.go:171","msg":"trace[501684040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29135; }","duration":"124.426988ms","start":"2026-10-15T07:25:06.897681Z","end":"2026-10-15T07:25:07.022108Z","steps":["trace[501684040] 'agreement among raft nodes before linearized reading' (duration: 124.127261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:25:08.937253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.047877ms","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-10-15T07:25:08.937318Z","caller":"traceutil/trace.go:171","msg":"trace[1098397576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29162; }","duration":"201.173669ms","start":"2026-10-15T07:25:08.736128Z","end":"2026-10-15T07:25:08.937301Z","steps":["trace[1098397576] 'range keys from in-memory index tree' (duration: 200.893593ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:00.099544Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25890} {"level":"info","ts":"2026-10-15T07:26:00.246741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25890,"took":"141.870853ms","hash":2310466806,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":44392448,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-10-15T07:26:00.246854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2310466806,"revision":25890,"compact-revision":23253} {"level":"info","ts":"2026-10-15T07:26:13.972025Z","caller":"traceutil/trace.go:171","msg":"trace[783600613] transaction","detail":"{read_only:false; response_revision:29848; number_of_response:1; }","duration":"104.467948ms","start":"2026-10-15T07:26:13.867532Z","end":"2026-10-15T07:26:13.972Z","steps":["trace[783600613] 'process raft request' (duration: 104.211782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:26:14.391141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.078442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:26:14.391232Z","caller":"traceutil/trace.go:171","msg":"trace[1347622045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29848; }","duration":"330.187214ms","start":"2026-10-15T07:26:14.061023Z","end":"2026-10-15T07:26:14.39121Z","steps":["trace[1347622045] 'range keys from in-memory index tree' (duration: 329.97935ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:26:14.391266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T07:26:14.06096Z","time spent":"330.297727ms","remote":"127.0.0.1:47742","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-10-15T07:26:14.39158Z","caller":"traceutil/trace.go:171","msg":"trace[55742657] transaction","detail":"{read_only:false; response_revision:29849; number_of_response:1; }","duration":"156.285643ms","start":"2026-10-15T07:26:14.23525Z","end":"2026-10-15T07:26:14.391535Z","steps":["trace[55742657] 'process raft request' (duration: 119.342909ms)","trace[55742657] 'compare' (duration: 36.699608ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:26:14.463843Z","caller":"traceutil/trace.go:171","msg":"trace[1225580974] transaction","detail":"{read_only:false; response_revision:29850; number_of_response:1; }","duration":"227.616637ms","start":"2026-10-15T07:26:14.236202Z","end":"2026-10-15T07:26:14.463818Z","steps":["trace[1225580974] 'process raft request' (duration: 227.26763ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:14.61254Z","caller":"traceutil/trace.go:171","msg":"trace[924656122] linearizableReadLoop","detail":"{readStateIndex:31230; appliedIndex:31229; }","duration":"126.592753ms","start":"2026-10-15T07:26:14.485912Z","end":"2026-10-15T07:26:14.612505Z","steps":["trace[924656122] 'read index received' (duration: 122.196999ms)","trace[924656122] 'applied index is now lower than readState.Index' (duration: 4.394744ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:26:14.61266Z","caller":"traceutil/trace.go:171","msg":"trace[956040265] transaction","detail":"{read_only:false; response_revision:29851; number_of_response:1; }","duration":"135.914485ms","start":"2026-10-15T07:26:14.476705Z","end":"2026-10-15T07:26:14.612619Z","steps":["trace[956040265] 'process raft request' (duration: 131.369697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:26:14.613169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.201607ms","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-10-15T07:26:14.613227Z","caller":"traceutil/trace.go:171","msg":"trace[328517406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29851; }","duration":"127.346759ms","start":"2026-10-15T07:26:14.485866Z","end":"2026-10-15T07:26:14.613212Z","steps":["trace[328517406] 'agreement among raft nodes before linearized reading' (duration: 126.749517ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:14.790327Z","caller":"traceutil/trace.go:171","msg":"trace[2087331944] transaction","detail":"{read_only:false; response_revision:29852; number_of_response:1; }","duration":"172.441841ms","start":"2026-10-15T07:26:14.617853Z","end":"2026-10-15T07:26:14.790294Z","steps":["trace[2087331944] 'process raft request' (duration: 172.237426ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:14.817248Z","caller":"traceutil/trace.go:171","msg":"trace[709034966] linearizableReadLoop","detail":"{readStateIndex:31233; appliedIndex:31230; }","duration":"109.577878ms","start":"2026-10-15T07:26:14.707642Z","end":"2026-10-15T07:26:14.81722Z","steps":["trace[709034966] 'read index received' (duration: 82.456545ms)","trace[709034966] 'applied index is now lower than readState.Index' (duration: 27.120163ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:26:14.817451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.790902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T07:26:14.817505Z","caller":"traceutil/trace.go:171","msg":"trace[1585033437] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:29854; }","duration":"109.903155ms","start":"2026-10-15T07:26:14.707582Z","end":"2026-10-15T07:26:14.817485Z","steps":["trace[1585033437] 'agreement among raft nodes before linearized reading' (duration: 109.754731ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:14.817442Z","caller":"traceutil/trace.go:171","msg":"trace[1943168001] transaction","detail":"{read_only:false; response_revision:29854; number_of_response:1; }","duration":"184.688163ms","start":"2026-10-15T07:26:14.632687Z","end":"2026-10-15T07:26:14.817375Z","steps":["trace[1943168001] 'process raft request' (duration: 184.476979ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:26:14.818636Z","caller":"traceutil/trace.go:171","msg":"trace[1173155649] transaction","detail":"{read_only:false; response_revision:29853; number_of_response:1; }","duration":"197.696534ms","start":"2026-10-15T07:26:14.620907Z","end":"2026-10-15T07:26:14.818577Z","steps":["trace[1173155649] 'process raft request' (duration: 196.10678ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:29:59.148817Z","caller":"traceutil/trace.go:171","msg":"trace[205620081] linearizableReadLoop","detail":"{readStateIndex:33864; appliedIndex:33863; }","duration":"115.506033ms","start":"2026-10-15T07:29:59.033284Z","end":"2026-10-15T07:29:59.14879Z","steps":["trace[205620081] 'read index received' (duration: 77.579991ms)","trace[205620081] 'applied index is now lower than readState.Index' (duration: 37.924552ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:29:59.149048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.738098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T07:29:59.149105Z","caller":"traceutil/trace.go:171","msg":"trace[1511156919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:32385; }","duration":"115.84397ms","start":"2026-10-15T07:29:59.033248Z","end":"2026-10-15T07:29:59.149092Z","steps":["trace[1511156919] 'agreement among raft nodes before linearized reading' (duration: 115.649296ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:29:59.14888Z","caller":"traceutil/trace.go:171","msg":"trace[1669524301] transaction","detail":"{read_only:false; response_revision:32385; number_of_response:1; }","duration":"121.469481ms","start":"2026-10-15T07:29:59.027369Z","end":"2026-10-15T07:29:59.148838Z","steps":["trace[1669524301] 'process raft request' (duration: 83.59333ms)","trace[1669524301] 'compare' (duration: 37.614696ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T07:30:03.74122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.819398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T07:30:03.741296Z","caller":"traceutil/trace.go:171","msg":"trace[1360678748] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:32470; }","duration":"184.974441ms","start":"2026-10-15T07:30:03.5563Z","end":"2026-10-15T07:30:03.741274Z","steps":["trace[1360678748] 'count revisions from in-memory index tree' (duration: 184.696774ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:30:03.751573Z","caller":"traceutil/trace.go:171","msg":"trace[974019641] linearizableReadLoop","detail":"{readStateIndex:33951; appliedIndex:33950; }","duration":"124.483886ms","start":"2026-10-15T07:30:03.627055Z","end":"2026-10-15T07:30:03.751539Z","steps":["trace[974019641] 'read index received' (duration: 123.879343ms)","trace[974019641] 'applied index is now lower than readState.Index' (duration: 603.063µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:30:03.75166Z","caller":"traceutil/trace.go:171","msg":"trace[33496171] transaction","detail":"{read_only:false; response_revision:32471; number_of_response:1; }","duration":"130.238869ms","start":"2026-10-15T07:30:03.621368Z","end":"2026-10-15T07:30:03.751607Z","steps":["trace[33496171] 'process raft request' (duration: 129.689317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T07:30:03.751963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.895134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-15T07:30:03.752003Z","caller":"traceutil/trace.go:171","msg":"trace[339181037] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:32471; }","duration":"124.958045ms","start":"2026-10-15T07:30:03.627036Z","end":"2026-10-15T07:30:03.751994Z","steps":["trace[339181037] 'agreement among raft nodes before linearized reading' (duration: 124.617988ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T07:31:00.117966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29725} {"level":"info","ts":"2026-10-15T07:31:00.347391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29725,"took":"222.657088ms","hash":719126222,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":38498304,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-15T07:31:00.347505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":719126222,"revision":29725,"compact-revision":25890} {"level":"info","ts":"2026-10-15T07:36:00.126314Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33040} {"level":"info","ts":"2026-10-15T07:36:00.31265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33040,"took":"180.451224ms","hash":3416550999,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":35254272,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T07:36:00.312724Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3416550999,"revision":33040,"compact-revision":29725} {"level":"info","ts":"2026-10-15T07:40:42.099296Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:40:42.114811Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T07:40:42.114995Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T07:41:00.134995Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35711} {"level":"info","ts":"2026-10-15T07:41:00.279297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35711,"took":"138.355001ms","hash":4019659858,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":34713600,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T07:41:00.279393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4019659858,"revision":35711,"compact-revision":33040} {"level":"info","ts":"2026-10-15T07:46:00.143409Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38413} {"level":"info","ts":"2026-10-15T07:46:00.305774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38413,"took":"157.47563ms","hash":2495919091,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":34881536,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T07:46:00.305863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2495919091,"revision":38413,"compact-revision":35711} {"level":"info","ts":"2026-10-15T07:46:57.307591Z","caller":"traceutil/trace.go:171","msg":"trace[1384183531] transaction","detail":"{read_only:false; response_revision:41646; number_of_response:1; }","duration":"110.74397ms","start":"2026-10-15T07:46:57.196802Z","end":"2026-10-15T07:46:57.307546Z","steps":["trace[1384183531] 'process raft request' (duration: 50.196895ms)","trace[1384183531] 'compare' (duration: 59.907671ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:46:57.633704Z","caller":"traceutil/trace.go:171","msg":"trace[1664558440] transaction","detail":"{read_only:false; response_revision:41650; number_of_response:1; }","duration":"145.703189ms","start":"2026-10-15T07:46:57.48795Z","end":"2026-10-15T07:46:57.633654Z","steps":["trace[1664558440] 'process raft request' (duration: 126.660431ms)","trace[1664558440] 'compare' (duration: 18.68927ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T07:51:00.151481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41094} {"level":"info","ts":"2026-10-15T07:51:00.319808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41094,"took":"162.543339ms","hash":1622046429,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":34344960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T07:51:00.319927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1622046429,"revision":41094,"compact-revision":38413} {"level":"info","ts":"2026-10-15T07:56:00.163697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43831} {"level":"info","ts":"2026-10-15T07:56:00.319838Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43831,"took":"149.686831ms","hash":3204310843,"current-db-size-bytes":61054976,"current-db-size":"61 MB","current-db-size-in-use-bytes":33140736,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T07:56:00.319975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3204310843,"revision":43831,"compact-revision":41094} {"level":"info","ts":"2026-10-15T07:58:24.300393Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"341a3703c39c1f5b","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T07:58:24.305946Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-10-15T07:58:24.306128Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}