{"level":"warn","ts":"2026-04-07T00:10:52.244453Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:10:52.244553Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219: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.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219: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-04-07T00:10:52.244632Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:10:52.24465Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-07T00:10:52.244678Z","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-04-07T00:10:52.245513Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-04-07T00:10:52.245606Z","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.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219: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-04-07T00:10:52.249739Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.880624ms"} {"level":"info","ts":"2026-04-07T00:10:52.264218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-04-07T00:10:52.264354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:10:52.264381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:10:52.264401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:10:52.264418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:10:52.264473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-04-07T00:10:52.272017Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:10:52.273731Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:10:52.275007Z","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-04-07T00:10:52.27749Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:10:52.27765Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:10:52.277806Z","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-04-07T00:10:52.277925Z","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-04-07T00:10:52.278006Z","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-04-07T00:10:52.278845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-04-07T00:10:52.279332Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-04-07T00:10:52.283125Z","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-04-07T00:10:52.283311Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-07T00:10:52.28337Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-04-07T00:10:52.283448Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:10:52.283499Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:10:52.665723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:10:52.665795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:10:52.665839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-04-07T00:10:52.665858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:10:52.665869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-07T00:10:52.665893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:10:52.665913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-04-07T00:10:52.666829Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:10:52.666835Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:10:52.666836Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:52.666922Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:10:52.667112Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:10:52.667158Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:10:52.667911Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:52.668072Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:52.668142Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:52.670475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-04-07T00:10:52.67072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:11:14.035734Z","caller":"traceutil/trace.go:171","msg":"trace[912452448] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"191.596125ms","start":"2026-04-07T00:11:13.844095Z","end":"2026-04-07T00:11:14.035691Z","steps":["trace[912452448] 'process raft request' (duration: 191.36419ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:22.741605Z","caller":"traceutil/trace.go:171","msg":"trace[511947003] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"132.190376ms","start":"2026-04-07T00:11:22.609395Z","end":"2026-04-07T00:11:22.741586Z","steps":["trace[511947003] 'process raft request' (duration: 132.086514ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:28.643018Z","caller":"traceutil/trace.go:171","msg":"trace[1900280691] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"103.566399ms","start":"2026-04-07T00:11:28.539414Z","end":"2026-04-07T00:11:28.642981Z","steps":["trace[1900280691] 'process raft request' (duration: 64.279043ms)","trace[1900280691] 'compare' (duration: 39.157893ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:28.982974Z","caller":"traceutil/trace.go:171","msg":"trace[408105677] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"221.365646ms","start":"2026-04-07T00:11:28.761583Z","end":"2026-04-07T00:11:28.982949Z","steps":["trace[408105677] 'read index received' (duration: 174.13992ms)","trace[408105677] 'applied index is now lower than readState.Index' (duration: 47.224826ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:28.98322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.624851ms","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-04-07T00:11:28.983334Z","caller":"traceutil/trace.go:171","msg":"trace[1683397820] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"241.327055ms","start":"2026-04-07T00:11:28.741953Z","end":"2026-04-07T00:11:28.98328Z","steps":["trace[1683397820] 'process raft request' (duration: 193.73843ms)","trace[1683397820] 'compare' (duration: 47.111175ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:28.983276Z","caller":"traceutil/trace.go:171","msg":"trace[1493352580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"221.736743ms","start":"2026-04-07T00:11:28.761529Z","end":"2026-04-07T00:11:28.983265Z","steps":["trace[1493352580] 'agreement among raft nodes before linearized reading' (duration: 221.514369ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:29.439194Z","caller":"traceutil/trace.go:171","msg":"trace[323834546] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"451.065651ms","start":"2026-04-07T00:11:28.98808Z","end":"2026-04-07T00:11:29.439145Z","steps":["trace[323834546] 'process raft request' (duration: 403.898155ms)","trace[323834546] 'compare' (duration: 46.943451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:29.439893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:28.988063Z","time spent":"451.220754ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:29.445533Z","caller":"traceutil/trace.go:171","msg":"trace[17294786] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"377.999636ms","start":"2026-04-07T00:11:29.067464Z","end":"2026-04-07T00:11:29.445464Z","steps":["trace[17294786] 'read index received' (duration: 324.642258ms)","trace[17294786] 'applied index is now lower than readState.Index' (duration: 53.355698ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:29.44565Z","caller":"traceutil/trace.go:171","msg":"trace[1567049900] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"412.413959ms","start":"2026-04-07T00:11:29.033178Z","end":"2026-04-07T00:11:29.445592Z","steps":["trace[1567049900] 'process raft request' (duration: 408.486624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:29.445804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.358795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:11:29.445867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.394316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:11:29.445912Z","caller":"traceutil/trace.go:171","msg":"trace[1916873073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"345.489778ms","start":"2026-04-07T00:11:29.100383Z","end":"2026-04-07T00:11:29.445873Z","steps":["trace[1916873073] 'agreement among raft nodes before linearized reading' (duration: 345.354685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:29.445912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:29.033152Z","time spent":"412.659744ms","remote":"127.0.0.1:58404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:11:29.445941Z","caller":"traceutil/trace.go:171","msg":"trace[1847153637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"378.479198ms","start":"2026-04-07T00:11:29.06743Z","end":"2026-04-07T00:11:29.445909Z","steps":["trace[1847153637] 'agreement among raft nodes before linearized reading' (duration: 378.181142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:29.446067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:29.100362Z","time spent":"345.691262ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:11:29.446087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:29.067416Z","time spent":"378.660031ms","remote":"127.0.0.1:58550","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-04-07T00:11:29.446748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.055984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T00:11:29.448292Z","caller":"traceutil/trace.go:171","msg":"trace[508660130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"264.147616ms","start":"2026-04-07T00:11:29.18265Z","end":"2026-04-07T00:11:29.446798Z","steps":["trace[508660130] 'agreement among raft nodes before linearized reading' (duration: 263.008162ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:44.932597Z","caller":"traceutil/trace.go:171","msg":"trace[1191950625] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"101.702601ms","start":"2026-04-07T00:11:44.83087Z","end":"2026-04-07T00:11:44.932573Z","steps":["trace[1191950625] 'process raft request' (duration: 101.561808ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:00.228069Z","caller":"traceutil/trace.go:171","msg":"trace[442595305] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"118.06092ms","start":"2026-04-07T00:13:00.109985Z","end":"2026-04-07T00:13:00.228046Z","steps":["trace[442595305] 'process raft request' (duration: 117.887927ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:00.34804Z","caller":"traceutil/trace.go:171","msg":"trace[959747657] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"112.958345ms","start":"2026-04-07T00:13:00.23506Z","end":"2026-04-07T00:13:00.348018Z","steps":["trace[959747657] 'process raft request' (duration: 112.783941ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:42.186183Z","caller":"traceutil/trace.go:171","msg":"trace[136421221] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"100.002275ms","start":"2026-04-07T00:13:42.086149Z","end":"2026-04-07T00:13:42.186151Z","steps":["trace[136421221] 'process raft request' (duration: 85.580534ms)","trace[136421221] 'compare' (duration: 14.270998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:13:42.648159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.146365ms","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-04-07T00:13:42.648303Z","caller":"traceutil/trace.go:171","msg":"trace[1249565896] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2169; }","duration":"132.342879ms","start":"2026-04-07T00:13:42.515936Z","end":"2026-04-07T00:13:42.648279Z","steps":["trace[1249565896] 'range keys from in-memory index tree' (duration: 132.114975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:42.648587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.848792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:13:42.64869Z","caller":"traceutil/trace.go:171","msg":"trace[403158212] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"154.600742ms","start":"2026-04-07T00:13:42.494069Z","end":"2026-04-07T00:13:42.64867Z","steps":["trace[403158212] 'process raft request' (duration: 18.403263ms)","trace[403158212] 'compare' (duration: 135.73196ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:42.892831Z","caller":"traceutil/trace.go:171","msg":"trace[2139057777] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"145.109645ms","start":"2026-04-07T00:13:42.747693Z","end":"2026-04-07T00:13:42.892802Z","steps":["trace[2139057777] 'process raft request' (duration: 113.810243ms)","trace[2139057777] 'compare' (duration: 30.852243ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:14:19.621453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.033212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:14:19.621568Z","caller":"traceutil/trace.go:171","msg":"trace[94914477] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2475; }","duration":"176.213935ms","start":"2026-04-07T00:14:19.445328Z","end":"2026-04-07T00:14:19.621542Z","steps":["trace[94914477] 'count revisions from in-memory index tree' (duration: 175.93418ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:55.012355Z","caller":"traceutil/trace.go:171","msg":"trace[375061386] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"116.943254ms","start":"2026-04-07T00:14:54.895388Z","end":"2026-04-07T00:14:55.012332Z","steps":["trace[375061386] 'process raft request' (duration: 116.810792ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:02.565922Z","caller":"traceutil/trace.go:171","msg":"trace[250495702] linearizableReadLoop","detail":"{readStateIndex:4516; appliedIndex:4515; }","duration":"113.418673ms","start":"2026-04-07T00:17:02.45248Z","end":"2026-04-07T00:17:02.565898Z","steps":["trace[250495702] 'read index received' (duration: 90.99511ms)","trace[250495702] 'applied index is now lower than readState.Index' (duration: 22.422272ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:02.566015Z","caller":"traceutil/trace.go:171","msg":"trace[917310742] transaction","detail":"{read_only:false; response_revision:4334; number_of_response:1; }","duration":"130.439213ms","start":"2026-04-07T00:17:02.435545Z","end":"2026-04-07T00:17:02.565984Z","steps":["trace[917310742] 'process raft request' (duration: 107.954628ms)","trace[917310742] 'compare' (duration: 22.002614ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:17:02.56621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.283218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-04-07T00:17:02.566271Z","caller":"traceutil/trace.go:171","msg":"trace[730461561] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4334; }","duration":"122.398114ms","start":"2026-04-07T00:17:02.443863Z","end":"2026-04-07T00:17:02.566261Z","steps":["trace[730461561] 'agreement among raft nodes before linearized reading' (duration: 122.228296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:02.566297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.597011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-07T00:17:02.566353Z","caller":"traceutil/trace.go:171","msg":"trace[338107672] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4334; }","duration":"109.690895ms","start":"2026-04-07T00:17:02.456648Z","end":"2026-04-07T00:17:02.566339Z","steps":["trace[338107672] 'agreement among raft nodes before linearized reading' (duration: 109.524848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:02.566548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.839549ms","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":"info","ts":"2026-04-07T00:17:02.56666Z","caller":"traceutil/trace.go:171","msg":"trace[1363436744] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4334; }","duration":"102.990286ms","start":"2026-04-07T00:17:02.463638Z","end":"2026-04-07T00:17:02.566628Z","steps":["trace[1363436744] 'agreement among raft nodes before linearized reading' (duration: 102.714474ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:02.747359Z","caller":"traceutil/trace.go:171","msg":"trace[1768256631] linearizableReadLoop","detail":"{readStateIndex:4528; appliedIndex:4527; }","duration":"110.69361ms","start":"2026-04-07T00:17:02.636626Z","end":"2026-04-07T00:17:02.747319Z","steps":["trace[1768256631] 'read index received' (duration: 109.481605ms)","trace[1768256631] 'applied index is now lower than readState.Index' (duration: 1.210825ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:02.747369Z","caller":"traceutil/trace.go:171","msg":"trace[420570048] transaction","detail":"{read_only:false; response_revision:4346; number_of_response:1; }","duration":"122.372652ms","start":"2026-04-07T00:17:02.624966Z","end":"2026-04-07T00:17:02.747338Z","steps":["trace[420570048] 'process raft request' (duration: 121.185558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:02.747707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.625403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-07T00:17:02.747762Z","caller":"traceutil/trace.go:171","msg":"trace[1489072245] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4346; }","duration":"107.723067ms","start":"2026-04-07T00:17:02.640025Z","end":"2026-04-07T00:17:02.747748Z","steps":["trace[1489072245] 'agreement among raft nodes before linearized reading' (duration: 107.526858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:02.7514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.614513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14497"} {"level":"info","ts":"2026-04-07T00:17:02.751475Z","caller":"traceutil/trace.go:171","msg":"trace[1078972344] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4346; }","duration":"138.274453ms","start":"2026-04-07T00:17:02.613183Z","end":"2026-04-07T00:17:02.751457Z","steps":["trace[1078972344] 'agreement among raft nodes before linearized reading' (duration: 134.251953ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:05.692513Z","caller":"traceutil/trace.go:171","msg":"trace[2106972005] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"103.193114ms","start":"2026-04-07T00:17:05.589292Z","end":"2026-04-07T00:17:05.692485Z","steps":["trace[2106972005] 'process raft request' (duration: 102.965414ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:09.493916Z","caller":"traceutil/trace.go:171","msg":"trace[514909391] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"138.492642ms","start":"2026-04-07T00:17:09.355393Z","end":"2026-04-07T00:17:09.493885Z","steps":["trace[514909391] 'process raft request' (duration: 43.929983ms)","trace[514909391] 'compare' (duration: 94.380891ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:09.501107Z","caller":"traceutil/trace.go:171","msg":"trace[800778831] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"137.670227ms","start":"2026-04-07T00:17:09.363409Z","end":"2026-04-07T00:17:09.501079Z","steps":["trace[800778831] 'process raft request' (duration: 137.463877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:18.963395Z","caller":"traceutil/trace.go:171","msg":"trace[955392699] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"143.59149ms","start":"2026-04-07T00:17:18.819758Z","end":"2026-04-07T00:17:18.963349Z","steps":["trace[955392699] 'process raft request' (duration: 49.396339ms)","trace[955392699] 'compare' (duration: 93.823805ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:18.963427Z","caller":"traceutil/trace.go:171","msg":"trace[1734564493] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"143.482995ms","start":"2026-04-07T00:17:18.819911Z","end":"2026-04-07T00:17:18.963394Z","steps":["trace[1734564493] 'process raft request' (duration: 143.36777ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:18.963497Z","caller":"traceutil/trace.go:171","msg":"trace[2110713178] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"143.438384ms","start":"2026-04-07T00:17:18.820037Z","end":"2026-04-07T00:17:18.963476Z","steps":["trace[2110713178] 'process raft request' (duration: 143.302948ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:18.963549Z","caller":"traceutil/trace.go:171","msg":"trace[1834565238] linearizableReadLoop","detail":"{readStateIndex:4920; appliedIndex:4917; }","duration":"131.853106ms","start":"2026-04-07T00:17:18.831683Z","end":"2026-04-07T00:17:18.963536Z","steps":["trace[1834565238] 'read index received' (duration: 37.467906ms)","trace[1834565238] 'applied index is now lower than readState.Index' (duration: 94.38446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:17:18.963723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.025293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f998j\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:17:18.963752Z","caller":"traceutil/trace.go:171","msg":"trace[1509796933] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f998j; range_end:; response_count:1; response_revision:4722; }","duration":"132.080656ms","start":"2026-04-07T00:17:18.831662Z","end":"2026-04-07T00:17:18.963743Z","steps":["trace[1509796933] 'agreement among raft nodes before linearized reading' (duration: 131.94946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:18.96377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.769182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:17:18.963808Z","caller":"traceutil/trace.go:171","msg":"trace[1201790420] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4722; }","duration":"112.863126ms","start":"2026-04-07T00:17:18.850936Z","end":"2026-04-07T00:17:18.963799Z","steps":["trace[1201790420] 'agreement among raft nodes before linearized reading' (duration: 112.72654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:19.427189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.747425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:17:19.427384Z","caller":"traceutil/trace.go:171","msg":"trace[687453160] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"237.04016ms","start":"2026-04-07T00:17:19.190285Z","end":"2026-04-07T00:17:19.427326Z","steps":["trace[687453160] 'process raft request' (duration: 28.007362ms)","trace[687453160] 'compare' (duration: 208.18519ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:19.495722Z","caller":"traceutil/trace.go:171","msg":"trace[206737441] linearizableReadLoop","detail":"{readStateIndex:4925; appliedIndex:4924; }","duration":"149.046704ms","start":"2026-04-07T00:17:19.346652Z","end":"2026-04-07T00:17:19.495699Z","steps":["trace[206737441] 'read index received' (duration: 148.871696ms)","trace[206737441] 'applied index is now lower than readState.Index' (duration: 173.968µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:19.496028Z","caller":"traceutil/trace.go:171","msg":"trace[823478695] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"269.733601ms","start":"2026-04-07T00:17:19.226281Z","end":"2026-04-07T00:17:19.496014Z","steps":["trace[823478695] 'process raft request' (duration: 269.311542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:19.496301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.643781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-07T00:17:19.496332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.489355ms","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-04-07T00:17:19.496343Z","caller":"traceutil/trace.go:171","msg":"trace[64731982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4726; }","duration":"149.696693ms","start":"2026-04-07T00:17:19.346636Z","end":"2026-04-07T00:17:19.496333Z","steps":["trace[64731982] 'agreement among raft nodes before linearized reading' (duration: 149.562877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:19.496363Z","caller":"traceutil/trace.go:171","msg":"trace[2129935986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4726; }","duration":"147.574118ms","start":"2026-04-07T00:17:19.348779Z","end":"2026-04-07T00:17:19.496353Z","steps":["trace[2129935986] 'agreement among raft nodes before linearized reading' (duration: 147.489244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:19.496757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.566119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T00:17:19.496787Z","caller":"traceutil/trace.go:171","msg":"trace[1644165683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4726; }","duration":"132.59736ms","start":"2026-04-07T00:17:19.364183Z","end":"2026-04-07T00:17:19.496781Z","steps":["trace[1644165683] 'agreement among raft nodes before linearized reading' (duration: 132.539807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:26.483639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.259493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:17:26.483862Z","caller":"traceutil/trace.go:171","msg":"trace[197977380] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"130.383421ms","start":"2026-04-07T00:17:26.353456Z","end":"2026-04-07T00:17:26.483839Z","steps":["trace[197977380] 'process raft request' (duration: 130.300147ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:26.483917Z","caller":"traceutil/trace.go:171","msg":"trace[1009045180] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"183.82941ms","start":"2026-04-07T00:17:26.30004Z","end":"2026-04-07T00:17:26.483869Z","steps":["trace[1009045180] 'process raft request' (duration: 64.247453ms)","trace[1009045180] 'compare' (duration: 119.151138ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:20:52.822806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3282} {"level":"info","ts":"2026-04-07T00:20:52.913377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3282,"took":"88.479425ms","hash":1141733516,"current-db-size-bytes":61136896,"current-db-size":"61 MB","current-db-size-in-use-bytes":34123776,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:20:52.913457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1141733516,"revision":3282,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T00:23:11.306183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.629993ms","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-04-07T00:23:11.306276Z","caller":"traceutil/trace.go:171","msg":"trace[471737408] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8057; }","duration":"256.766156ms","start":"2026-04-07T00:23:11.04949Z","end":"2026-04-07T00:23:11.306256Z","steps":["trace[471737408] 'range keys from in-memory index tree' (duration: 256.48058ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:22.489149Z","caller":"traceutil/trace.go:171","msg":"trace[22860888] transaction","detail":"{read_only:false; response_revision:8132; number_of_response:1; }","duration":"105.172553ms","start":"2026-04-07T00:23:22.383951Z","end":"2026-04-07T00:23:22.489124Z","steps":["trace[22860888] 'process raft request' (duration: 101.453639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:23.214461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.218894ms","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-04-07T00:23:23.214518Z","caller":"traceutil/trace.go:171","msg":"trace[1076803331] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8135; }","duration":"225.307577ms","start":"2026-04-07T00:23:22.989199Z","end":"2026-04-07T00:23:23.214506Z","steps":["trace[1076803331] 'range keys from in-memory index tree' (duration: 225.066232ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:23.304971Z","caller":"traceutil/trace.go:171","msg":"trace[477667600] linearizableReadLoop","detail":"{readStateIndex:8497; appliedIndex:8496; }","duration":"206.022522ms","start":"2026-04-07T00:23:23.098923Z","end":"2026-04-07T00:23:23.304945Z","steps":["trace[477667600] 'read index received' (duration: 205.874399ms)","trace[477667600] 'applied index is now lower than readState.Index' (duration: 146.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:23.305041Z","caller":"traceutil/trace.go:171","msg":"trace[354048670] transaction","detail":"{read_only:false; response_revision:8136; number_of_response:1; }","duration":"287.324988ms","start":"2026-04-07T00:23:23.017695Z","end":"2026-04-07T00:23:23.30502Z","steps":["trace[354048670] 'process raft request' (duration: 287.063243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:23.305127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.185315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:23:23.305167Z","caller":"traceutil/trace.go:171","msg":"trace[2085379082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8136; }","duration":"206.237786ms","start":"2026-04-07T00:23:23.098917Z","end":"2026-04-07T00:23:23.305155Z","steps":["trace[2085379082] 'agreement among raft nodes before linearized reading' (duration: 206.160154ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:23.451087Z","caller":"traceutil/trace.go:171","msg":"trace[1505641353] linearizableReadLoop","detail":"{readStateIndex:8498; appliedIndex:8497; }","duration":"144.564852ms","start":"2026-04-07T00:23:23.3065Z","end":"2026-04-07T00:23:23.451064Z","steps":["trace[1505641353] 'read index received' (duration: 109.148743ms)","trace[1505641353] 'applied index is now lower than readState.Index' (duration: 35.412319ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:23.451202Z","caller":"traceutil/trace.go:171","msg":"trace[1925270872] transaction","detail":"{read_only:false; response_revision:8137; number_of_response:1; }","duration":"233.567152ms","start":"2026-04-07T00:23:23.217598Z","end":"2026-04-07T00:23:23.451165Z","steps":["trace[1925270872] 'process raft request' (duration: 197.916309ms)","trace[1925270872] 'compare' (duration: 35.343287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:23:23.451317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.770496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:23:23.45136Z","caller":"traceutil/trace.go:171","msg":"trace[363054335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8137; }","duration":"144.878439ms","start":"2026-04-07T00:23:23.306471Z","end":"2026-04-07T00:23:23.45135Z","steps":["trace[363054335] 'agreement among raft nodes before linearized reading' (duration: 144.757816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:23.459063Z","caller":"traceutil/trace.go:171","msg":"trace[1440805606] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8137; }","duration":"146.601123ms","start":"2026-04-07T00:23:23.312438Z","end":"2026-04-07T00:23:23.459039Z","steps":["trace[1440805606] 'process raft request' (duration: 146.4698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:24.01601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.657444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:23:24.016129Z","caller":"traceutil/trace.go:171","msg":"trace[187595294] transaction","detail":"{read_only:false; response_revision:8145; number_of_response:1; }","duration":"347.833228ms","start":"2026-04-07T00:23:23.668265Z","end":"2026-04-07T00:23:24.016098Z","steps":["trace[187595294] 'process raft request' (duration: 24.00269ms)","trace[187595294] 'compare' (duration: 323.506941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:23:24.016186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:23:23.668252Z","time spent":"347.90728ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:23:24.9325Z","caller":"traceutil/trace.go:171","msg":"trace[1582538760] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"174.223385ms","start":"2026-04-07T00:23:24.758258Z","end":"2026-04-07T00:23:24.932481Z","steps":["trace[1582538760] 'process raft request' (duration: 174.103623ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:26.249597Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002150.wal"} {"level":"info","ts":"2026-04-07T00:23:51.396195Z","caller":"traceutil/trace.go:171","msg":"trace[980624593] linearizableReadLoop","detail":"{readStateIndex:8750; appliedIndex:8749; }","duration":"295.290006ms","start":"2026-04-07T00:23:51.100885Z","end":"2026-04-07T00:23:51.396175Z","steps":["trace[980624593] 'read index received' (duration: 295.211804ms)","trace[980624593] 'applied index is now lower than readState.Index' (duration: 77.032µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:51.396305Z","caller":"traceutil/trace.go:171","msg":"trace[1378828830] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"362.96659ms","start":"2026-04-07T00:23:51.0333Z","end":"2026-04-07T00:23:51.396267Z","steps":["trace[1378828830] 'process raft request' (duration: 362.747305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:51.396335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.433329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:23:51.396533Z","caller":"traceutil/trace.go:171","msg":"trace[1413214516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8377; }","duration":"295.670593ms","start":"2026-04-07T00:23:51.100829Z","end":"2026-04-07T00:23:51.3965Z","steps":["trace[1413214516] 'agreement among raft nodes before linearized reading' (duration: 295.425838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:51.396521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:23:51.033281Z","time spent":"363.129272ms","remote":"127.0.0.1:58550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:23:51.606267Z","caller":"traceutil/trace.go:171","msg":"trace[791629098] linearizableReadLoop","detail":"{readStateIndex:8751; appliedIndex:8750; }","duration":"209.968381ms","start":"2026-04-07T00:23:51.396256Z","end":"2026-04-07T00:23:51.606225Z","steps":["trace[791629098] 'read index received' (duration: 143.288667ms)","trace[791629098] 'applied index is now lower than readState.Index' (duration: 66.677733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:23:51.606353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.134913ms","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-04-07T00:23:51.606355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.99717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:23:51.606416Z","caller":"traceutil/trace.go:171","msg":"trace[318504347] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8377; }","duration":"411.233675ms","start":"2026-04-07T00:23:51.195168Z","end":"2026-04-07T00:23:51.606401Z","steps":["trace[318504347] 'agreement among raft nodes before linearized reading' (duration: 411.062932ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:51.606422Z","caller":"traceutil/trace.go:171","msg":"trace[2048057464] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8377; }","duration":"470.137233ms","start":"2026-04-07T00:23:51.136272Z","end":"2026-04-07T00:23:51.60641Z","steps":["trace[2048057464] 'agreement among raft nodes before linearized reading' (duration: 469.97652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:51.606447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:23:51.195148Z","time spent":"411.294206ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:23:51.606456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:23:51.136241Z","time spent":"470.208105ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":31,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:23:51.60649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.726444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:23:51.606577Z","caller":"traceutil/trace.go:171","msg":"trace[1809966337] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:8377; }","duration":"288.00781ms","start":"2026-04-07T00:23:51.318553Z","end":"2026-04-07T00:23:51.606561Z","steps":["trace[1809966337] 'agreement among raft nodes before linearized reading' (duration: 287.726975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:51.606585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.895418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:23:51.606639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.309088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f998j\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T00:23:51.606669Z","caller":"traceutil/trace.go:171","msg":"trace[143573918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8377; }","duration":"209.014251ms","start":"2026-04-07T00:23:51.397634Z","end":"2026-04-07T00:23:51.606649Z","steps":["trace[143573918] 'agreement among raft nodes before linearized reading' (duration: 208.857118ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:51.606695Z","caller":"traceutil/trace.go:171","msg":"trace[2072847625] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f998j; range_end:; response_count:1; response_revision:8377; }","duration":"496.415099ms","start":"2026-04-07T00:23:51.110265Z","end":"2026-04-07T00:23:51.606681Z","steps":["trace[2072847625] 'agreement among raft nodes before linearized reading' (duration: 496.154124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:51.606753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:23:51.110255Z","time spent":"496.483882ms","remote":"127.0.0.1:58470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-f998j\" "} {"level":"info","ts":"2026-04-07T00:24:04.499259Z","caller":"traceutil/trace.go:171","msg":"trace[517475140] linearizableReadLoop","detail":"{readStateIndex:8886; appliedIndex:8885; }","duration":"100.210834ms","start":"2026-04-07T00:24:04.399021Z","end":"2026-04-07T00:24:04.499232Z","steps":["trace[517475140] 'read index received' (duration: 100.058391ms)","trace[517475140] 'applied index is now lower than readState.Index' (duration: 151.073µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:24:04.499347Z","caller":"traceutil/trace.go:171","msg":"trace[397476238] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"120.004401ms","start":"2026-04-07T00:24:04.379318Z","end":"2026-04-07T00:24:04.499322Z","steps":["trace[397476238] 'process raft request' (duration: 119.755436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:24:04.499496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.46172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-07T00:24:04.499541Z","caller":"traceutil/trace.go:171","msg":"trace[559576796] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8505; }","duration":"100.555311ms","start":"2026-04-07T00:24:04.39897Z","end":"2026-04-07T00:24:04.499525Z","steps":["trace[559576796] 'agreement among raft nodes before linearized reading' (duration: 100.391358ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:04.906314Z","caller":"traceutil/trace.go:171","msg":"trace[1548062088] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"110.264215ms","start":"2026-04-07T00:24:04.796008Z","end":"2026-04-07T00:24:04.906272Z","steps":["trace[1548062088] 'process raft request' (duration: 70.455599ms)","trace[1548062088] 'compare' (duration: 39.660453ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:24:06.173738Z","caller":"traceutil/trace.go:171","msg":"trace[1897981121] linearizableReadLoop","detail":"{readStateIndex:8904; appliedIndex:8902; }","duration":"239.031891ms","start":"2026-04-07T00:24:05.934663Z","end":"2026-04-07T00:24:06.173695Z","steps":["trace[1897981121] 'read index received' (duration: 201.239555ms)","trace[1897981121] 'applied index is now lower than readState.Index' (duration: 37.790606ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:24:06.173841Z","caller":"traceutil/trace.go:171","msg":"trace[1082526115] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"322.177945ms","start":"2026-04-07T00:24:05.851626Z","end":"2026-04-07T00:24:06.173804Z","steps":["trace[1082526115] 'process raft request' (duration: 321.98272ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:06.173857Z","caller":"traceutil/trace.go:171","msg":"trace[1288256346] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"325.056753ms","start":"2026-04-07T00:24:05.848764Z","end":"2026-04-07T00:24:06.173821Z","steps":["trace[1288256346] 'process raft request' (duration: 287.124213ms)","trace[1288256346] 'compare' (duration: 37.49772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:24:06.173916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.230326ms","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:5"} {"level":"info","ts":"2026-04-07T00:24:06.173985Z","caller":"traceutil/trace.go:171","msg":"trace[1314077875] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8521; }","duration":"239.354768ms","start":"2026-04-07T00:24:05.934614Z","end":"2026-04-07T00:24:06.173968Z","steps":["trace[1314077875] 'agreement among raft nodes before linearized reading' (duration: 239.218656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:24:06.174007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:24:05.851598Z","time spent":"322.332759ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:24:06.174129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.95081ms","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-04-07T00:24:06.17419Z","caller":"traceutil/trace.go:171","msg":"trace[1793030336] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8521; }","duration":"165.043531ms","start":"2026-04-07T00:24:06.009126Z","end":"2026-04-07T00:24:06.17417Z","steps":["trace[1793030336] 'agreement among raft nodes before linearized reading' (duration: 164.798847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:24:06.174553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:24:05.848737Z","time spent":"325.209236ms","remote":"127.0.0.1:58322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:25:11.476441Z","caller":"traceutil/trace.go:171","msg":"trace[1995572864] transaction","detail":"{read_only:false; response_revision:9233; number_of_response:1; }","duration":"244.650087ms","start":"2026-04-07T00:25:11.231761Z","end":"2026-04-07T00:25:11.476411Z","steps":["trace[1995572864] 'process raft request' (duration: 244.468984ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:25:11.593735Z","caller":"traceutil/trace.go:171","msg":"trace[798965177] transaction","detail":"{read_only:false; number_of_response:0; response_revision:9233; }","duration":"108.967297ms","start":"2026-04-07T00:25:11.484744Z","end":"2026-04-07T00:25:11.593711Z","steps":["trace[798965177] 'process raft request' (duration: 108.866055ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:25:49.227513Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7e142186203fa167","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:25:49.233889Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:25:49.234019Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:25:52.828489Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6669} {"level":"info","ts":"2026-04-07T00:25:52.925231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6669,"took":"94.782623ms","hash":2534163636,"current-db-size-bytes":61136896,"current-db-size":"61 MB","current-db-size-in-use-bytes":27373568,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-07T00:25:52.925286Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2534163636,"revision":6669,"compact-revision":3282} {"level":"info","ts":"2026-04-07T00:30:52.835185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9595} {"level":"info","ts":"2026-04-07T00:30:52.92294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9595,"took":"84.586282ms","hash":1330265394,"current-db-size-bytes":61136896,"current-db-size":"61 MB","current-db-size-in-use-bytes":25247744,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T00:30:52.923003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1330265394,"revision":9595,"compact-revision":6669}