{"level":"warn","ts":"2026-04-07T09:43:48.817801Z","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-07T09:43:48.8179Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.159: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.159:2380","--initial-cluster=instance=https://199.19.213.159:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.159:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.159: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-07T09:43:48.817971Z","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-07T09:43:48.817985Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.159:2380"]} {"level":"info","ts":"2026-04-07T09:43:48.818016Z","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-07T09:43:48.818711Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"]} {"level":"info","ts":"2026-04-07T09:43:48.818807Z","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.159:2380"],"listen-peer-urls":["https://199.19.213.159:2380"],"advertise-client-urls":["https://199.19.213.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.159: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-07T09:43:48.824211Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.11364ms"} {"level":"info","ts":"2026-04-07T09:43:48.830132Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"63cd833aa17a09c7","cluster-id":"27437233b92849be"} {"level":"info","ts":"2026-04-07T09:43:48.830227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T09:43:48.83025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became follower at term 0"} {"level":"info","ts":"2026-04-07T09:43:48.830268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 63cd833aa17a09c7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T09:43:48.830281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became follower at term 1"} {"level":"info","ts":"2026-04-07T09:43:48.830345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=(7191548467821021639)"} {"level":"warn","ts":"2026-04-07T09:43:48.83558Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T09:43:48.836957Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T09:43:48.837972Z","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-07T09:43:48.840177Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"63cd833aa17a09c7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T09:43:48.840377Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"63cd833aa17a09c7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T09:43:48.840545Z","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-07T09:43:48.840716Z","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-07T09:43:48.840735Z","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-07T09:43:48.842259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 switched to configuration voters=(7191548467821021639)"} {"level":"info","ts":"2026-04-07T09:43:48.842876Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27437233b92849be","local-member-id":"63cd833aa17a09c7","added-peer-id":"63cd833aa17a09c7","added-peer-peer-urls":["https://199.19.213.159:2380"]} {"level":"info","ts":"2026-04-07T09:43:48.845897Z","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-07T09:43:48.846088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.159:2380"} {"level":"info","ts":"2026-04-07T09:43:48.846189Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.159:2380"} {"level":"info","ts":"2026-04-07T09:43:48.846456Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"63cd833aa17a09c7","initial-advertise-peer-urls":["https://199.19.213.159:2380"],"listen-peer-urls":["https://199.19.213.159:2380"],"advertise-client-urls":["https://199.19.213.159:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.159:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T09:43:48.846561Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T09:43:49.030851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T09:43:49.030944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T09:43:49.031011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 received MsgPreVoteResp from 63cd833aa17a09c7 at term 1"} {"level":"info","ts":"2026-04-07T09:43:49.031033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became candidate at term 2"} {"level":"info","ts":"2026-04-07T09:43:49.031045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 received MsgVoteResp from 63cd833aa17a09c7 at term 2"} {"level":"info","ts":"2026-04-07T09:43:49.031062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"63cd833aa17a09c7 became leader at term 2"} {"level":"info","ts":"2026-04-07T09:43:49.031077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 63cd833aa17a09c7 elected leader 63cd833aa17a09c7 at term 2"} {"level":"info","ts":"2026-04-07T09:43:49.032418Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:43:49.032604Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"63cd833aa17a09c7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.159:2379]}","request-path":"/0/members/63cd833aa17a09c7/attributes","cluster-id":"27437233b92849be","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T09:43:49.032836Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:43:49.032939Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T09:43:49.033662Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27437233b92849be","local-member-id":"63cd833aa17a09c7","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:43:49.033827Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:43:49.033897Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T09:43:49.035105Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T09:43:49.035149Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T09:43:49.036186Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.159:2379"} {"level":"info","ts":"2026-04-07T09:43:49.037167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T09:44:11.826716Z","caller":"traceutil/trace.go:171","msg":"trace[121534591] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"136.376021ms","start":"2026-04-07T09:44:11.690308Z","end":"2026-04-07T09:44:11.826684Z","steps":["trace[121534591] 'process raft request' (duration: 136.222088ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:11.988115Z","caller":"traceutil/trace.go:171","msg":"trace[1305212980] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"296.142053ms","start":"2026-04-07T09:44:11.691927Z","end":"2026-04-07T09:44:11.988069Z","steps":["trace[1305212980] 'process raft request' (duration: 203.240485ms)","trace[1305212980] 'compare' (duration: 92.708854ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:19.060927Z","caller":"traceutil/trace.go:171","msg":"trace[8593782] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"111.643841ms","start":"2026-04-07T09:44:18.949255Z","end":"2026-04-07T09:44:19.060899Z","steps":["trace[8593782] 'process raft request' (duration: 105.619754ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:19.062331Z","caller":"traceutil/trace.go:171","msg":"trace[1015244494] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"107.635553ms","start":"2026-04-07T09:44:18.95461Z","end":"2026-04-07T09:44:19.062246Z","steps":["trace[1015244494] 'process raft request' (duration: 106.205815ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:19.062656Z","caller":"traceutil/trace.go:171","msg":"trace[1453335769] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"103.603645ms","start":"2026-04-07T09:44:18.958988Z","end":"2026-04-07T09:44:19.062592Z","steps":["trace[1453335769] 'process raft request' (duration: 102.81062ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:19.064312Z","caller":"traceutil/trace.go:171","msg":"trace[2067306236] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"100.358494ms","start":"2026-04-07T09:44:18.963922Z","end":"2026-04-07T09:44:19.06428Z","steps":["trace[2067306236] 'process raft request' (duration: 98.381365ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:19.539117Z","caller":"traceutil/trace.go:171","msg":"trace[1970115640] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"122.785487ms","start":"2026-04-07T09:44:19.416297Z","end":"2026-04-07T09:44:19.539082Z","steps":["trace[1970115640] 'process raft request' (duration: 68.010107ms)","trace[1970115640] 'compare' (duration: 54.594087ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:25.299535Z","caller":"traceutil/trace.go:171","msg":"trace[122095221] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"151.095935ms","start":"2026-04-07T09:44:25.148401Z","end":"2026-04-07T09:44:25.299497Z","steps":["trace[122095221] 'process raft request' (duration: 150.447293ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:25.579865Z","caller":"traceutil/trace.go:171","msg":"trace[1977126882] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"221.246803ms","start":"2026-04-07T09:44:25.358585Z","end":"2026-04-07T09:44:25.579832Z","steps":["trace[1977126882] 'process raft request' (duration: 221.013969ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:27.161027Z","caller":"traceutil/trace.go:171","msg":"trace[1583217224] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"108.619153ms","start":"2026-04-07T09:44:27.052373Z","end":"2026-04-07T09:44:27.160992Z","steps":["trace[1583217224] 'process raft request' (duration: 108.4497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:27.16105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.051671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:44:27.161158Z","caller":"traceutil/trace.go:171","msg":"trace[1279324195] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"107.874749ms","start":"2026-04-07T09:44:27.053257Z","end":"2026-04-07T09:44:27.161132Z","steps":["trace[1279324195] 'process raft request' (duration: 107.648344ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:27.161192Z","caller":"traceutil/trace.go:171","msg":"trace[1954398506] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"109.231945ms","start":"2026-04-07T09:44:27.051925Z","end":"2026-04-07T09:44:27.161157Z","steps":["trace[1954398506] 'agreement among raft nodes before linearized reading' (duration: 109.026551ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:27.161244Z","caller":"traceutil/trace.go:171","msg":"trace[519897106] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"109.147293ms","start":"2026-04-07T09:44:27.05208Z","end":"2026-04-07T09:44:27.161227Z","steps":["trace[519897106] 'process raft request' (duration: 108.625664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:44:40.221113Z","caller":"traceutil/trace.go:171","msg":"trace[817616272] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"109.264186ms","start":"2026-04-07T09:44:40.111818Z","end":"2026-04-07T09:44:40.221082Z","steps":["trace[817616272] 'read index received' (duration: 108.991091ms)","trace[817616272] 'applied index is now lower than readState.Index' (duration: 271.765µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:44:40.22124Z","caller":"traceutil/trace.go:171","msg":"trace[2069859264] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"137.777168ms","start":"2026-04-07T09:44:40.083427Z","end":"2026-04-07T09:44:40.221204Z","steps":["trace[2069859264] 'process raft request' (duration: 137.423491ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:44:40.221648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.806367ms","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-07T09:44:40.221711Z","caller":"traceutil/trace.go:171","msg":"trace[1584805364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"109.914208ms","start":"2026-04-07T09:44:40.111782Z","end":"2026-04-07T09:44:40.221696Z","steps":["trace[1584805364] 'agreement among raft nodes before linearized reading' (duration: 109.4552ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:28.639721Z","caller":"traceutil/trace.go:171","msg":"trace[399975711] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"102.447513ms","start":"2026-04-07T09:45:28.537246Z","end":"2026-04-07T09:45:28.639694Z","steps":["trace[399975711] 'process raft request' (duration: 102.295041ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:45:28.644687Z","caller":"traceutil/trace.go:171","msg":"trace[1374012795] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"106.603523ms","start":"2026-04-07T09:45:28.538069Z","end":"2026-04-07T09:45:28.644672Z","steps":["trace[1374012795] 'process raft request' (duration: 106.524641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:33.981357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.514461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:46:33.981506Z","caller":"traceutil/trace.go:171","msg":"trace[554031795] linearizableReadLoop","detail":"{readStateIndex:2182; appliedIndex:2181; }","duration":"219.975683ms","start":"2026-04-07T09:46:33.761508Z","end":"2026-04-07T09:46:33.981483Z","steps":["trace[554031795] 'read index received' (duration: 99.570726ms)","trace[554031795] 'applied index is now lower than readState.Index' (duration: 120.403077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:46:33.981706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.191048ms","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-07T09:46:33.981728Z","caller":"traceutil/trace.go:171","msg":"trace[262058149] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"222.003442ms","start":"2026-04-07T09:46:33.759562Z","end":"2026-04-07T09:46:33.981566Z","steps":["trace[262058149] 'process raft request' (duration: 101.485272ms)","trace[262058149] 'compare' (duration: 119.385368ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:46:33.981765Z","caller":"traceutil/trace.go:171","msg":"trace[2039825239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2113; }","duration":"220.296599ms","start":"2026-04-07T09:46:33.761454Z","end":"2026-04-07T09:46:33.981751Z","steps":["trace[2039825239] 'agreement among raft nodes before linearized reading' (duration: 220.122806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:46:34.038707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.995463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T09:46:34.038757Z","caller":"traceutil/trace.go:171","msg":"trace[2119886780] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2114; }","duration":"149.105335ms","start":"2026-04-07T09:46:33.88964Z","end":"2026-04-07T09:46:34.038745Z","steps":["trace[2119886780] 'agreement among raft nodes before linearized reading' (duration: 148.944342ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:47:13.169945Z","caller":"traceutil/trace.go:171","msg":"trace[1882001967] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"162.619128ms","start":"2026-04-07T09:47:13.007301Z","end":"2026-04-07T09:47:13.16992Z","steps":["trace[1882001967] 'process raft request' (duration: 159.683983ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:47:46.003388Z","caller":"traceutil/trace.go:171","msg":"trace[971971772] transaction","detail":"{read_only:false; response_revision:2662; number_of_response:1; }","duration":"128.276841ms","start":"2026-04-07T09:47:45.875075Z","end":"2026-04-07T09:47:46.003351Z","steps":["trace[971971772] 'process raft request' (duration: 110.53327ms)","trace[971971772] 'compare' (duration: 17.588988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:47:46.266619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.653402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:47:46.266765Z","caller":"traceutil/trace.go:171","msg":"trace[1849443715] transaction","detail":"{read_only:false; response_revision:2664; number_of_response:1; }","duration":"268.139051ms","start":"2026-04-07T09:47:45.998604Z","end":"2026-04-07T09:47:46.266743Z","steps":["trace[1849443715] 'process raft request' (duration: 165.230593ms)","trace[1849443715] 'compare' (duration: 102.462139ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:50:07.886853Z","caller":"traceutil/trace.go:171","msg":"trace[2144953178] transaction","detail":"{read_only:false; response_revision:4467; number_of_response:1; }","duration":"117.065415ms","start":"2026-04-07T09:50:07.769764Z","end":"2026-04-07T09:50:07.886829Z","steps":["trace[2144953178] 'process raft request' (duration: 116.910632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:21.012094Z","caller":"traceutil/trace.go:171","msg":"trace[119269241] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"188.1858ms","start":"2026-04-07T09:50:20.823879Z","end":"2026-04-07T09:50:21.012065Z","steps":["trace[119269241] 'process raft request' (duration: 187.944945ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:21.028364Z","caller":"traceutil/trace.go:171","msg":"trace[1232313315] linearizableReadLoop","detail":"{readStateIndex:4912; appliedIndex:4910; }","duration":"133.145547ms","start":"2026-04-07T09:50:20.895193Z","end":"2026-04-07T09:50:21.028338Z","steps":["trace[1232313315] 'read index received' (duration: 116.76104ms)","trace[1232313315] 'applied index is now lower than readState.Index' (duration: 16.383277ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:50:21.02869Z","caller":"traceutil/trace.go:171","msg":"trace[1001091645] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"143.134454ms","start":"2026-04-07T09:50:20.88554Z","end":"2026-04-07T09:50:21.028675Z","steps":["trace[1001091645] 'process raft request' (duration: 142.658436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:50:21.02902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.79989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-07T09:50:21.029124Z","caller":"traceutil/trace.go:171","msg":"trace[412865056] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4717; }","duration":"133.966333ms","start":"2026-04-07T09:50:20.895135Z","end":"2026-04-07T09:50:21.029102Z","steps":["trace[412865056] 'agreement among raft nodes before linearized reading' (duration: 133.621156ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:21.226631Z","caller":"traceutil/trace.go:171","msg":"trace[756351604] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"146.969806ms","start":"2026-04-07T09:50:21.079624Z","end":"2026-04-07T09:50:21.226593Z","steps":["trace[756351604] 'process raft request' (duration: 84.209599ms)","trace[756351604] 'compare' (duration: 62.577344ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:50:21.883194Z","caller":"traceutil/trace.go:171","msg":"trace[1683782758] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"162.932937ms","start":"2026-04-07T09:50:21.720232Z","end":"2026-04-07T09:50:21.883165Z","steps":["trace[1683782758] 'process raft request' (duration: 162.721642ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:22.050408Z","caller":"traceutil/trace.go:171","msg":"trace[1786927053] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"160.379669ms","start":"2026-04-07T09:50:21.889998Z","end":"2026-04-07T09:50:22.050377Z","steps":["trace[1786927053] 'process raft request' (duration: 160.100073ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:22.05041Z","caller":"traceutil/trace.go:171","msg":"trace[1665780565] linearizableReadLoop","detail":"{readStateIndex:4929; appliedIndex:4928; }","duration":"159.825188ms","start":"2026-04-07T09:50:21.890524Z","end":"2026-04-07T09:50:22.050349Z","steps":["trace[1665780565] 'read index received' (duration: 159.572583ms)","trace[1665780565] 'applied index is now lower than readState.Index' (duration: 250.845µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:50:22.052953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.182162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-44698e48-ddfe-4254-97cd-2133be4d4cae\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-07T09:50:22.053114Z","caller":"traceutil/trace.go:171","msg":"trace[1229267151] range","detail":"{range_begin:/registry/persistentvolumes/pvc-44698e48-ddfe-4254-97cd-2133be4d4cae; range_end:; response_count:1; response_revision:4734; }","duration":"162.536219ms","start":"2026-04-07T09:50:21.89051Z","end":"2026-04-07T09:50:22.053046Z","steps":["trace[1229267151] 'agreement among raft nodes before linearized reading' (duration: 159.95082ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:22.061831Z","caller":"traceutil/trace.go:171","msg":"trace[461265647] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"162.60968ms","start":"2026-04-07T09:50:21.899182Z","end":"2026-04-07T09:50:22.061792Z","steps":["trace[461265647] 'process raft request' (duration: 161.609111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:50:22.062067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.298593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-07T09:50:22.06228Z","caller":"traceutil/trace.go:171","msg":"trace[287844485] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4735; }","duration":"171.512936ms","start":"2026-04-07T09:50:21.890663Z","end":"2026-04-07T09:50:22.062176Z","steps":["trace[287844485] 'agreement among raft nodes before linearized reading' (duration: 171.14016ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:28.098491Z","caller":"traceutil/trace.go:171","msg":"trace[1202310689] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"101.997503ms","start":"2026-04-07T09:50:27.996461Z","end":"2026-04-07T09:50:28.098458Z","steps":["trace[1202310689] 'process raft request' (duration: 84.415003ms)","trace[1202310689] 'compare' (duration: 17.139372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:50:28.323356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.823271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:50:28.323667Z","caller":"traceutil/trace.go:171","msg":"trace[2134200201] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5074; }","duration":"133.994513ms","start":"2026-04-07T09:50:28.189652Z","end":"2026-04-07T09:50:28.323646Z","steps":["trace[2134200201] 'read index received' (duration: 15.748665ms)","trace[2134200201] 'applied index is now lower than readState.Index' (duration: 118.244598ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:50:28.32379Z","caller":"traceutil/trace.go:171","msg":"trace[626980635] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"172.350043ms","start":"2026-04-07T09:50:28.151429Z","end":"2026-04-07T09:50:28.323779Z","steps":["trace[626980635] 'process raft request' (duration: 172.097767ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:28.323851Z","caller":"traceutil/trace.go:171","msg":"trace[1439090362] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"172.7218ms","start":"2026-04-07T09:50:28.151085Z","end":"2026-04-07T09:50:28.323806Z","steps":["trace[1439090362] 'process raft request' (duration: 54.303239ms)","trace[1439090362] 'compare' (duration: 117.551845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:50:28.324056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.943097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f06296c3-d892-4f39-987c-008fe03c1d5a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T09:50:28.324121Z","caller":"traceutil/trace.go:171","msg":"trace[749274945] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f06296c3-d892-4f39-987c-008fe03c1d5a; range_end:; response_count:1; response_revision:4878; }","duration":"131.080689ms","start":"2026-04-07T09:50:28.193026Z","end":"2026-04-07T09:50:28.324107Z","steps":["trace[749274945] 'agreement among raft nodes before linearized reading' (duration: 130.854175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:50:28.324518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.839719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a40a40f351de31\" ","response":"range_response_count:1 size:1012"} {"level":"info","ts":"2026-04-07T09:50:28.324622Z","caller":"traceutil/trace.go:171","msg":"trace[2142348021] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a40a40f351de31; range_end:; response_count:1; response_revision:4878; }","duration":"134.954971ms","start":"2026-04-07T09:50:28.189644Z","end":"2026-04-07T09:50:28.324599Z","steps":["trace[2142348021] 'agreement among raft nodes before linearized reading' (duration: 134.758667ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:28.566087Z","caller":"traceutil/trace.go:171","msg":"trace[585960805] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5076; }","duration":"237.730339ms","start":"2026-04-07T09:50:28.328319Z","end":"2026-04-07T09:50:28.566049Z","steps":["trace[585960805] 'read index received' (duration: 236.968955ms)","trace[585960805] 'applied index is now lower than readState.Index' (duration: 758.434µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:50:28.566203Z","caller":"traceutil/trace.go:171","msg":"trace[408571767] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"238.859581ms","start":"2026-04-07T09:50:28.327263Z","end":"2026-04-07T09:50:28.566122Z","steps":["trace[408571767] 'process raft request' (duration: 237.953033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:50:28.566456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.111296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-07T09:50:28.566557Z","caller":"traceutil/trace.go:171","msg":"trace[389365220] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4879; }","duration":"238.251319ms","start":"2026-04-07T09:50:28.328281Z","end":"2026-04-07T09:50:28.566532Z","steps":["trace[389365220] 'agreement among raft nodes before linearized reading' (duration: 237.871002ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:28.56758Z","caller":"traceutil/trace.go:171","msg":"trace[1856277313] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"237.922113ms","start":"2026-04-07T09:50:28.329646Z","end":"2026-04-07T09:50:28.567569Z","steps":["trace[1856277313] 'process raft request' (duration: 237.834801ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:28.568199Z","caller":"traceutil/trace.go:171","msg":"trace[1082133121] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"238.448222ms","start":"2026-04-07T09:50:28.329742Z","end":"2026-04-07T09:50:28.56819Z","steps":["trace[1082133121] 'process raft request' (duration: 237.8002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:50:28.568258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.927493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:59703"} {"level":"info","ts":"2026-04-07T09:50:28.568328Z","caller":"traceutil/trace.go:171","msg":"trace[19978483] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4882; }","duration":"238.002364ms","start":"2026-04-07T09:50:28.33031Z","end":"2026-04-07T09:50:28.568313Z","steps":["trace[19978483] 'agreement among raft nodes before linearized reading' (duration: 237.75252ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:28.568369Z","caller":"traceutil/trace.go:171","msg":"trace[834342147] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"238.512773ms","start":"2026-04-07T09:50:28.329801Z","end":"2026-04-07T09:50:28.568314Z","steps":["trace[834342147] 'process raft request' (duration: 238.151707ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:50:40.16666Z","caller":"traceutil/trace.go:171","msg":"trace[546258468] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"116.001816ms","start":"2026-04-07T09:50:40.050626Z","end":"2026-04-07T09:50:40.166627Z","steps":["trace[546258468] 'process raft request' (duration: 115.815373ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:51:18.266181Z","caller":"traceutil/trace.go:171","msg":"trace[604349357] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"118.499972ms","start":"2026-04-07T09:51:18.147648Z","end":"2026-04-07T09:51:18.266148Z","steps":["trace[604349357] 'process raft request' (duration: 88.733654ms)","trace[604349357] 'compare' (duration: 29.477782ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:53:49.228666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3262} {"level":"info","ts":"2026-04-07T09:53:49.324064Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3262,"took":"93.076817ms","hash":3865782375,"current-db-size-bytes":60465152,"current-db-size":"60 MB","current-db-size-in-use-bytes":33976320,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T09:53:49.324156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3865782375,"revision":3262,"compact-revision":-1} {"level":"info","ts":"2026-04-07T09:56:20.517173Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002116.wal"} {"level":"info","ts":"2026-04-07T09:56:23.34648Z","caller":"traceutil/trace.go:171","msg":"trace[168405002] transaction","detail":"{read_only:false; response_revision:8136; number_of_response:1; }","duration":"154.992151ms","start":"2026-04-07T09:56:23.19144Z","end":"2026-04-07T09:56:23.346432Z","steps":["trace[168405002] 'process raft request' (duration: 154.725926ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:23.492997Z","caller":"traceutil/trace.go:171","msg":"trace[1993582102] transaction","detail":"{read_only:false; response_revision:8137; number_of_response:1; }","duration":"142.319521ms","start":"2026-04-07T09:56:23.350632Z","end":"2026-04-07T09:56:23.492952Z","steps":["trace[1993582102] 'process raft request' (duration: 134.649706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:24.834494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.804315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T09:56:24.834733Z","caller":"traceutil/trace.go:171","msg":"trace[395894293] linearizableReadLoop","detail":"{readStateIndex:8500; appliedIndex:8498; }","duration":"388.923307ms","start":"2026-04-07T09:56:24.445782Z","end":"2026-04-07T09:56:24.834705Z","steps":["trace[395894293] 'read index received' (duration: 248.898079ms)","trace[395894293] 'applied index is now lower than readState.Index' (duration: 140.024128ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:56:24.834765Z","caller":"traceutil/trace.go:171","msg":"trace[984192275] transaction","detail":"{read_only:false; response_revision:8145; number_of_response:1; }","duration":"396.600191ms","start":"2026-04-07T09:56:24.438117Z","end":"2026-04-07T09:56:24.834717Z","steps":["trace[984192275] 'process raft request' (duration: 256.442311ms)","trace[984192275] 'compare' (duration: 139.421857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:56:24.834933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.983612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:56:24.83498Z","caller":"traceutil/trace.go:171","msg":"trace[686463677] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8146; }","duration":"291.068804ms","start":"2026-04-07T09:56:24.543898Z","end":"2026-04-07T09:56:24.834966Z","steps":["trace[686463677] 'agreement among raft nodes before linearized reading' (duration: 290.938091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:24.835037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.416939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T09:56:24.835098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.989885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tl9d6\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T09:56:24.835163Z","caller":"traceutil/trace.go:171","msg":"trace[1371278312] transaction","detail":"{read_only:false; response_revision:8146; number_of_response:1; }","duration":"393.682976ms","start":"2026-04-07T09:56:24.441466Z","end":"2026-04-07T09:56:24.835149Z","steps":["trace[1371278312] 'process raft request' (duration: 393.172327ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:24.835202Z","caller":"traceutil/trace.go:171","msg":"trace[502053144] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tl9d6; range_end:; response_count:1; response_revision:8146; }","duration":"218.151249ms","start":"2026-04-07T09:56:24.617029Z","end":"2026-04-07T09:56:24.83518Z","steps":["trace[502053144] 'agreement among raft nodes before linearized reading' (duration: 217.841183ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:24.83519Z","caller":"traceutil/trace.go:171","msg":"trace[1101823165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8146; }","duration":"154.687554ms","start":"2026-04-07T09:56:24.680457Z","end":"2026-04-07T09:56:24.835145Z","steps":["trace[1101823165] 'agreement among raft nodes before linearized reading' (duration: 154.391709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:24.83524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:56:24.441434Z","time spent":"393.769388ms","remote":"127.0.0.1:33706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:56:24.835368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.583293ms","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-07T09:56:24.835408Z","caller":"traceutil/trace.go:171","msg":"trace[226743732] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8146; }","duration":"240.680974ms","start":"2026-04-07T09:56:24.594715Z","end":"2026-04-07T09:56:24.835396Z","steps":["trace[226743732] 'agreement among raft nodes before linearized reading' (duration: 240.50995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:24.835024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:56:24.438102Z","time spent":"396.780744ms","remote":"127.0.0.1:33706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T09:56:24.835206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.424546ms","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-07T09:56:24.835533Z","caller":"traceutil/trace.go:171","msg":"trace[1476586796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8146; }","duration":"389.747003ms","start":"2026-04-07T09:56:24.445767Z","end":"2026-04-07T09:56:24.835514Z","steps":["trace[1476586796] 'agreement among raft nodes before linearized reading' (duration: 389.065709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:24.836347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T09:56:24.445729Z","time spent":"389.848365ms","remote":"127.0.0.1:33706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T09:56:35.661501Z","caller":"traceutil/trace.go:171","msg":"trace[1107847064] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"100.690017ms","start":"2026-04-07T09:56:35.560781Z","end":"2026-04-07T09:56:35.661471Z","steps":["trace[1107847064] 'process raft request' (duration: 100.467062ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:36.691356Z","caller":"traceutil/trace.go:171","msg":"trace[1126964645] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"113.144151ms","start":"2026-04-07T09:56:36.578162Z","end":"2026-04-07T09:56:36.691306Z","steps":["trace[1126964645] 'process raft request' (duration: 112.865276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:37.043361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.467601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T09:56:37.043474Z","caller":"traceutil/trace.go:171","msg":"trace[635544063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8225; }","duration":"153.648124ms","start":"2026-04-07T09:56:36.889801Z","end":"2026-04-07T09:56:37.043449Z","steps":["trace[635544063] 'range keys from in-memory index tree' (duration: 153.258347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:37.043693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.481751ms","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-07T09:56:37.043853Z","caller":"traceutil/trace.go:171","msg":"trace[1849093824] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8225; }","duration":"144.699635ms","start":"2026-04-07T09:56:36.89913Z","end":"2026-04-07T09:56:37.043829Z","steps":["trace[1849093824] 'range keys from in-memory index tree' (duration: 144.368789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:37.044185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.024147ms","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-07T09:56:37.044247Z","caller":"traceutil/trace.go:171","msg":"trace[1650636198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8225; }","duration":"140.174371ms","start":"2026-04-07T09:56:36.904059Z","end":"2026-04-07T09:56:37.044233Z","steps":["trace[1650636198] 'range keys from in-memory index tree' (duration: 139.854555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:56:37.044474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.059834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tl9d6\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T09:56:37.04456Z","caller":"traceutil/trace.go:171","msg":"trace[649509255] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tl9d6; range_end:; response_count:1; response_revision:8225; }","duration":"136.122395ms","start":"2026-04-07T09:56:36.908381Z","end":"2026-04-07T09:56:37.044503Z","steps":["trace[649509255] 'range keys from in-memory index tree' (duration: 135.90768ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:37.260376Z","caller":"traceutil/trace.go:171","msg":"trace[537656689] transaction","detail":"{read_only:false; response_revision:8226; number_of_response:1; }","duration":"210.880443ms","start":"2026-04-07T09:56:37.049463Z","end":"2026-04-07T09:56:37.260343Z","steps":["trace[537656689] 'process raft request' (duration: 210.633089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:37.264893Z","caller":"traceutil/trace.go:171","msg":"trace[748374039] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"214.815547ms","start":"2026-04-07T09:56:37.050024Z","end":"2026-04-07T09:56:37.264839Z","steps":["trace[748374039] 'process raft request' (duration: 214.665834ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:37.265182Z","caller":"traceutil/trace.go:171","msg":"trace[1921726210] transaction","detail":"{read_only:false; response_revision:8228; number_of_response:1; }","duration":"215.111872ms","start":"2026-04-07T09:56:37.050052Z","end":"2026-04-07T09:56:37.265164Z","steps":["trace[1921726210] 'process raft request' (duration: 214.740875ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:37.265362Z","caller":"traceutil/trace.go:171","msg":"trace[1286989815] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"211.556445ms","start":"2026-04-07T09:56:37.053789Z","end":"2026-04-07T09:56:37.265345Z","steps":["trace[1286989815] 'process raft request' (duration: 211.29055ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:37.265325Z","caller":"traceutil/trace.go:171","msg":"trace[745540847] linearizableReadLoop","detail":"{readStateIndex:8588; appliedIndex:8584; }","duration":"203.202578ms","start":"2026-04-07T09:56:37.062088Z","end":"2026-04-07T09:56:37.26529Z","steps":["trace[745540847] 'read index received' (duration: 198.035251ms)","trace[745540847] 'applied index is now lower than readState.Index' (duration: 5.165397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:56:37.265809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.694568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:203503"} {"level":"info","ts":"2026-04-07T09:56:37.265861Z","caller":"traceutil/trace.go:171","msg":"trace[693517312] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:8229; }","duration":"203.79929ms","start":"2026-04-07T09:56:37.06205Z","end":"2026-04-07T09:56:37.265849Z","steps":["trace[693517312] 'agreement among raft nodes before linearized reading' (duration: 203.358771ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:56:37.984758Z","caller":"traceutil/trace.go:171","msg":"trace[1001057227] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"114.749171ms","start":"2026-04-07T09:56:37.869978Z","end":"2026-04-07T09:56:37.984727Z","steps":["trace[1001057227] 'process raft request' (duration: 114.361454ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:05.787899Z","caller":"traceutil/trace.go:171","msg":"trace[941109057] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8843; }","duration":"147.56051ms","start":"2026-04-07T09:57:05.640307Z","end":"2026-04-07T09:57:05.787867Z","steps":["trace[941109057] 'read index received' (duration: 147.233503ms)","trace[941109057] 'applied index is now lower than readState.Index' (duration: 325.717µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:57:05.787979Z","caller":"traceutil/trace.go:171","msg":"trace[1427047150] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"160.597636ms","start":"2026-04-07T09:57:05.627341Z","end":"2026-04-07T09:57:05.787939Z","steps":["trace[1427047150] 'process raft request' (duration: 160.294139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:05.788223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.884637ms","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":"warn","ts":"2026-04-07T09:57:05.788217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.587236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:57:05.788279Z","caller":"traceutil/trace.go:171","msg":"trace[1753062545] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8472; }","duration":"148.005738ms","start":"2026-04-07T09:57:05.640257Z","end":"2026-04-07T09:57:05.788263Z","steps":["trace[1753062545] 'agreement among raft nodes before linearized reading' (duration: 147.791965ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:05.788329Z","caller":"traceutil/trace.go:171","msg":"trace[807720317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8472; }","duration":"108.722308ms","start":"2026-04-07T09:57:05.679578Z","end":"2026-04-07T09:57:05.7883Z","steps":["trace[807720317] 'agreement among raft nodes before linearized reading' (duration: 108.511384ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:05.949803Z","caller":"traceutil/trace.go:171","msg":"trace[776854883] linearizableReadLoop","detail":"{readStateIndex:8845; appliedIndex:8844; }","duration":"119.110873ms","start":"2026-04-07T09:57:05.830651Z","end":"2026-04-07T09:57:05.949762Z","steps":["trace[776854883] 'read index received' (duration: 110.901118ms)","trace[776854883] 'applied index is now lower than readState.Index' (duration: 8.208035ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:57:05.949919Z","caller":"traceutil/trace.go:171","msg":"trace[1739127593] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"151.759318ms","start":"2026-04-07T09:57:05.798061Z","end":"2026-04-07T09:57:05.949821Z","steps":["trace[1739127593] 'process raft request' (duration: 143.494693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:05.950173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.490051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T09:57:05.95029Z","caller":"traceutil/trace.go:171","msg":"trace[1794943051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8473; }","duration":"119.678005ms","start":"2026-04-07T09:57:05.830586Z","end":"2026-04-07T09:57:05.950264Z","steps":["trace[1794943051] 'agreement among raft nodes before linearized reading' (duration: 119.348578ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:18.428058Z","caller":"traceutil/trace.go:171","msg":"trace[1159156012] linearizableReadLoop","detail":"{readStateIndex:8974; appliedIndex:8973; }","duration":"133.30665ms","start":"2026-04-07T09:57:18.294705Z","end":"2026-04-07T09:57:18.428011Z","steps":["trace[1159156012] 'read index received' (duration: 59.495311ms)","trace[1159156012] 'applied index is now lower than readState.Index' (duration: 73.809739ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T09:57:18.428362Z","caller":"traceutil/trace.go:171","msg":"trace[552712070] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8595; }","duration":"154.151545ms","start":"2026-04-07T09:57:18.274157Z","end":"2026-04-07T09:57:18.428308Z","steps":["trace[552712070] 'process raft request' (duration: 80.054629ms)","trace[552712070] 'compare' (duration: 73.619236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T09:57:18.428757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.010255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-frs6k\" ","response":"range_response_count:1 size:2806"} {"level":"warn","ts":"2026-04-07T09:57:18.429066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.78495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-frs6k\" ","response":"range_response_count:1 size:4630"} {"level":"info","ts":"2026-04-07T09:57:18.429523Z","caller":"traceutil/trace.go:171","msg":"trace[1836751951] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-frs6k; range_end:; response_count:1; response_revision:8595; }","duration":"134.33092ms","start":"2026-04-07T09:57:18.295173Z","end":"2026-04-07T09:57:18.429504Z","steps":["trace[1836751951] 'agreement among raft nodes before linearized reading' (duration: 133.617127ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:18.429534Z","caller":"traceutil/trace.go:171","msg":"trace[878804979] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-frs6k; range_end:; response_count:1; response_revision:8595; }","duration":"134.87296ms","start":"2026-04-07T09:57:18.294618Z","end":"2026-04-07T09:57:18.429491Z","steps":["trace[878804979] 'agreement among raft nodes before linearized reading' (duration: 133.811431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:18.706285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.303753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-07T09:57:18.706591Z","caller":"traceutil/trace.go:171","msg":"trace[1691670512] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8599; }","duration":"157.70731ms","start":"2026-04-07T09:57:18.548857Z","end":"2026-04-07T09:57:18.706564Z","steps":["trace[1691670512] 'range keys from in-memory index tree' (duration: 156.818295ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:19.402285Z","caller":"traceutil/trace.go:171","msg":"trace[1582457571] transaction","detail":"{read_only:false; response_revision:8604; number_of_response:1; }","duration":"126.93544ms","start":"2026-04-07T09:57:19.275317Z","end":"2026-04-07T09:57:19.402253Z","steps":["trace[1582457571] 'process raft request' (duration: 126.743237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:20.23561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.706879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T09:57:20.235831Z","caller":"traceutil/trace.go:171","msg":"trace[1582704389] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8609; }","duration":"202.006987ms","start":"2026-04-07T09:57:20.03379Z","end":"2026-04-07T09:57:20.235797Z","steps":["trace[1582704389] 'count revisions from in-memory index tree' (duration: 201.571537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:20.235687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.201055ms","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-07T09:57:20.23605Z","caller":"traceutil/trace.go:171","msg":"trace[382071020] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8609; }","duration":"136.546072ms","start":"2026-04-07T09:57:20.09939Z","end":"2026-04-07T09:57:20.235936Z","steps":["trace[382071020] 'range keys from in-memory index tree' (duration: 135.88405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T09:57:20.235681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.432228ms","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-07T09:57:20.236307Z","caller":"traceutil/trace.go:171","msg":"trace[428280868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8609; }","duration":"182.08354ms","start":"2026-04-07T09:57:20.054169Z","end":"2026-04-07T09:57:20.236253Z","steps":["trace[428280868] 'range keys from in-memory index tree' (duration: 181.206174ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:57:20.856761Z","caller":"traceutil/trace.go:171","msg":"trace[1983824688] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"111.99927ms","start":"2026-04-07T09:57:20.744712Z","end":"2026-04-07T09:57:20.856711Z","steps":["trace[1983824688] 'process raft request' (duration: 102.780876ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T09:58:49.236609Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6644} {"level":"info","ts":"2026-04-07T09:58:49.34096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6644,"took":"101.933779ms","hash":4266874671,"current-db-size-bytes":60465152,"current-db-size":"60 MB","current-db-size-in-use-bytes":27152384,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-07T09:58:49.341044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4266874671,"revision":6644,"compact-revision":3262} {"level":"info","ts":"2026-04-07T09:58:55.44096Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"63cd833aa17a09c7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T09:58:55.452022Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T09:58:55.452136Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T10:03:49.243422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9533} {"level":"info","ts":"2026-04-07T10:03:49.331063Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9533,"took":"84.432592ms","hash":404117665,"current-db-size-bytes":60465152,"current-db-size":"60 MB","current-db-size-in-use-bytes":25313280,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T10:03:49.33115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":404117665,"revision":9533,"compact-revision":6644}