{"level":"warn","ts":"2026-04-20T11:51:32.942202Z","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-20T11:51:32.942304Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-20T11:51:32.942383Z","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-20T11:51:32.942398Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-20T11:51:32.94242Z","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-20T11:51:32.943073Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-20T11:51:32.943156Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-20T11:51:32.948556Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.178725ms"} {"level":"info","ts":"2026-04-20T11:51:32.955222Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-20T11:51:32.955306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:51:32.955331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-20T11:51:32.955346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:51:32.95536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-20T11:51:32.955405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-20T11:51:32.963016Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:51:32.964428Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:51:32.965495Z","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-20T11:51:32.967888Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:51:32.96934Z","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-20T11:51:32.968904Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:51:32.969414Z","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-20T11:51:32.969505Z","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-20T11:51:32.970407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-20T11:51:32.970601Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-20T11:51:32.972944Z","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-20T11:51:32.973163Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:51:32.973205Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:51:32.973287Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-20T11:51:32.973305Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-20T11:51:33.156202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:51:33.156286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:51:33.156312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-20T11:51:33.156327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:51:33.156339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-20T11:51:33.156351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-20T11:51:33.15636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-20T11:51:33.157482Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:51:33.1575Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:51:33.157663Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:51:33.157714Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:51:33.157797Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:51:33.157847Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:51:33.159874Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-20T11:51:33.160267Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:51:33.160266Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T11:51:33.160335Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:51:33.16036Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:52:09.172032Z","caller":"traceutil/trace.go:171","msg":"trace[1945587558] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"170.236562ms","start":"2026-04-20T11:52:09.001729Z","end":"2026-04-20T11:52:09.171965Z","steps":["trace[1945587558] 'process raft request' (duration: 169.457077ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:09.332288Z","caller":"traceutil/trace.go:171","msg":"trace[2099839093] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"144.664704ms","start":"2026-04-20T11:52:09.187586Z","end":"2026-04-20T11:52:09.332251Z","steps":["trace[2099839093] 'process raft request' (duration: 144.405009ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:09.332394Z","caller":"traceutil/trace.go:171","msg":"trace[1780850850] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:598; }","duration":"114.701684ms","start":"2026-04-20T11:52:09.217658Z","end":"2026-04-20T11:52:09.33236Z","steps":["trace[1780850850] 'read index received' (duration: 27.902682ms)","trace[1780850850] 'applied index is now lower than readState.Index' (duration: 86.796852ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:52:09.332527Z","caller":"traceutil/trace.go:171","msg":"trace[635628519] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"103.041537ms","start":"2026-04-20T11:52:09.229448Z","end":"2026-04-20T11:52:09.33249Z","steps":["trace[635628519] 'process raft request' (duration: 102.68125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:09.332663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.989079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T11:52:09.332769Z","caller":"traceutil/trace.go:171","msg":"trace[761644033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"115.147532ms","start":"2026-04-20T11:52:09.217607Z","end":"2026-04-20T11:52:09.332755Z","steps":["trace[761644033] 'agreement among raft nodes before linearized reading' (duration: 114.920628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:52:09.929139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.79355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:52:09.929239Z","caller":"traceutil/trace.go:171","msg":"trace[17430375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"195.942143ms","start":"2026-04-20T11:52:09.733266Z","end":"2026-04-20T11:52:09.929209Z","steps":["trace[17430375] 'range keys from in-memory index tree' (duration: 195.694409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:52:09.932413Z","caller":"traceutil/trace.go:171","msg":"trace[2026502510] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"193.879663ms","start":"2026-04-20T11:52:09.738512Z","end":"2026-04-20T11:52:09.932392Z","steps":["trace[2026502510] 'process raft request' (duration: 193.268062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:02.923974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.793928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:53:02.924081Z","caller":"traceutil/trace.go:171","msg":"trace[197049536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1173; }","duration":"190.957771ms","start":"2026-04-20T11:53:02.733098Z","end":"2026-04-20T11:53:02.924056Z","steps":["trace[197049536] 'range keys from in-memory index tree' (duration: 190.674855ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:23.669669Z","caller":"traceutil/trace.go:171","msg":"trace[1050396002] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"107.87382ms","start":"2026-04-20T11:53:23.561769Z","end":"2026-04-20T11:53:23.669642Z","steps":["trace[1050396002] 'process raft request' (duration: 107.712297ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:53:23.825629Z","caller":"traceutil/trace.go:171","msg":"trace[704621669] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"100.722458ms","start":"2026-04-20T11:53:23.72487Z","end":"2026-04-20T11:53:23.825592Z","steps":["trace[704621669] 'process raft request' (duration: 30.049937ms)","trace[704621669] 'compare' (duration: 70.407146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:53:47.275618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.067208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:53:47.275735Z","caller":"traceutil/trace.go:171","msg":"trace[1171809924] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1891; }","duration":"378.257726ms","start":"2026-04-20T11:53:46.897461Z","end":"2026-04-20T11:53:47.275719Z","steps":["trace[1171809924] 'read index received' (duration: 79.569186ms)","trace[1171809924] 'applied index is now lower than readState.Index' (duration: 298.68711ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:53:47.275794Z","caller":"traceutil/trace.go:171","msg":"trace[1502284742] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"382.356892ms","start":"2026-04-20T11:53:46.893381Z","end":"2026-04-20T11:53:47.275738Z","steps":["trace[1502284742] 'process raft request' (duration: 83.605041ms)","trace[1502284742] 'compare' (duration: 297.938256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:53:47.275903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.437439ms","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-20T11:53:47.276163Z","caller":"traceutil/trace.go:171","msg":"trace[1896170916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1818; }","duration":"378.732383ms","start":"2026-04-20T11:53:46.897419Z","end":"2026-04-20T11:53:47.276151Z","steps":["trace[1896170916] 'agreement among raft nodes before linearized reading' (duration: 378.344086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:53:47.276117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:46.893358Z","time spent":"382.668687ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:53:47.276212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:53:46.897397Z","time spent":"378.804485ms","remote":"127.0.0.1:34526","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-04-20T11:54:00.161438Z","caller":"traceutil/trace.go:171","msg":"trace[177324276] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"136.903039ms","start":"2026-04-20T11:54:00.024513Z","end":"2026-04-20T11:54:00.161416Z","steps":["trace[177324276] 'process raft request' (duration: 136.739866ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:54:00.162158Z","caller":"traceutil/trace.go:171","msg":"trace[1320802396] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"136.795797ms","start":"2026-04-20T11:54:00.025324Z","end":"2026-04-20T11:54:00.162119Z","steps":["trace[1320802396] 'process raft request' (duration: 136.695275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:54:33.748768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.881732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:54:33.749082Z","caller":"traceutil/trace.go:171","msg":"trace[1956646853] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"233.59526ms","start":"2026-04-20T11:54:33.515411Z","end":"2026-04-20T11:54:33.749007Z","steps":["trace[1956646853] 'process raft request' (duration: 71.400522ms)","trace[1956646853] 'compare' (duration: 161.79028ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:22.12151Z","caller":"traceutil/trace.go:171","msg":"trace[80561065] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"139.666187ms","start":"2026-04-20T11:55:21.981819Z","end":"2026-04-20T11:55:22.121486Z","steps":["trace[80561065] 'process raft request' (duration: 139.521844ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:22.122014Z","caller":"traceutil/trace.go:171","msg":"trace[1111594042] linearizableReadLoop","detail":"{readStateIndex:2528; appliedIndex:2528; }","duration":"118.648784ms","start":"2026-04-20T11:55:22.003348Z","end":"2026-04-20T11:55:22.121997Z","steps":["trace[1111594042] 'read index received' (duration: 118.639104ms)","trace[1111594042] 'applied index is now lower than readState.Index' (duration: 8.62µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:22.122411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.048371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-20T11:55:22.122509Z","caller":"traceutil/trace.go:171","msg":"trace[104995635] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2434; }","duration":"119.221824ms","start":"2026-04-20T11:55:22.003272Z","end":"2026-04-20T11:55:22.122493Z","steps":["trace[104995635] 'agreement among raft nodes before linearized reading' (duration: 119.034691ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:56.569196Z","caller":"traceutil/trace.go:171","msg":"trace[394611957] transaction","detail":"{read_only:false; response_revision:2621; number_of_response:1; }","duration":"278.354325ms","start":"2026-04-20T11:55:56.290814Z","end":"2026-04-20T11:55:56.569168Z","steps":["trace[394611957] 'process raft request' (duration: 278.020718ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:56.5937Z","caller":"traceutil/trace.go:171","msg":"trace[493235326] linearizableReadLoop","detail":"{readStateIndex:2723; appliedIndex:2721; }","duration":"203.863662ms","start":"2026-04-20T11:55:56.38982Z","end":"2026-04-20T11:55:56.593683Z","steps":["trace[493235326] 'read index received' (duration: 179.046927ms)","trace[493235326] 'applied index is now lower than readState.Index' (duration: 24.815775ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:56.59381Z","caller":"traceutil/trace.go:171","msg":"trace[1133431467] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"208.156272ms","start":"2026-04-20T11:55:56.38562Z","end":"2026-04-20T11:55:56.593776Z","steps":["trace[1133431467] 'process raft request' (duration: 207.943108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:56.593987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.158147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T11:55:56.594022Z","caller":"traceutil/trace.go:171","msg":"trace[202513836] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2622; }","duration":"204.253349ms","start":"2026-04-20T11:55:56.389757Z","end":"2026-04-20T11:55:56.594011Z","steps":["trace[202513836] 'agreement among raft nodes before linearized reading' (duration: 204.026104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:56.594162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.23337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T11:55:56.594183Z","caller":"traceutil/trace.go:171","msg":"trace[1409198252] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:2622; }","duration":"103.308232ms","start":"2026-04-20T11:55:56.49087Z","end":"2026-04-20T11:55:56.594178Z","steps":["trace[1409198252] 'agreement among raft nodes before linearized reading' (duration: 103.245639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:56.594295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.250207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T11:55:56.594414Z","caller":"traceutil/trace.go:171","msg":"trace[439577434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2622; }","duration":"175.513003ms","start":"2026-04-20T11:55:56.418878Z","end":"2026-04-20T11:55:56.594391Z","steps":["trace[439577434] 'agreement among raft nodes before linearized reading' (duration: 174.957141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:56.909599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.112088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:55:56.90979Z","caller":"traceutil/trace.go:171","msg":"trace[1636759900] linearizableReadLoop","detail":"{readStateIndex:2730; appliedIndex:2728; }","duration":"174.491472ms","start":"2026-04-20T11:55:56.735281Z","end":"2026-04-20T11:55:56.909772Z","steps":["trace[1636759900] 'read index received' (duration: 25.105949ms)","trace[1636759900] 'applied index is now lower than readState.Index' (duration: 149.384273ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:56.909849Z","caller":"traceutil/trace.go:171","msg":"trace[1350477175] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"176.303557ms","start":"2026-04-20T11:55:56.733511Z","end":"2026-04-20T11:55:56.909815Z","steps":["trace[1350477175] 'process raft request' (duration: 176.204765ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:56.909886Z","caller":"traceutil/trace.go:171","msg":"trace[606836132] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"176.689513ms","start":"2026-04-20T11:55:56.733152Z","end":"2026-04-20T11:55:56.909842Z","steps":["trace[606836132] 'process raft request' (duration: 27.235829ms)","trace[606836132] 'compare' (duration: 148.983466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:55:56.909969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.698066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:56.910037Z","caller":"traceutil/trace.go:171","msg":"trace[1534752047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2628; }","duration":"174.822399ms","start":"2026-04-20T11:55:56.735198Z","end":"2026-04-20T11:55:56.91002Z","steps":["trace[1534752047] 'agreement among raft nodes before linearized reading' (duration: 174.720227ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:55:57.117907Z","caller":"traceutil/trace.go:171","msg":"trace[1251031032] linearizableReadLoop","detail":"{readStateIndex:2731; appliedIndex:2730; }","duration":"179.559517ms","start":"2026-04-20T11:55:56.938323Z","end":"2026-04-20T11:55:57.117882Z","steps":["trace[1251031032] 'read index received' (duration: 179.371204ms)","trace[1251031032] 'applied index is now lower than readState.Index' (duration: 187.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:57.117998Z","caller":"traceutil/trace.go:171","msg":"trace[1964489656] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"202.972104ms","start":"2026-04-20T11:55:56.914977Z","end":"2026-04-20T11:55:57.11795Z","steps":["trace[1964489656] 'process raft request' (duration: 202.784321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:57.118097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.741311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:57.118177Z","caller":"traceutil/trace.go:171","msg":"trace[1218562029] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2629; }","duration":"179.842233ms","start":"2026-04-20T11:55:56.938317Z","end":"2026-04-20T11:55:57.11816Z","steps":["trace[1218562029] 'agreement among raft nodes before linearized reading' (duration: 179.651999ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:59.70328Z","caller":"traceutil/trace.go:171","msg":"trace[1831357425] linearizableReadLoop","detail":"{readStateIndex:4989; appliedIndex:4988; }","duration":"221.328349ms","start":"2026-04-20T11:59:59.481905Z","end":"2026-04-20T11:59:59.703233Z","steps":["trace[1831357425] 'read index received' (duration: 183.180983ms)","trace[1831357425] 'applied index is now lower than readState.Index' (duration: 38.144576ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:59:59.703593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.647234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b3d89d2c-5241-4335-b92a-9b1cd09f071c\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-04-20T11:59:59.703685Z","caller":"traceutil/trace.go:171","msg":"trace[132907568] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b3d89d2c-5241-4335-b92a-9b1cd09f071c; range_end:; response_count:1; response_revision:4805; }","duration":"221.812508ms","start":"2026-04-20T11:59:59.481853Z","end":"2026-04-20T11:59:59.703666Z","steps":["trace[132907568] 'agreement among raft nodes before linearized reading' (duration: 221.608884ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:59.703714Z","caller":"traceutil/trace.go:171","msg":"trace[1972303308] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"245.798568ms","start":"2026-04-20T11:59:59.457859Z","end":"2026-04-20T11:59:59.703658Z","steps":["trace[1972303308] 'process raft request' (duration: 207.193574ms)","trace[1972303308] 'compare' (duration: 37.705887ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:59:59.743957Z","caller":"traceutil/trace.go:171","msg":"trace[200448573] transaction","detail":"{read_only:false; response_revision:4806; number_of_response:1; }","duration":"261.589504ms","start":"2026-04-20T11:59:59.482338Z","end":"2026-04-20T11:59:59.743927Z","steps":["trace[200448573] 'process raft request' (duration: 261.375229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:00.235077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.741432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:00:00.235425Z","caller":"traceutil/trace.go:171","msg":"trace[1809480688] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"132.265359ms","start":"2026-04-20T12:00:00.10312Z","end":"2026-04-20T12:00:00.235385Z","steps":["trace[1809480688] 'process raft request' (duration: 26.054359ms)","trace[1809480688] 'compare' (duration: 105.200372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:00:00.235791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.478556ms","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-04-20T12:00:00.235876Z","caller":"traceutil/trace.go:171","msg":"trace[1211477930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4814; }","duration":"121.610019ms","start":"2026-04-20T12:00:00.114247Z","end":"2026-04-20T12:00:00.235857Z","steps":["trace[1211477930] 'agreement among raft nodes before linearized reading' (duration: 121.294003ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:00.235481Z","caller":"traceutil/trace.go:171","msg":"trace[939744458] linearizableReadLoop","detail":"{readStateIndex:4999; appliedIndex:4998; }","duration":"121.12224ms","start":"2026-04-20T12:00:00.114303Z","end":"2026-04-20T12:00:00.235426Z","steps":["trace[939744458] 'read index received' (duration: 14.694095ms)","trace[939744458] 'applied index is now lower than readState.Index' (duration: 106.426275ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:00:00.23655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.57763ms","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-04-20T12:00:00.23659Z","caller":"traceutil/trace.go:171","msg":"trace[105137168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4814; }","duration":"119.678152ms","start":"2026-04-20T12:00:00.116901Z","end":"2026-04-20T12:00:00.236579Z","steps":["trace[105137168] 'agreement among raft nodes before linearized reading' (duration: 119.54099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:37.792551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.642043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:00:37.792692Z","caller":"traceutil/trace.go:171","msg":"trace[374427001] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"309.651724ms","start":"2026-04-20T12:00:37.48302Z","end":"2026-04-20T12:00:37.792672Z","steps":["trace[374427001] 'process raft request' (duration: 30.791027ms)","trace[374427001] 'compare' (duration: 278.47106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:00:37.792902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:37.482994Z","time spent":"309.745916ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:00:38.099836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.600002ms","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-20T12:00:38.099992Z","caller":"traceutil/trace.go:171","msg":"trace[1828408925] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5380; }","duration":"190.797256ms","start":"2026-04-20T12:00:37.909172Z","end":"2026-04-20T12:00:38.099969Z","steps":["trace[1828408925] 'range keys from in-memory index tree' (duration: 190.577092ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:38.114815Z","caller":"traceutil/trace.go:171","msg":"trace[1902652638] linearizableReadLoop","detail":"{readStateIndex:5583; appliedIndex:5582; }","duration":"277.42371ms","start":"2026-04-20T12:00:37.837376Z","end":"2026-04-20T12:00:38.114799Z","steps":["trace[1902652638] 'read index received' (duration: 277.219627ms)","trace[1902652638] 'applied index is now lower than readState.Index' (duration: 203.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:38.114923Z","caller":"traceutil/trace.go:171","msg":"trace[2106372233] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"283.215878ms","start":"2026-04-20T12:00:37.831696Z","end":"2026-04-20T12:00:38.114912Z","steps":["trace[2106372233] 'process raft request' (duration: 282.966294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:38.115044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.647494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:00:38.115103Z","caller":"traceutil/trace.go:171","msg":"trace[2053010552] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5381; }","duration":"277.745136ms","start":"2026-04-20T12:00:37.837345Z","end":"2026-04-20T12:00:38.11509Z","steps":["trace[2053010552] 'agreement among raft nodes before linearized reading' (duration: 277.645614ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:38.123789Z","caller":"traceutil/trace.go:171","msg":"trace[967927962] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"231.674162ms","start":"2026-04-20T12:00:37.892072Z","end":"2026-04-20T12:00:38.123746Z","steps":["trace[967927962] 'process raft request' (duration: 231.421397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:38.124051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.940571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T12:00:38.124148Z","caller":"traceutil/trace.go:171","msg":"trace[256264726] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5382; }","duration":"213.100144ms","start":"2026-04-20T12:00:37.911025Z","end":"2026-04-20T12:00:38.124125Z","steps":["trace[256264726] 'agreement among raft nodes before linearized reading' (duration: 212.755497ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:38.238899Z","caller":"traceutil/trace.go:171","msg":"trace[1159906241] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"115.2854ms","start":"2026-04-20T12:00:38.123581Z","end":"2026-04-20T12:00:38.238866Z","steps":["trace[1159906241] 'process raft request' (duration: 93.278608ms)","trace[1159906241] 'compare' (duration: 21.86864ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:38.25039Z","caller":"traceutil/trace.go:171","msg":"trace[405906074] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"126.767906ms","start":"2026-04-20T12:00:38.123597Z","end":"2026-04-20T12:00:38.250365Z","steps":["trace[405906074] 'process raft request' (duration: 126.523181ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:38.250491Z","caller":"traceutil/trace.go:171","msg":"trace[1838662150] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"126.699195ms","start":"2026-04-20T12:00:38.123711Z","end":"2026-04-20T12:00:38.25041Z","steps":["trace[1838662150] 'process raft request' (duration: 126.546672ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:38.250736Z","caller":"traceutil/trace.go:171","msg":"trace[1670632265] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5584; }","duration":"124.128616ms","start":"2026-04-20T12:00:38.126587Z","end":"2026-04-20T12:00:38.250715Z","steps":["trace[1670632265] 'read index received' (duration: 90.432075ms)","trace[1670632265] 'applied index is now lower than readState.Index' (duration: 33.695121ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:38.250917Z","caller":"traceutil/trace.go:171","msg":"trace[1295245927] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"126.777076ms","start":"2026-04-20T12:00:38.124085Z","end":"2026-04-20T12:00:38.250862Z","steps":["trace[1295245927] 'process raft request' (duration: 126.219626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:38.251212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.591145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3a302dce-80f0-4c5f-beb1-d32e12392743\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-20T12:00:38.25131Z","caller":"traceutil/trace.go:171","msg":"trace[1265117970] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3a302dce-80f0-4c5f-beb1-d32e12392743; range_end:; response_count:1; response_revision:5386; }","duration":"124.728787ms","start":"2026-04-20T12:00:38.126553Z","end":"2026-04-20T12:00:38.251282Z","steps":["trace[1265117970] 'agreement among raft nodes before linearized reading' (duration: 124.431571ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:16.8231Z","caller":"traceutil/trace.go:171","msg":"trace[676331002] transaction","detail":"{read_only:false; response_revision:5750; number_of_response:1; }","duration":"145.23349ms","start":"2026-04-20T12:01:16.677827Z","end":"2026-04-20T12:01:16.82306Z","steps":["trace[676331002] 'process raft request' (duration: 145.102558ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:16.823165Z","caller":"traceutil/trace.go:171","msg":"trace[1475854358] transaction","detail":"{read_only:false; response_revision:5749; number_of_response:1; }","duration":"147.138916ms","start":"2026-04-20T12:01:16.675915Z","end":"2026-04-20T12:01:16.823054Z","steps":["trace[1475854358] 'process raft request' (duration: 72.912426ms)","trace[1475854358] 'compare' (duration: 73.881204ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:01:33.432837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2820} {"level":"info","ts":"2026-04-20T12:01:33.503985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2820,"took":"69.723396ms","hash":2915791877,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":32784384,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-20T12:01:33.504059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2915791877,"revision":2820,"compact-revision":-1} {"level":"info","ts":"2026-04-20T12:06:33.442288Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5887} {"level":"info","ts":"2026-04-20T12:06:33.525775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5887,"took":"80.994275ms","hash":400525411,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":26497024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-20T12:06:33.525858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":400525411,"revision":5887,"compact-revision":2820} {"level":"warn","ts":"2026-04-20T12:06:35.700921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.680296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nl7fp\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-04-20T12:06:35.701069Z","caller":"traceutil/trace.go:171","msg":"trace[540999296] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nl7fp; range_end:; response_count:1; response_revision:8101; }","duration":"203.90925ms","start":"2026-04-20T12:06:35.497125Z","end":"2026-04-20T12:06:35.701034Z","steps":["trace[540999296] 'range keys from in-memory index tree' (duration: 203.431791ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:06:35.70093Z","caller":"traceutil/trace.go:171","msg":"trace[1618108629] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"105.523578ms","start":"2026-04-20T12:06:35.595362Z","end":"2026-04-20T12:06:35.700886Z","steps":["trace[1618108629] 'process raft request' (duration: 92.298128ms)","trace[1618108629] 'compare' (duration: 12.979125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:06:36.648608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.471663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:06:36.64873Z","caller":"traceutil/trace.go:171","msg":"trace[794020253] linearizableReadLoop","detail":"{readStateIndex:8394; appliedIndex:8393; }","duration":"330.686797ms","start":"2026-04-20T12:06:36.318022Z","end":"2026-04-20T12:06:36.648709Z","steps":["trace[794020253] 'read index received' (duration: 25.020811ms)","trace[794020253] 'applied index is now lower than readState.Index' (duration: 305.664446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:06:36.648987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.558823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T12:06:36.648992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.251896ms","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-04-20T12:06:36.649056Z","caller":"traceutil/trace.go:171","msg":"trace[104408042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8104; }","duration":"357.656854ms","start":"2026-04-20T12:06:36.291359Z","end":"2026-04-20T12:06:36.649016Z","steps":["trace[104408042] 'agreement among raft nodes before linearized reading' (duration: 357.42998ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:06:36.649072Z","caller":"traceutil/trace.go:171","msg":"trace[556303324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8104; }","duration":"340.378319ms","start":"2026-04-20T12:06:36.308673Z","end":"2026-04-20T12:06:36.649051Z","steps":["trace[556303324] 'agreement among raft nodes before linearized reading' (duration: 340.114934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:36.649116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:36.308659Z","time spent":"340.4485ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T12:06:36.649134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:36.291334Z","time spent":"357.762597ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T12:06:36.648959Z","caller":"traceutil/trace.go:171","msg":"trace[773748141] transaction","detail":"{read_only:false; response_revision:8104; number_of_response:1; }","duration":"342.51011ms","start":"2026-04-20T12:06:36.306361Z","end":"2026-04-20T12:06:36.648871Z","steps":["trace[773748141] 'process raft request' (duration: 36.671731ms)","trace[773748141] 'compare' (duration: 305.317899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:06:36.649439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:36.306313Z","time spent":"343.0243ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:06:36.687205Z","caller":"traceutil/trace.go:171","msg":"trace[842841881] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"363.786601ms","start":"2026-04-20T12:06:36.323392Z","end":"2026-04-20T12:06:36.687179Z","steps":["trace[842841881] 'process raft request' (duration: 363.478985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:36.687339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:06:36.323378Z","time spent":"363.880742ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:06:36.687499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.458129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T12:06:36.687551Z","caller":"traceutil/trace.go:171","msg":"trace[206273487] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:8105; }","duration":"162.566751ms","start":"2026-04-20T12:06:36.524969Z","end":"2026-04-20T12:06:36.687535Z","steps":["trace[206273487] 'agreement among raft nodes before linearized reading' (duration: 162.423958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:36.687345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.71213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-20T12:06:36.687679Z","caller":"traceutil/trace.go:171","msg":"trace[524148152] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8105; }","duration":"218.104177ms","start":"2026-04-20T12:06:36.469548Z","end":"2026-04-20T12:06:36.687652Z","steps":["trace[524148152] 'agreement among raft nodes before linearized reading' (duration: 217.583578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:36.687798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.501902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T12:06:36.687951Z","caller":"traceutil/trace.go:171","msg":"trace[2062462391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8105; }","duration":"221.695715ms","start":"2026-04-20T12:06:36.4662Z","end":"2026-04-20T12:06:36.687896Z","steps":["trace[2062462391] 'agreement among raft nodes before linearized reading' (duration: 221.040213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:06:36.689265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.438498ms","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-04-20T12:06:36.689396Z","caller":"traceutil/trace.go:171","msg":"trace[1330501698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8105; }","duration":"223.655942ms","start":"2026-04-20T12:06:36.465712Z","end":"2026-04-20T12:06:36.689368Z","steps":["trace[1330501698] 'agreement among raft nodes before linearized reading' (duration: 221.614533ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:04.823605Z","caller":"traceutil/trace.go:171","msg":"trace[950487242] linearizableReadLoop","detail":"{readStateIndex:8602; appliedIndex:8601; }","duration":"382.616475ms","start":"2026-04-20T12:07:04.440778Z","end":"2026-04-20T12:07:04.823395Z","steps":["trace[950487242] 'read index received' (duration: 382.385471ms)","trace[950487242] 'applied index is now lower than readState.Index' (duration: 230.024µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:04.823862Z","caller":"traceutil/trace.go:171","msg":"trace[1290900726] transaction","detail":"{read_only:false; response_revision:8305; number_of_response:1; }","duration":"395.152681ms","start":"2026-04-20T12:07:04.428669Z","end":"2026-04-20T12:07:04.823822Z","steps":["trace[1290900726] 'process raft request' (duration: 394.57028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:04.823894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.090354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:07:04.824006Z","caller":"traceutil/trace.go:171","msg":"trace[688898271] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:8305; }","duration":"383.286008ms","start":"2026-04-20T12:07:04.440705Z","end":"2026-04-20T12:07:04.823991Z","steps":["trace[688898271] 'agreement among raft nodes before linearized reading' (duration: 383.073124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:04.824055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:04.440679Z","time spent":"383.366779ms","remote":"127.0.0.1:48842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T12:07:04.824061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:04.428632Z","time spent":"395.323744ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:07:19.269872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.843538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:07:19.27061Z","caller":"traceutil/trace.go:171","msg":"trace[1078393679] transaction","detail":"{read_only:false; response_revision:8428; number_of_response:1; }","duration":"310.749861ms","start":"2026-04-20T12:07:18.959806Z","end":"2026-04-20T12:07:19.270555Z","steps":["trace[1078393679] 'process raft request' (duration: 63.120739ms)","trace[1078393679] 'compare' (duration: 246.224525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:07:19.270891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:18.959734Z","time spent":"311.008797ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:07:19.270933Z","caller":"traceutil/trace.go:171","msg":"trace[1865365508] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"310.577159ms","start":"2026-04-20T12:07:18.960329Z","end":"2026-04-20T12:07:19.270906Z","steps":["trace[1865365508] 'process raft request' (duration: 310.205142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.271046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:18.960311Z","time spent":"310.68384ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:07:19.271079Z","caller":"traceutil/trace.go:171","msg":"trace[2022963823] linearizableReadLoop","detail":"{readStateIndex:8734; appliedIndex:8731; }","duration":"253.822839ms","start":"2026-04-20T12:07:19.017233Z","end":"2026-04-20T12:07:19.271056Z","steps":["trace[2022963823] 'read index received' (duration: 5.701937ms)","trace[2022963823] 'applied index is now lower than readState.Index' (duration: 248.117672ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:07:19.271271Z","caller":"traceutil/trace.go:171","msg":"trace[1267697833] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"310.65044ms","start":"2026-04-20T12:07:18.960498Z","end":"2026-04-20T12:07:19.271148Z","steps":["trace[1267697833] 'process raft request' (duration: 310.322644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.271358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.990166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T12:07:19.271382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.437689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T12:07:19.271411Z","caller":"traceutil/trace.go:171","msg":"trace[203474282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8430; }","duration":"251.090548ms","start":"2026-04-20T12:07:19.020304Z","end":"2026-04-20T12:07:19.271395Z","steps":["trace[203474282] 'agreement among raft nodes before linearized reading' (duration: 250.930405ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:07:19.271432Z","caller":"traceutil/trace.go:171","msg":"trace[975954630] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8430; }","duration":"162.50921ms","start":"2026-04-20T12:07:19.108904Z","end":"2026-04-20T12:07:19.271413Z","steps":["trace[975954630] 'agreement among raft nodes before linearized reading' (duration: 162.377348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.271433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.440701ms","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-04-20T12:07:19.271496Z","caller":"traceutil/trace.go:171","msg":"trace[1890978132] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8430; }","duration":"229.524342ms","start":"2026-04-20T12:07:19.041953Z","end":"2026-04-20T12:07:19.271477Z","steps":["trace[1890978132] 'agreement among raft nodes before linearized reading' (duration: 229.337879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.271479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:07:18.960457Z","time spent":"310.927465ms","remote":"127.0.0.1:34526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:07:19.271606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.631862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-20T12:07:19.271675Z","caller":"traceutil/trace.go:171","msg":"trace[1389928195] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8430; }","duration":"231.738674ms","start":"2026-04-20T12:07:19.039918Z","end":"2026-04-20T12:07:19.271656Z","steps":["trace[1389928195] 'agreement among raft nodes before linearized reading' (duration: 231.595441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:07:19.271723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.504932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T12:07:19.271782Z","caller":"traceutil/trace.go:171","msg":"trace[1002711533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8430; }","duration":"254.614214ms","start":"2026-04-20T12:07:19.017152Z","end":"2026-04-20T12:07:19.271766Z","steps":["trace[1002711533] 'agreement among raft nodes before linearized reading' (duration: 254.481031ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:08:07.957227Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242f.wal"} {"level":"info","ts":"2026-04-20T12:08:27.801703Z","caller":"traceutil/trace.go:171","msg":"trace[2028404330] transaction","detail":"{read_only:false; response_revision:9072; number_of_response:1; }","duration":"122.684893ms","start":"2026-04-20T12:08:27.678978Z","end":"2026-04-20T12:08:27.801662Z","steps":["trace[2028404330] 'process raft request' (duration: 94.917823ms)","trace[2028404330] 'compare' (duration: 27.23118ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:08:28.159939Z","caller":"traceutil/trace.go:171","msg":"trace[804477934] transaction","detail":"{read_only:false; response_revision:9074; number_of_response:1; }","duration":"118.380543ms","start":"2026-04-20T12:08:28.041533Z","end":"2026-04-20T12:08:28.159913Z","steps":["trace[804477934] 'process raft request' (duration: 118.111078ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:40.07016Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T12:09:40.076264Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T12:09:40.076364Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T12:11:33.451448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8089} {"level":"info","ts":"2026-04-20T12:11:33.521931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8089,"took":"67.69791ms","hash":2068344464,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":29417472,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T12:11:33.522125Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2068344464,"revision":8089,"compact-revision":5887} {"level":"info","ts":"2026-04-20T12:16:33.457704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10522} {"level":"info","ts":"2026-04-20T12:16:33.537512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10522,"took":"77.372782ms","hash":4130940880,"current-db-size-bytes":57794560,"current-db-size":"58 MB","current-db-size-in-use-bytes":20090880,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T12:16:33.537615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4130940880,"revision":10522,"compact-revision":8089}