{"level":"warn","ts":"2026-02-24T19:58:12.829487Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T19:58:12.829589Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-02-24T19:58:12.829672Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T19:58:12.829688Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-24T19:58:12.829711Z","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-02-24T19:58:12.830535Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-02-24T19:58:12.830627Z","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://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-02-24T19:58:12.834557Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.665939ms"} {"level":"info","ts":"2026-02-24T19:58:12.848243Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-02-24T19:58:12.848405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T19:58:12.848453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-02-24T19:58:12.848527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T19:58:12.848546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-02-24T19:58:12.848661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-02-24T19:58:12.854416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T19:58:12.856068Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T19:58:12.857401Z","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-02-24T19:58:12.860414Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T19:58:12.86062Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T19:58:12.860714Z","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-02-24T19:58:12.860906Z","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-02-24T19:58:12.860924Z","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-02-24T19:58:12.863291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-02-24T19:58:12.863772Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-24T19:58:12.868507Z","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-02-24T19:58:12.86864Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-24T19:58:12.868679Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-24T19:58:12.868961Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T19:58:12.869006Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T19:58:13.549457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T19:58:13.549512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T19:58:13.549536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-02-24T19:58:13.549556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-02-24T19:58:13.549567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-24T19:58:13.549594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-02-24T19:58:13.549607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-24T19:58:13.550458Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T19:58:13.550461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T19:58:13.55052Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T19:58:13.55055Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:13.550925Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T19:58:13.550954Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T19:58:13.551531Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:13.551684Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:13.551716Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T19:58:13.55368Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-02-24T19:58:13.554506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T19:58:48.761977Z","caller":"traceutil/trace.go:171","msg":"trace[1113689334] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"293.363896ms","start":"2026-02-24T19:58:48.468592Z","end":"2026-02-24T19:58:48.761956Z","steps":["trace[1113689334] 'read index received' (duration: 293.077939ms)","trace[1113689334] 'applied index is now lower than readState.Index' (duration: 284.917µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:48.762243Z","caller":"traceutil/trace.go:171","msg":"trace[1097757145] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"351.395016ms","start":"2026-02-24T19:58:48.410805Z","end":"2026-02-24T19:58:48.7622Z","steps":["trace[1097757145] 'process raft request' (duration: 350.988135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:48.762407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.760747ms","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-02-24T19:58:48.762539Z","caller":"traceutil/trace.go:171","msg":"trace[1182443475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:546; }","duration":"293.970022ms","start":"2026-02-24T19:58:48.468543Z","end":"2026-02-24T19:58:48.762513Z","steps":["trace[1182443475] 'agreement among raft nodes before linearized reading' (duration: 293.613673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:48.763158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:48.410771Z","time spent":"351.56054ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:58:49.282715Z","caller":"traceutil/trace.go:171","msg":"trace[291339273] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"291.027514ms","start":"2026-02-24T19:58:48.991649Z","end":"2026-02-24T19:58:49.282676Z","steps":["trace[291339273] 'process raft request' (duration: 290.758137ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T19:58:49.617208Z","caller":"traceutil/trace.go:171","msg":"trace[922007652] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:562; }","duration":"583.548409ms","start":"2026-02-24T19:58:49.033629Z","end":"2026-02-24T19:58:49.617177Z","steps":["trace[922007652] 'read index received' (duration: 248.833002ms)","trace[922007652] 'applied index is now lower than readState.Index' (duration: 334.714346ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T19:58:49.617316Z","caller":"traceutil/trace.go:171","msg":"trace[171719132] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"615.962066ms","start":"2026-02-24T19:58:49.001297Z","end":"2026-02-24T19:58:49.61726Z","steps":["trace[171719132] 'process raft request' (duration: 615.725419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:49.617461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.605295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T19:58:49.617522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:49.00127Z","time spent":"616.14371ms","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4351,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T19:58:49.61754Z","caller":"traceutil/trace.go:171","msg":"trace[1175235848] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:549; }","duration":"578.734629ms","start":"2026-02-24T19:58:49.03878Z","end":"2026-02-24T19:58:49.617515Z","steps":["trace[1175235848] 'agreement among raft nodes before linearized reading' (duration: 578.559734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:49.617552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.923109ms","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-02-24T19:58:49.617621Z","caller":"traceutil/trace.go:171","msg":"trace[1470309672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"584.027362ms","start":"2026-02-24T19:58:49.033575Z","end":"2026-02-24T19:58:49.617602Z","steps":["trace[1470309672] 'agreement among raft nodes before linearized reading' (duration: 583.745034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:58:49.617619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:49.038751Z","time spent":"578.853391ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-24T19:58:49.617745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T19:58:49.033552Z","time spent":"584.101833ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T19:58:49.617766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.845201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T19:58:49.618046Z","caller":"traceutil/trace.go:171","msg":"trace[157050187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"172.114588ms","start":"2026-02-24T19:58:49.445856Z","end":"2026-02-24T19:58:49.61797Z","steps":["trace[157050187] 'agreement among raft nodes before linearized reading' (duration: 171.595584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:09.904309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.843188ms","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-02-24T19:59:09.904419Z","caller":"traceutil/trace.go:171","msg":"trace[1594557623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:935; }","duration":"152.019903ms","start":"2026-02-24T19:59:09.752376Z","end":"2026-02-24T19:59:09.904396Z","steps":["trace[1594557623] 'range keys from in-memory index tree' (duration: 151.637882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:09.904383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.837588ms","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-02-24T19:59:09.904499Z","caller":"traceutil/trace.go:171","msg":"trace[2012689291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:935; }","duration":"152.016163ms","start":"2026-02-24T19:59:09.752455Z","end":"2026-02-24T19:59:09.904471Z","steps":["trace[2012689291] 'range keys from in-memory index tree' (duration: 151.55945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T19:59:09.904708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.797761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T19:59:09.904838Z","caller":"traceutil/trace.go:171","msg":"trace[1931259351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"160.991536ms","start":"2026-02-24T19:59:09.743818Z","end":"2026-02-24T19:59:09.90481Z","steps":["trace[1931259351] 'range keys from in-memory index tree' (duration: 160.563744ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:00:02.087941Z","caller":"traceutil/trace.go:171","msg":"trace[2030257997] linearizableReadLoop","detail":"{readStateIndex:1654; appliedIndex:1653; }","duration":"158.887075ms","start":"2026-02-24T20:00:01.929019Z","end":"2026-02-24T20:00:02.087906Z","steps":["trace[2030257997] 'read index received' (duration: 158.621928ms)","trace[2030257997] 'applied index is now lower than readState.Index' (duration: 263.207µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:00:02.088014Z","caller":"traceutil/trace.go:171","msg":"trace[1542612694] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"285.597791ms","start":"2026-02-24T20:00:01.802374Z","end":"2026-02-24T20:00:02.087972Z","steps":["trace[1542612694] 'process raft request' (duration: 285.319144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:00:02.08826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.200624ms","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-02-24T20:00:02.088383Z","caller":"traceutil/trace.go:171","msg":"trace[175249326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1596; }","duration":"159.389729ms","start":"2026-02-24T20:00:01.928965Z","end":"2026-02-24T20:00:02.088355Z","steps":["trace[175249326] 'agreement among raft nodes before linearized reading' (duration: 159.080691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:00:02.530473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.691636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T20:00:02.530707Z","caller":"traceutil/trace.go:171","msg":"trace[635959086] linearizableReadLoop","detail":"{readStateIndex:1658; appliedIndex:1656; }","duration":"331.42408ms","start":"2026-02-24T20:00:02.199266Z","end":"2026-02-24T20:00:02.53069Z","steps":["trace[635959086] 'read index received' (duration: 93.2272ms)","trace[635959086] 'applied index is now lower than readState.Index' (duration: 238.195779ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:00:02.530698Z","caller":"traceutil/trace.go:171","msg":"trace[558077913] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"354.892363ms","start":"2026-02-24T20:00:02.175755Z","end":"2026-02-24T20:00:02.530648Z","steps":["trace[558077913] 'process raft request' (duration: 116.689234ms)","trace[558077913] 'compare' (duration: 237.297425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:00:02.530831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.572983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T20:00:02.530856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:00:02.175743Z","time spent":"355.026247ms","remote":"127.0.0.1:37264","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-02-24T20:00:02.530865Z","caller":"traceutil/trace.go:171","msg":"trace[2020573193] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1600; }","duration":"331.651356ms","start":"2026-02-24T20:00:02.199204Z","end":"2026-02-24T20:00:02.530855Z","steps":["trace[2020573193] 'agreement among raft nodes before linearized reading' (duration: 331.588034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:00:02.530925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:00:02.199182Z","time spent":"331.732798ms","remote":"127.0.0.1:37240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:00:02.531123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.495806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5324"} {"level":"info","ts":"2026-02-24T20:00:02.531248Z","caller":"traceutil/trace.go:171","msg":"trace[1748830653] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1600; }","duration":"299.681022ms","start":"2026-02-24T20:00:02.231534Z","end":"2026-02-24T20:00:02.531215Z","steps":["trace[1748830653] 'agreement among raft nodes before linearized reading' (duration: 299.390464ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:00:02.531486Z","caller":"traceutil/trace.go:171","msg":"trace[1779819534] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"354.943835ms","start":"2026-02-24T20:00:02.17584Z","end":"2026-02-24T20:00:02.530784Z","steps":["trace[1779819534] 'process raft request' (duration: 354.74093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:00:02.531634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:00:02.231508Z","time spent":"300.102894ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5348,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-24T20:00:02.531792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:00:02.17582Z","time spent":"355.750467ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:01:37.720347Z","caller":"traceutil/trace.go:171","msg":"trace[1934032020] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"104.557269ms","start":"2026-02-24T20:01:37.61577Z","end":"2026-02-24T20:01:37.720327Z","steps":["trace[1934032020] 'process raft request' (duration: 104.247931ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:01:43.135768Z","caller":"traceutil/trace.go:171","msg":"trace[1730112121] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"124.253731ms","start":"2026-02-24T20:01:43.011488Z","end":"2026-02-24T20:01:43.135741Z","steps":["trace[1730112121] 'process raft request' (duration: 113.390917ms)","trace[1730112121] 'compare' (duration: 10.776001ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:02:09.471954Z","caller":"traceutil/trace.go:171","msg":"trace[1083888688] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"212.932602ms","start":"2026-02-24T20:02:09.258985Z","end":"2026-02-24T20:02:09.471917Z","steps":["trace[1083888688] 'process raft request' (duration: 142.387506ms)","trace[1083888688] 'compare' (duration: 70.31933ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:02:09.771426Z","caller":"traceutil/trace.go:171","msg":"trace[564056808] transaction","detail":"{read_only:false; response_revision:2257; number_of_response:1; }","duration":"105.308455ms","start":"2026-02-24T20:02:09.666081Z","end":"2026-02-24T20:02:09.771389Z","steps":["trace[564056808] 'process raft request' (duration: 105.12973ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:02:10.08052Z","caller":"traceutil/trace.go:171","msg":"trace[1092964812] linearizableReadLoop","detail":"{readStateIndex:2344; appliedIndex:2343; }","duration":"183.036835ms","start":"2026-02-24T20:02:09.897454Z","end":"2026-02-24T20:02:10.080491Z","steps":["trace[1092964812] 'read index received' (duration: 182.819429ms)","trace[1092964812] 'applied index is now lower than readState.Index' (duration: 215.996µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:02:10.08062Z","caller":"traceutil/trace.go:171","msg":"trace[2125900800] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"304.516406ms","start":"2026-02-24T20:02:09.776063Z","end":"2026-02-24T20:02:10.08058Z","steps":["trace[2125900800] 'process raft request' (duration: 304.236469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:02:10.080787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:02:09.776031Z","time spent":"304.66788ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:02:10.080852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.366623ms","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-02-24T20:02:10.080901Z","caller":"traceutil/trace.go:171","msg":"trace[52671307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2258; }","duration":"183.499856ms","start":"2026-02-24T20:02:09.897389Z","end":"2026-02-24T20:02:10.080889Z","steps":["trace[52671307] 'agreement among raft nodes before linearized reading' (duration: 183.27228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:02:10.081083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.61906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.1897473c32897692\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-02-24T20:02:10.081209Z","caller":"traceutil/trace.go:171","msg":"trace[1235904279] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.1897473c32897692; range_end:; response_count:1; response_revision:2258; }","duration":"156.794596ms","start":"2026-02-24T20:02:09.924389Z","end":"2026-02-24T20:02:10.081184Z","steps":["trace[1235904279] 'agreement among raft nodes before linearized reading' (duration: 156.349044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:03:36.446986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.426078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:03:36.447098Z","caller":"traceutil/trace.go:171","msg":"trace[841711180] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2872; }","duration":"118.607302ms","start":"2026-02-24T20:03:36.328466Z","end":"2026-02-24T20:03:36.447073Z","steps":["trace[841711180] 'count revisions from in-memory index tree' (duration: 118.10246ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:03:36.67271Z","caller":"traceutil/trace.go:171","msg":"trace[371075364] linearizableReadLoop","detail":"{readStateIndex:2984; appliedIndex:2983; }","duration":"175.731906ms","start":"2026-02-24T20:03:36.496955Z","end":"2026-02-24T20:03:36.672687Z","steps":["trace[371075364] 'read index received' (duration: 175.614473ms)","trace[371075364] 'applied index is now lower than readState.Index' (duration: 115.933µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:03:36.672958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.950762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-02-24T20:03:36.673073Z","caller":"traceutil/trace.go:171","msg":"trace[2078811143] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2872; }","duration":"136.035504ms","start":"2026-02-24T20:03:36.536952Z","end":"2026-02-24T20:03:36.672988Z","steps":["trace[2078811143] 'agreement among raft nodes before linearized reading' (duration: 135.87887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:03:36.67359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.700032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-02-24T20:03:36.673787Z","caller":"traceutil/trace.go:171","msg":"trace[86053679] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2872; }","duration":"176.901927ms","start":"2026-02-24T20:03:36.496862Z","end":"2026-02-24T20:03:36.673764Z","steps":["trace[86053679] 'agreement among raft nodes before linearized reading' (duration: 175.962452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:07:35.121139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.97293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T20:07:35.121302Z","caller":"traceutil/trace.go:171","msg":"trace[1423905475] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"191.641158ms","start":"2026-02-24T20:07:34.929625Z","end":"2026-02-24T20:07:35.121266Z","steps":["trace[1423905475] 'process raft request' (duration: 89.461663ms)","trace[1423905475] 'compare' (duration: 101.808685ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:07:35.121403Z","caller":"traceutil/trace.go:171","msg":"trace[1074696258] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5110; }","duration":"180.062776ms","start":"2026-02-24T20:07:34.941302Z","end":"2026-02-24T20:07:35.121365Z","steps":["trace[1074696258] 'read index received' (duration: 77.8294ms)","trace[1074696258] 'applied index is now lower than readState.Index' (duration: 102.230596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:07:35.121704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.400775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/vector-lbcdz\" ","response":"range_response_count:1 size:4927"} {"level":"info","ts":"2026-02-24T20:07:35.121738Z","caller":"traceutil/trace.go:171","msg":"trace[905353481] range","detail":"{range_begin:/registry/pods/monitoring/vector-lbcdz; range_end:; response_count:1; response_revision:4917; }","duration":"180.508287ms","start":"2026-02-24T20:07:34.94122Z","end":"2026-02-24T20:07:35.121728Z","steps":["trace[905353481] 'agreement among raft nodes before linearized reading' (duration: 180.317022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:07:35.121775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.043793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-19db090b-7ed0-4a77-83b7-cd46736e3168\" ","response":"range_response_count:1 size:3998"} {"level":"info","ts":"2026-02-24T20:07:35.121853Z","caller":"traceutil/trace.go:171","msg":"trace[2023981253] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-19db090b-7ed0-4a77-83b7-cd46736e3168; range_end:; response_count:1; response_revision:4917; }","duration":"149.183966ms","start":"2026-02-24T20:07:34.972648Z","end":"2026-02-24T20:07:35.121832Z","steps":["trace[2023981253] 'agreement among raft nodes before linearized reading' (duration: 148.96758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:07:35.122231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.497461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:776805"} {"level":"info","ts":"2026-02-24T20:07:35.122335Z","caller":"traceutil/trace.go:171","msg":"trace[1824124910] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4917; }","duration":"125.623554ms","start":"2026-02-24T20:07:34.996692Z","end":"2026-02-24T20:07:35.122316Z","steps":["trace[1824124910] 'agreement among raft nodes before linearized reading' (duration: 124.988437ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:07:45.265816Z","caller":"traceutil/trace.go:171","msg":"trace[184304699] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"140.343349ms","start":"2026-02-24T20:07:45.125451Z","end":"2026-02-24T20:07:45.265794Z","steps":["trace[184304699] 'process raft request' (duration: 140.195945ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:07:45.265965Z","caller":"traceutil/trace.go:171","msg":"trace[643499227] linearizableReadLoop","detail":"{readStateIndex:5337; appliedIndex:5334; }","duration":"101.962958ms","start":"2026-02-24T20:07:45.163978Z","end":"2026-02-24T20:07:45.265941Z","steps":["trace[643499227] 'read index received' (duration: 49.375456ms)","trace[643499227] 'applied index is now lower than readState.Index' (duration: 52.586142ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:07:45.266063Z","caller":"traceutil/trace.go:171","msg":"trace[771646757] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"110.246681ms","start":"2026-02-24T20:07:45.155762Z","end":"2026-02-24T20:07:45.266008Z","steps":["trace[771646757] 'process raft request' (duration: 109.992624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:07:45.266168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.167904ms","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-02-24T20:07:45.266209Z","caller":"traceutil/trace.go:171","msg":"trace[1959957939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5135; }","duration":"102.246136ms","start":"2026-02-24T20:07:45.163953Z","end":"2026-02-24T20:07:45.266199Z","steps":["trace[1959957939] 'agreement among raft nodes before linearized reading' (duration: 102.084402ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:07:48.979373Z","caller":"traceutil/trace.go:171","msg":"trace[547200509] linearizableReadLoop","detail":"{readStateIndex:5381; appliedIndex:5380; }","duration":"100.277554ms","start":"2026-02-24T20:07:48.879058Z","end":"2026-02-24T20:07:48.979336Z","steps":["trace[547200509] 'read index received' (duration: 100.187991ms)","trace[547200509] 'applied index is now lower than readState.Index' (duration: 87.863µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:07:48.979472Z","caller":"traceutil/trace.go:171","msg":"trace[1744986967] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"133.143607ms","start":"2026-02-24T20:07:48.846296Z","end":"2026-02-24T20:07:48.979439Z","steps":["trace[1744986967] 'process raft request' (duration: 132.841299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:07:48.979776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.656603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-24T20:07:48.979894Z","caller":"traceutil/trace.go:171","msg":"trace[2033159048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5178; }","duration":"100.859049ms","start":"2026-02-24T20:07:48.87901Z","end":"2026-02-24T20:07:48.979869Z","steps":["trace[2033159048] 'agreement among raft nodes before linearized reading' (duration: 100.451498ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:07:53.434732Z","caller":"traceutil/trace.go:171","msg":"trace[837527773] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"138.51219ms","start":"2026-02-24T20:07:53.29617Z","end":"2026-02-24T20:07:53.434682Z","steps":["trace[837527773] 'process raft request' (duration: 138.280753ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:08:13.594122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2749} {"level":"info","ts":"2026-02-24T20:08:13.650506Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2749,"took":"55.029098ms","hash":2096853387,"current-db-size-bytes":39231488,"current-db-size":"39 MB","current-db-size-in-use-bytes":28856320,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-24T20:08:13.650576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2096853387,"revision":2749,"compact-revision":-1} {"level":"info","ts":"2026-02-24T20:13:13.600998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5534} {"level":"info","ts":"2026-02-24T20:13:13.688184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5534,"took":"85.311632ms","hash":1837832196,"current-db-size-bytes":40357888,"current-db-size":"40 MB","current-db-size-in-use-bytes":21860352,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-02-24T20:13:13.68824Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1837832196,"revision":5534,"compact-revision":2749} {"level":"info","ts":"2026-02-24T20:13:50.246648Z","caller":"traceutil/trace.go:171","msg":"trace[1546599583] transaction","detail":"{read_only:false; response_revision:7932; number_of_response:1; }","duration":"202.676064ms","start":"2026-02-24T20:13:50.04394Z","end":"2026-02-24T20:13:50.246616Z","steps":["trace[1546599583] 'process raft request' (duration: 202.436387ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:13:50.417911Z","caller":"traceutil/trace.go:171","msg":"trace[1574284077] linearizableReadLoop","detail":"{readStateIndex:8224; appliedIndex:8223; }","duration":"171.329072ms","start":"2026-02-24T20:13:50.246557Z","end":"2026-02-24T20:13:50.417886Z","steps":["trace[1574284077] 'read index received' (duration: 171.283091ms)","trace[1574284077] 'applied index is now lower than readState.Index' (duration: 44.871µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:13:50.417954Z","caller":"traceutil/trace.go:171","msg":"trace[1879145023] transaction","detail":"{read_only:false; response_revision:7933; number_of_response:1; }","duration":"185.961564ms","start":"2026-02-24T20:13:50.231967Z","end":"2026-02-24T20:13:50.417929Z","steps":["trace[1879145023] 'process raft request' (duration: 185.7807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:13:50.418219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.031886ms","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-02-24T20:13:50.418264Z","caller":"traceutil/trace.go:171","msg":"trace[267348948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7933; }","duration":"186.120699ms","start":"2026-02-24T20:13:50.232132Z","end":"2026-02-24T20:13:50.418253Z","steps":["trace[267348948] 'agreement among raft nodes before linearized reading' (duration: 185.845082ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:13:50.418858Z","caller":"traceutil/trace.go:171","msg":"trace[1893551175] transaction","detail":"{read_only:false; response_revision:7934; number_of_response:1; }","duration":"168.332911ms","start":"2026-02-24T20:13:50.250498Z","end":"2026-02-24T20:13:50.418831Z","steps":["trace[1893551175] 'process raft request' (duration: 168.235039ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:13:59.820611Z","caller":"traceutil/trace.go:171","msg":"trace[1219416418] transaction","detail":"{read_only:false; response_revision:7977; number_of_response:1; }","duration":"224.711197ms","start":"2026-02-24T20:13:59.595871Z","end":"2026-02-24T20:13:59.820582Z","steps":["trace[1219416418] 'process raft request' (duration: 224.512291ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:00.578014Z","caller":"traceutil/trace.go:171","msg":"trace[748511976] linearizableReadLoop","detail":"{readStateIndex:8275; appliedIndex:8274; }","duration":"113.655454ms","start":"2026-02-24T20:14:00.464341Z","end":"2026-02-24T20:14:00.577996Z","steps":["trace[748511976] 'read index received' (duration: 113.559941ms)","trace[748511976] 'applied index is now lower than readState.Index' (duration: 94.843µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:14:00.578058Z","caller":"traceutil/trace.go:171","msg":"trace[348630220] transaction","detail":"{read_only:false; response_revision:7981; number_of_response:1; }","duration":"113.922561ms","start":"2026-02-24T20:14:00.464112Z","end":"2026-02-24T20:14:00.578035Z","steps":["trace[348630220] 'process raft request' (duration: 113.774327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:14:00.578166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.822928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-24T20:14:00.57819Z","caller":"traceutil/trace.go:171","msg":"trace[87407386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7981; }","duration":"113.89911ms","start":"2026-02-24T20:14:00.464285Z","end":"2026-02-24T20:14:00.578184Z","steps":["trace[87407386] 'agreement among raft nodes before linearized reading' (duration: 113.780897ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:30.126101Z","caller":"traceutil/trace.go:171","msg":"trace[9563825] linearizableReadLoop","detail":"{readStateIndex:8479; appliedIndex:8478; }","duration":"142.463497ms","start":"2026-02-24T20:14:29.983614Z","end":"2026-02-24T20:14:30.126077Z","steps":["trace[9563825] 'read index received' (duration: 142.333234ms)","trace[9563825] 'applied index is now lower than readState.Index' (duration: 129.463µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:14:30.126138Z","caller":"traceutil/trace.go:171","msg":"trace[1219634167] transaction","detail":"{read_only:false; response_revision:8177; number_of_response:1; }","duration":"194.600718ms","start":"2026-02-24T20:14:29.931515Z","end":"2026-02-24T20:14:30.126116Z","steps":["trace[1219634167] 'process raft request' (duration: 194.399063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:14:30.126348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.302387ms","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":"warn","ts":"2026-02-24T20:14:30.126365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.736694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-24T20:14:30.126391Z","caller":"traceutil/trace.go:171","msg":"trace[1224706199] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8177; }","duration":"128.357258ms","start":"2026-02-24T20:14:29.998021Z","end":"2026-02-24T20:14:30.126378Z","steps":["trace[1224706199] 'agreement among raft nodes before linearized reading' (duration: 128.202404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:14:30.126412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.806387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-85n5s\" ","response":"range_response_count:1 size:6386"} {"level":"info","ts":"2026-02-24T20:14:30.126475Z","caller":"traceutil/trace.go:171","msg":"trace[640574158] range","detail":"{range_begin:/registry/pods/openstack/keepalived-85n5s; range_end:; response_count:1; response_revision:8177; }","duration":"110.894219ms","start":"2026-02-24T20:14:30.015569Z","end":"2026-02-24T20:14:30.126463Z","steps":["trace[640574158] 'agreement among raft nodes before linearized reading' (duration: 110.750895ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:30.126471Z","caller":"traceutil/trace.go:171","msg":"trace[1296126403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8177; }","duration":"142.861907ms","start":"2026-02-24T20:14:29.983562Z","end":"2026-02-24T20:14:30.126424Z","steps":["trace[1296126403] 'agreement among raft nodes before linearized reading' (duration: 142.659162ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:30.332047Z","caller":"traceutil/trace.go:171","msg":"trace[1789042322] transaction","detail":"{read_only:false; response_revision:8179; number_of_response:1; }","duration":"201.441581ms","start":"2026-02-24T20:14:30.130581Z","end":"2026-02-24T20:14:30.332023Z","steps":["trace[1789042322] 'process raft request' (duration: 201.339238ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:30.332222Z","caller":"traceutil/trace.go:171","msg":"trace[1076854809] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"202.841329ms","start":"2026-02-24T20:14:30.129331Z","end":"2026-02-24T20:14:30.332172Z","steps":["trace[1076854809] 'process raft request' (duration: 163.447571ms)","trace[1076854809] 'compare' (duration: 38.986307ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:14:30.332401Z","caller":"traceutil/trace.go:171","msg":"trace[1466686493] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"200.103075ms","start":"2026-02-24T20:14:30.132286Z","end":"2026-02-24T20:14:30.332389Z","steps":["trace[1466686493] 'process raft request' (duration: 199.700044ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:30.332398Z","caller":"traceutil/trace.go:171","msg":"trace[1384120870] linearizableReadLoop","detail":"{readStateIndex:8481; appliedIndex:8479; }","duration":"201.333788ms","start":"2026-02-24T20:14:30.131041Z","end":"2026-02-24T20:14:30.332374Z","steps":["trace[1384120870] 'read index received' (duration: 161.702944ms)","trace[1384120870] 'applied index is now lower than readState.Index' (duration: 39.628974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:14:30.332809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.750859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:225491"} {"level":"info","ts":"2026-02-24T20:14:30.332929Z","caller":"traceutil/trace.go:171","msg":"trace[1444483104] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8180; }","duration":"201.902614ms","start":"2026-02-24T20:14:30.131012Z","end":"2026-02-24T20:14:30.332915Z","steps":["trace[1444483104] 'agreement among raft nodes before linearized reading' (duration: 201.419071ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:42.213444Z","caller":"traceutil/trace.go:171","msg":"trace[387731061] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"144.45728ms","start":"2026-02-24T20:14:42.06896Z","end":"2026-02-24T20:14:42.213418Z","steps":["trace[387731061] 'process raft request' (duration: 144.298806ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:43.074937Z","caller":"traceutil/trace.go:171","msg":"trace[868776129] transaction","detail":"{read_only:false; response_revision:8283; number_of_response:1; }","duration":"146.722401ms","start":"2026-02-24T20:14:42.928179Z","end":"2026-02-24T20:14:43.074901Z","steps":["trace[868776129] 'process raft request' (duration: 146.555536ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:14:43.457605Z","caller":"traceutil/trace.go:171","msg":"trace[1696994561] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"103.277133ms","start":"2026-02-24T20:14:43.354285Z","end":"2026-02-24T20:14:43.457562Z","steps":["trace[1696994561] 'process raft request' (duration: 73.276148ms)","trace[1696994561] 'compare' (duration: 29.719518ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:17:34.191651Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b66d12fd59101739","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-24T20:17:34.20061Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-24T20:17:34.200743Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-24T20:18:13.606605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7581} {"level":"info","ts":"2026-02-24T20:18:13.667345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7581,"took":"58.751237ms","hash":2226499819,"current-db-size-bytes":40357888,"current-db-size":"40 MB","current-db-size-in-use-bytes":25829376,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-02-24T20:18:13.667395Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2226499819,"revision":7581,"compact-revision":5534} {"level":"info","ts":"2026-02-24T20:19:36.164358Z","caller":"traceutil/trace.go:171","msg":"trace[1109387334] transaction","detail":"{read_only:false; response_revision:10694; number_of_response:1; }","duration":"214.408614ms","start":"2026-02-24T20:19:35.949909Z","end":"2026-02-24T20:19:36.164317Z","steps":["trace[1109387334] 'process raft request' (duration: 214.165828ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:19:36.744309Z","caller":"traceutil/trace.go:171","msg":"trace[1161146320] transaction","detail":"{read_only:false; response_revision:10700; number_of_response:1; }","duration":"114.069712ms","start":"2026-02-24T20:19:36.630213Z","end":"2026-02-24T20:19:36.744282Z","steps":["trace[1161146320] 'process raft request' (duration: 95.179425ms)","trace[1161146320] 'compare' (duration: 18.481156ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:23:13.613228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9957} {"level":"info","ts":"2026-02-24T20:23:13.697651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9957,"took":"81.445745ms","hash":178049605,"current-db-size-bytes":40357888,"current-db-size":"40 MB","current-db-size-in-use-bytes":23777280,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-02-24T20:23:13.697752Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":178049605,"revision":9957,"compact-revision":7581} {"level":"info","ts":"2026-02-24T20:25:38.034384Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000003483.wal"} {"level":"warn","ts":"2026-02-24T20:25:43.371823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.035194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T20:25:43.371879Z","caller":"traceutil/trace.go:171","msg":"trace[648195647] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:12998; }","duration":"148.139267ms","start":"2026-02-24T20:25:43.223728Z","end":"2026-02-24T20:25:43.371867Z","steps":["trace[648195647] 'count revisions from in-memory index tree' (duration: 147.965483ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:25:59.173565Z","caller":"traceutil/trace.go:171","msg":"trace[187227520] transaction","detail":"{read_only:false; response_revision:13089; number_of_response:1; }","duration":"208.356094ms","start":"2026-02-24T20:25:58.965182Z","end":"2026-02-24T20:25:59.173538Z","steps":["trace[187227520] 'process raft request' (duration: 208.171069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.418718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540884119943320,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T20:26:08.456764Z","caller":"traceutil/trace.go:171","msg":"trace[1508701654] linearizableReadLoop","detail":"{readStateIndex:13722; appliedIndex:13721; }","duration":"538.415435ms","start":"2026-02-24T20:26:07.918323Z","end":"2026-02-24T20:26:08.456739Z","steps":["trace[1508701654] 'read index received' (duration: 538.202339ms)","trace[1508701654] 'applied index is now lower than readState.Index' (duration: 212.006µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:26:08.45683Z","caller":"traceutil/trace.go:171","msg":"trace[611656168] transaction","detail":"{read_only:false; response_revision:13248; number_of_response:1; }","duration":"550.123009ms","start":"2026-02-24T20:26:07.906663Z","end":"2026-02-24T20:26:08.456786Z","steps":["trace[611656168] 'process raft request' (duration: 549.934504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.457091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:26:07.906628Z","time spent":"550.264603ms","remote":"127.0.0.1:37074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T20:26:08.457106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.753434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T20:26:08.457256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.835289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-02-24T20:26:08.457277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.59928ms","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-02-24T20:26:08.457362Z","caller":"traceutil/trace.go:171","msg":"trace[1293993916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13248; }","duration":"335.711672ms","start":"2026-02-24T20:26:08.121632Z","end":"2026-02-24T20:26:08.457343Z","steps":["trace[1293993916] 'agreement among raft nodes before linearized reading' (duration: 335.528047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.457422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:26:08.121617Z","time spent":"335.790225ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T20:26:08.457128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.687949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T20:26:08.4576Z","caller":"traceutil/trace.go:171","msg":"trace[866513547] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:13248; }","duration":"480.217152ms","start":"2026-02-24T20:26:07.977368Z","end":"2026-02-24T20:26:08.457585Z","steps":["trace[866513547] 'agreement among raft nodes before linearized reading' (duration: 479.682678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.457708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:26:07.977341Z","time spent":"480.294965ms","remote":"127.0.0.1:49068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:26:08.457297Z","caller":"traceutil/trace.go:171","msg":"trace[92107822] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13248; }","duration":"334.909071ms","start":"2026-02-24T20:26:08.122372Z","end":"2026-02-24T20:26:08.457281Z","steps":["trace[92107822] 'agreement among raft nodes before linearized reading' (duration: 334.772208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.458001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:26:08.12235Z","time spent":"335.60163ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-02-24T20:26:08.457382Z","caller":"traceutil/trace.go:171","msg":"trace[1953278887] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:13248; }","duration":"538.9922ms","start":"2026-02-24T20:26:07.918274Z","end":"2026-02-24T20:26:08.457266Z","steps":["trace[1953278887] 'agreement among raft nodes before linearized reading' (duration: 538.718103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.457519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.504584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d313da1c-3c56-4b28-8a16-44f98efd6f02\" ","response":"range_response_count:1 size:3563"} {"level":"warn","ts":"2026-02-24T20:26:08.458123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:26:07.918252Z","time spent":"539.797903ms","remote":"127.0.0.1:37436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":11,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:26:08.458176Z","caller":"traceutil/trace.go:171","msg":"trace[1080158089] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d313da1c-3c56-4b28-8a16-44f98efd6f02; range_end:; response_count:1; response_revision:13248; }","duration":"375.214534ms","start":"2026-02-24T20:26:08.082935Z","end":"2026-02-24T20:26:08.45815Z","steps":["trace[1080158089] 'agreement among raft nodes before linearized reading' (duration: 374.367911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:26:08.458257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:26:08.082919Z","time spent":"375.306876ms","remote":"127.0.0.1:37186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3587,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d313da1c-3c56-4b28-8a16-44f98efd6f02\" "} {"level":"info","ts":"2026-02-24T20:27:41.071Z","caller":"traceutil/trace.go:171","msg":"trace[1575249956] transaction","detail":"{read_only:false; response_revision:14253; number_of_response:1; }","duration":"143.283497ms","start":"2026-02-24T20:27:40.927688Z","end":"2026-02-24T20:27:41.070972Z","steps":["trace[1575249956] 'process raft request' (duration: 113.531948ms)","trace[1575249956] 'compare' (duration: 29.093081ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:27:41.07125Z","caller":"traceutil/trace.go:171","msg":"trace[489950844] transaction","detail":"{read_only:false; response_revision:14254; number_of_response:1; }","duration":"143.251946ms","start":"2026-02-24T20:27:40.927981Z","end":"2026-02-24T20:27:41.071233Z","steps":["trace[489950844] 'process raft request' (duration: 142.437855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:27:42.183714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.668032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-r6mm7\" ","response":"range_response_count:1 size:9665"} {"level":"info","ts":"2026-02-24T20:27:42.183819Z","caller":"traceutil/trace.go:171","msg":"trace[1120621519] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-r6mm7; range_end:; response_count:1; response_revision:14257; }","duration":"218.815396ms","start":"2026-02-24T20:27:41.964981Z","end":"2026-02-24T20:27:42.183796Z","steps":["trace[1120621519] 'range keys from in-memory index tree' (duration: 218.494456ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:27:42.205968Z","caller":"traceutil/trace.go:171","msg":"trace[61547765] transaction","detail":"{read_only:false; response_revision:14258; number_of_response:1; }","duration":"212.831544ms","start":"2026-02-24T20:27:41.993104Z","end":"2026-02-24T20:27:42.205936Z","steps":["trace[61547765] 'process raft request' (duration: 212.609199ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:28:13.62044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11976} {"level":"info","ts":"2026-02-24T20:28:13.696583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11976,"took":"74.07886ms","hash":2366558811,"current-db-size-bytes":40357888,"current-db-size":"40 MB","current-db-size-in-use-bytes":27607040,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-24T20:28:13.69664Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2366558811,"revision":11976,"compact-revision":9957} {"level":"info","ts":"2026-02-24T20:33:13.626333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14427} {"level":"info","ts":"2026-02-24T20:33:13.728209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14427,"took":"98.786636ms","hash":3157921104,"current-db-size-bytes":40357888,"current-db-size":"40 MB","current-db-size-in-use-bytes":28381184,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-24T20:33:13.728318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3157921104,"revision":14427,"compact-revision":11976} {"level":"info","ts":"2026-02-24T20:33:39.123773Z","caller":"traceutil/trace.go:171","msg":"trace[991465878] linearizableReadLoop","detail":"{readStateIndex:17555; appliedIndex:17553; }","duration":"169.077884ms","start":"2026-02-24T20:33:38.954672Z","end":"2026-02-24T20:33:39.123749Z","steps":["trace[991465878] 'read index received' (duration: 139.19456ms)","trace[991465878] 'applied index is now lower than readState.Index' (duration: 29.882414ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:33:39.124051Z","caller":"traceutil/trace.go:171","msg":"trace[1713379045] transaction","detail":"{read_only:false; response_revision:16957; number_of_response:1; }","duration":"184.93147ms","start":"2026-02-24T20:33:38.939104Z","end":"2026-02-24T20:33:39.124035Z","steps":["trace[1713379045] 'process raft request' (duration: 154.77196ms)","trace[1713379045] 'compare' (duration: 29.449502ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:33:39.124246Z","caller":"traceutil/trace.go:171","msg":"trace[2040333436] transaction","detail":"{read_only:false; response_revision:16958; number_of_response:1; }","duration":"182.726191ms","start":"2026-02-24T20:33:38.94151Z","end":"2026-02-24T20:33:39.124236Z","steps":["trace[2040333436] 'process raft request' (duration: 182.186656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:39.124683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.975688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-02-24T20:33:39.124794Z","caller":"traceutil/trace.go:171","msg":"trace[1668432821] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16958; }","duration":"170.114762ms","start":"2026-02-24T20:33:38.954653Z","end":"2026-02-24T20:33:39.124768Z","steps":["trace[1668432821] 'agreement among raft nodes before linearized reading' (duration: 169.638659ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:33:45.084686Z","caller":"traceutil/trace.go:171","msg":"trace[1233637505] linearizableReadLoop","detail":"{readStateIndex:17582; appliedIndex:17581; }","duration":"116.495321ms","start":"2026-02-24T20:33:44.968158Z","end":"2026-02-24T20:33:45.084653Z","steps":["trace[1233637505] 'read index received' (duration: 116.243535ms)","trace[1233637505] 'applied index is now lower than readState.Index' (duration: 250.556µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:33:45.084855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.665556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:33:45.08492Z","caller":"traceutil/trace.go:171","msg":"trace[810129177] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:16984; }","duration":"116.782109ms","start":"2026-02-24T20:33:44.968121Z","end":"2026-02-24T20:33:45.084903Z","steps":["trace[810129177] 'agreement among raft nodes before linearized reading' (duration: 116.659306ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:33:45.084945Z","caller":"traceutil/trace.go:171","msg":"trace[1817144996] transaction","detail":"{read_only:false; response_revision:16984; number_of_response:1; }","duration":"120.92242ms","start":"2026-02-24T20:33:44.963999Z","end":"2026-02-24T20:33:45.084921Z","steps":["trace[1817144996] 'process raft request' (duration: 120.467518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:45.372862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.206334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:33:45.373072Z","caller":"traceutil/trace.go:171","msg":"trace[1510468589] transaction","detail":"{read_only:false; response_revision:16985; number_of_response:1; }","duration":"222.857711ms","start":"2026-02-24T20:33:45.150195Z","end":"2026-02-24T20:33:45.373053Z","steps":["trace[1510468589] 'process raft request' (duration: 39.373989ms)","trace[1510468589] 'compare' (duration: 183.02321ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:33:45.373319Z","caller":"traceutil/trace.go:171","msg":"trace[925480407] linearizableReadLoop","detail":"{readStateIndex:17584; appliedIndex:17582; }","duration":"214.890236ms","start":"2026-02-24T20:33:45.158403Z","end":"2026-02-24T20:33:45.373293Z","steps":["trace[925480407] 'read index received' (duration: 31.227839ms)","trace[925480407] 'applied index is now lower than readState.Index' (duration: 183.661057ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:33:45.373545Z","caller":"traceutil/trace.go:171","msg":"trace[874006297] transaction","detail":"{read_only:false; response_revision:16986; number_of_response:1; }","duration":"220.247011ms","start":"2026-02-24T20:33:45.153285Z","end":"2026-02-24T20:33:45.373532Z","steps":["trace[874006297] 'process raft request' (duration: 219.685476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:45.373738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.388291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9wldr\" ","response":"range_response_count:1 size:21727"} {"level":"info","ts":"2026-02-24T20:33:45.37379Z","caller":"traceutil/trace.go:171","msg":"trace[2007686555] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9wldr; range_end:; response_count:1; response_revision:16986; }","duration":"173.474823ms","start":"2026-02-24T20:33:45.200303Z","end":"2026-02-24T20:33:45.373778Z","steps":["trace[2007686555] 'agreement among raft nodes before linearized reading' (duration: 173.268298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:33:45.373761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.342008ms","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-02-24T20:33:45.373848Z","caller":"traceutil/trace.go:171","msg":"trace[769219476] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16986; }","duration":"215.461481ms","start":"2026-02-24T20:33:45.158364Z","end":"2026-02-24T20:33:45.373826Z","steps":["trace[769219476] 'agreement among raft nodes before linearized reading' (duration: 215.229595ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:38:09.121931Z","caller":"traceutil/trace.go:171","msg":"trace[1498968370] transaction","detail":"{read_only:false; response_revision:18828; number_of_response:1; }","duration":"125.076472ms","start":"2026-02-24T20:38:08.996828Z","end":"2026-02-24T20:38:09.121905Z","steps":["trace[1498968370] 'process raft request' (duration: 124.925848ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:38:13.632057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16600} {"level":"info","ts":"2026-02-24T20:38:13.732805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16600,"took":"97.307345ms","hash":1724326523,"current-db-size-bytes":43311104,"current-db-size":"43 MB","current-db-size-in-use-bytes":30679040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-24T20:38:13.732857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1724326523,"revision":16600,"compact-revision":14427} {"level":"info","ts":"2026-02-24T20:38:14.509377Z","caller":"traceutil/trace.go:171","msg":"trace[708992549] transaction","detail":"{read_only:false; response_revision:18850; number_of_response:1; }","duration":"142.601612ms","start":"2026-02-24T20:38:14.366732Z","end":"2026-02-24T20:38:14.509334Z","steps":["trace[708992549] 'process raft request' (duration: 142.449588ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:38:14.660005Z","caller":"traceutil/trace.go:171","msg":"trace[1546256306] transaction","detail":"{read_only:false; response_revision:18851; number_of_response:1; }","duration":"113.191072ms","start":"2026-02-24T20:38:14.54677Z","end":"2026-02-24T20:38:14.659961Z","steps":["trace[1546256306] 'process raft request' (duration: 111.242811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:38:15.022973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.925637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:38:15.02304Z","caller":"traceutil/trace.go:171","msg":"trace[2012927594] linearizableReadLoop","detail":"{readStateIndex:19521; appliedIndex:19520; }","duration":"312.018738ms","start":"2026-02-24T20:38:14.711011Z","end":"2026-02-24T20:38:15.02303Z","steps":["trace[2012927594] 'read index received' (duration: 148.03446ms)","trace[2012927594] 'applied index is now lower than readState.Index' (duration: 163.983188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:38:15.023108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.11026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:38:15.023125Z","caller":"traceutil/trace.go:171","msg":"trace[803893664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18853; }","duration":"312.166382ms","start":"2026-02-24T20:38:14.710954Z","end":"2026-02-24T20:38:15.02312Z","steps":["trace[803893664] 'agreement among raft nodes before linearized reading' (duration: 312.12361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:38:15.023142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:38:14.710922Z","time spent":"312.216983ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:38:15.023127Z","caller":"traceutil/trace.go:171","msg":"trace[955614012] transaction","detail":"{read_only:false; response_revision:18853; number_of_response:1; }","duration":"357.571272ms","start":"2026-02-24T20:38:14.665518Z","end":"2026-02-24T20:38:15.02309Z","steps":["trace[955614012] 'process raft request' (duration: 193.47629ms)","trace[955614012] 'compare' (duration: 163.850805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:38:15.02347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:38:14.665505Z","time spent":"357.787898ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:38:15.280465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.247974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:38:15.280593Z","caller":"traceutil/trace.go:171","msg":"trace[1364942732] linearizableReadLoop","detail":"{readStateIndex:19522; appliedIndex:19521; }","duration":"257.504442ms","start":"2026-02-24T20:38:15.023054Z","end":"2026-02-24T20:38:15.280558Z","steps":["trace[1364942732] 'read index received' (duration: 147.037792ms)","trace[1364942732] 'applied index is now lower than readState.Index' (duration: 110.46522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:38:15.280718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.904256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:38:15.280762Z","caller":"traceutil/trace.go:171","msg":"trace[1678086919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18854; }","duration":"256.967278ms","start":"2026-02-24T20:38:15.023783Z","end":"2026-02-24T20:38:15.28075Z","steps":["trace[1678086919] 'agreement among raft nodes before linearized reading' (duration: 256.899446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:38:15.280785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.776102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:38:15.280862Z","caller":"traceutil/trace.go:171","msg":"trace[1138654118] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:18854; }","duration":"115.900246ms","start":"2026-02-24T20:38:15.164944Z","end":"2026-02-24T20:38:15.280845Z","steps":["trace[1138654118] 'agreement among raft nodes before linearized reading' (duration: 115.742311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:38:15.281051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.783509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-24T20:38:15.281364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.424544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-02-24T20:38:15.280608Z","caller":"traceutil/trace.go:171","msg":"trace[818535979] transaction","detail":"{read_only:false; response_revision:18854; number_of_response:1; }","duration":"472.80903ms","start":"2026-02-24T20:38:14.807773Z","end":"2026-02-24T20:38:15.280582Z","steps":["trace[818535979] 'process raft request' (duration: 362.36707ms)","trace[818535979] 'compare' (duration: 110.134621ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:38:15.28148Z","caller":"traceutil/trace.go:171","msg":"trace[841464300] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18854; }","duration":"496.570378ms","start":"2026-02-24T20:38:14.784892Z","end":"2026-02-24T20:38:15.281463Z","steps":["trace[841464300] 'agreement among raft nodes before linearized reading' (duration: 495.741516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:38:15.281539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:38:14.784875Z","time spent":"496.656961ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-02-24T20:38:15.281364Z","caller":"traceutil/trace.go:171","msg":"trace[452886403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18854; }","duration":"133.17872ms","start":"2026-02-24T20:38:15.148164Z","end":"2026-02-24T20:38:15.281343Z","steps":["trace[452886403] 'agreement among raft nodes before linearized reading' (duration: 132.566264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:38:15.281574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:38:14.807752Z","time spent":"473.753915ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:38:15.444599Z","caller":"traceutil/trace.go:171","msg":"trace[994299287] transaction","detail":"{read_only:false; response_revision:18856; number_of_response:1; }","duration":"158.663044ms","start":"2026-02-24T20:38:15.285903Z","end":"2026-02-24T20:38:15.444566Z","steps":["trace[994299287] 'process raft request' (duration: 143.913438ms)","trace[994299287] 'compare' (duration: 14.354416ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:38:15.444701Z","caller":"traceutil/trace.go:171","msg":"trace[428333427] transaction","detail":"{read_only:false; response_revision:18857; number_of_response:1; }","duration":"156.364743ms","start":"2026-02-24T20:38:15.288299Z","end":"2026-02-24T20:38:15.444664Z","steps":["trace[428333427] 'process raft request' (duration: 156.136237ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:38:57.245165Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b66d12fd59101739","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-24T20:38:57.252045Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-02-24T20:38:57.252122Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-02-24T20:42:03.538832Z","caller":"traceutil/trace.go:171","msg":"trace[1947385159] transaction","detail":"{read_only:false; response_revision:21105; number_of_response:1; }","duration":"106.995554ms","start":"2026-02-24T20:42:03.431809Z","end":"2026-02-24T20:42:03.538804Z","steps":["trace[1947385159] 'process raft request' (duration: 106.823109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:42:03.80344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.017558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-24T20:42:03.803515Z","caller":"traceutil/trace.go:171","msg":"trace[1816639578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21106; }","duration":"141.144502ms","start":"2026-02-24T20:42:03.662355Z","end":"2026-02-24T20:42:03.8035Z","steps":["trace[1816639578] 'range keys from in-memory index tree' (duration: 140.753762ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:43:13.639478Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18848} {"level":"info","ts":"2026-02-24T20:43:13.752162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18848,"took":"109.160422ms","hash":2639377989,"current-db-size-bytes":45719552,"current-db-size":"46 MB","current-db-size-in-use-bytes":38088704,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-02-24T20:43:13.752237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2639377989,"revision":18848,"compact-revision":16600} {"level":"warn","ts":"2026-02-24T20:45:29.758431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.598067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:45:29.758544Z","caller":"traceutil/trace.go:171","msg":"trace[1548194500] linearizableReadLoop","detail":"{readStateIndex:23914; appliedIndex:23913; }","duration":"126.682613ms","start":"2026-02-24T20:45:29.631846Z","end":"2026-02-24T20:45:29.758529Z","steps":["trace[1548194500] 'read index received' (duration: 20.84075ms)","trace[1548194500] 'applied index is now lower than readState.Index' (duration: 105.840503ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:29.75872Z","caller":"traceutil/trace.go:171","msg":"trace[1201564755] transaction","detail":"{read_only:false; response_revision:23120; number_of_response:1; }","duration":"156.3411ms","start":"2026-02-24T20:45:29.602369Z","end":"2026-02-24T20:45:29.75871Z","steps":["trace[1201564755] 'process raft request' (duration: 50.379573ms)","trace[1201564755] 'compare' (duration: 105.492014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:29.758978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.090254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-02-24T20:45:29.759034Z","caller":"traceutil/trace.go:171","msg":"trace[1359798892] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23120; }","duration":"127.215627ms","start":"2026-02-24T20:45:29.631804Z","end":"2026-02-24T20:45:29.759019Z","steps":["trace[1359798892] 'agreement among raft nodes before linearized reading' (duration: 127.022832ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:30.636676Z","caller":"traceutil/trace.go:171","msg":"trace[238311260] transaction","detail":"{read_only:false; response_revision:23123; number_of_response:1; }","duration":"231.073256ms","start":"2026-02-24T20:45:30.40558Z","end":"2026-02-24T20:45:30.636654Z","steps":["trace[238311260] 'process raft request' (duration: 230.889432ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:35.054601Z","caller":"traceutil/trace.go:171","msg":"trace[1257773047] linearizableReadLoop","detail":"{readStateIndex:23954; appliedIndex:23953; }","duration":"108.061422ms","start":"2026-02-24T20:45:34.946519Z","end":"2026-02-24T20:45:35.05458Z","steps":["trace[1257773047] 'read index received' (duration: 107.878818ms)","trace[1257773047] 'applied index is now lower than readState.Index' (duration: 181.834µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:35.054667Z","caller":"traceutil/trace.go:171","msg":"trace[1082016687] transaction","detail":"{read_only:false; response_revision:23159; number_of_response:1; }","duration":"115.064571ms","start":"2026-02-24T20:45:34.939567Z","end":"2026-02-24T20:45:35.054631Z","steps":["trace[1082016687] 'process raft request' (duration: 114.822454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:35.054753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.568839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-02-24T20:45:35.054782Z","caller":"traceutil/trace.go:171","msg":"trace[859718885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23159; }","duration":"107.657172ms","start":"2026-02-24T20:45:34.947118Z","end":"2026-02-24T20:45:35.054775Z","steps":["trace[859718885] 'agreement among raft nodes before linearized reading' (duration: 107.58487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:35.054847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.302669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-24T20:45:35.054918Z","caller":"traceutil/trace.go:171","msg":"trace[1809225496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23159; }","duration":"108.409411ms","start":"2026-02-24T20:45:34.946492Z","end":"2026-02-24T20:45:35.054902Z","steps":["trace[1809225496] 'agreement among raft nodes before linearized reading' (duration: 108.201855ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:35.164607Z","caller":"traceutil/trace.go:171","msg":"trace[132597268] transaction","detail":"{read_only:false; response_revision:23160; number_of_response:1; }","duration":"105.849723ms","start":"2026-02-24T20:45:35.058728Z","end":"2026-02-24T20:45:35.164578Z","steps":["trace[132597268] 'process raft request' (duration: 105.581625ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:35.165647Z","caller":"traceutil/trace.go:171","msg":"trace[1944813880] transaction","detail":"{read_only:false; response_revision:23161; number_of_response:1; }","duration":"106.238934ms","start":"2026-02-24T20:45:35.059361Z","end":"2026-02-24T20:45:35.1656Z","steps":["trace[1944813880] 'process raft request' (duration: 105.69006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:35.460287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.852852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:45:35.460478Z","caller":"traceutil/trace.go:171","msg":"trace[1769411503] transaction","detail":"{read_only:false; response_revision:23163; number_of_response:1; }","duration":"194.605307ms","start":"2026-02-24T20:45:35.26585Z","end":"2026-02-24T20:45:35.460455Z","steps":["trace[1769411503] 'process raft request' (duration: 62.483968ms)","trace[1769411503] 'compare' (duration: 131.641986ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:35.460728Z","caller":"traceutil/trace.go:171","msg":"trace[653156956] transaction","detail":"{read_only:false; response_revision:23165; number_of_response:1; }","duration":"152.792884ms","start":"2026-02-24T20:45:35.307916Z","end":"2026-02-24T20:45:35.460709Z","steps":["trace[653156956] 'process raft request' (duration: 152.711452ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:35.460762Z","caller":"traceutil/trace.go:171","msg":"trace[1157475897] transaction","detail":"{read_only:false; response_revision:23164; number_of_response:1; }","duration":"190.695572ms","start":"2026-02-24T20:45:35.270052Z","end":"2026-02-24T20:45:35.460747Z","steps":["trace[1157475897] 'process raft request' (duration: 190.333722ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:35.651435Z","caller":"traceutil/trace.go:171","msg":"trace[1557617239] transaction","detail":"{read_only:false; response_revision:23166; number_of_response:1; }","duration":"123.781944ms","start":"2026-02-24T20:45:35.527631Z","end":"2026-02-24T20:45:35.651413Z","steps":["trace[1557617239] 'process raft request' (duration: 123.62505ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:35.83773Z","caller":"traceutil/trace.go:171","msg":"trace[137216639] transaction","detail":"{read_only:false; response_revision:23167; number_of_response:1; }","duration":"182.443331ms","start":"2026-02-24T20:45:35.65526Z","end":"2026-02-24T20:45:35.837704Z","steps":["trace[137216639] 'process raft request' (duration: 135.50697ms)","trace[137216639] 'compare' (duration: 46.700924ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:35.837896Z","caller":"traceutil/trace.go:171","msg":"trace[2125028055] linearizableReadLoop","detail":"{readStateIndex:23962; appliedIndex:23961; }","duration":"153.111403ms","start":"2026-02-24T20:45:35.684769Z","end":"2026-02-24T20:45:35.83788Z","steps":["trace[2125028055] 'read index received' (duration: 106.012268ms)","trace[2125028055] 'applied index is now lower than readState.Index' (duration: 47.097945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:35.838079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.263607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:35.838128Z","caller":"traceutil/trace.go:171","msg":"trace[739607870] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:23168; }","duration":"153.39313ms","start":"2026-02-24T20:45:35.684718Z","end":"2026-02-24T20:45:35.838112Z","steps":["trace[739607870] 'agreement among raft nodes before linearized reading' (duration: 153.270297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:35.838116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.33443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:35.838194Z","caller":"traceutil/trace.go:171","msg":"trace[1342493674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23168; }","duration":"127.448203ms","start":"2026-02-24T20:45:35.710728Z","end":"2026-02-24T20:45:35.838176Z","steps":["trace[1342493674] 'agreement among raft nodes before linearized reading' (duration: 127.283759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:36.140435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.343836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:36.140545Z","caller":"traceutil/trace.go:171","msg":"trace[1595123368] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:23170; }","duration":"103.520171ms","start":"2026-02-24T20:45:36.037004Z","end":"2026-02-24T20:45:36.140524Z","steps":["trace[1595123368] 'count revisions from in-memory index tree' (duration: 103.229363ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:37.040081Z","caller":"traceutil/trace.go:171","msg":"trace[1729882470] linearizableReadLoop","detail":"{readStateIndex:23967; appliedIndex:23965; }","duration":"329.044398ms","start":"2026-02-24T20:45:36.710995Z","end":"2026-02-24T20:45:37.04004Z","steps":["trace[1729882470] 'read index received' (duration: 154.855369ms)","trace[1729882470] 'applied index is now lower than readState.Index' (duration: 174.188049ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:37.040339Z","caller":"traceutil/trace.go:171","msg":"trace[806055456] transaction","detail":"{read_only:false; response_revision:23171; number_of_response:1; }","duration":"383.942593ms","start":"2026-02-24T20:45:36.656386Z","end":"2026-02-24T20:45:37.040328Z","steps":["trace[806055456] 'process raft request' (duration: 383.485451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.040448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:36.656351Z","time spent":"384.023445ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:37.040567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.919998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12013"} {"level":"info","ts":"2026-02-24T20:45:37.040613Z","caller":"traceutil/trace.go:171","msg":"trace[1186530524] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23172; }","duration":"293.00145ms","start":"2026-02-24T20:45:36.747601Z","end":"2026-02-24T20:45:37.040603Z","steps":["trace[1186530524] 'agreement among raft nodes before linearized reading' (duration: 292.852326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.040634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.648144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:37.040661Z","caller":"traceutil/trace.go:171","msg":"trace[880895078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23172; }","duration":"329.712725ms","start":"2026-02-24T20:45:36.71094Z","end":"2026-02-24T20:45:37.040653Z","steps":["trace[880895078] 'agreement among raft nodes before linearized reading' (duration: 329.667194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.040691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:36.71092Z","time spent":"329.764867ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:45:37.040786Z","caller":"traceutil/trace.go:171","msg":"trace[893187057] transaction","detail":"{read_only:false; response_revision:23172; number_of_response:1; }","duration":"173.682946ms","start":"2026-02-24T20:45:36.867093Z","end":"2026-02-24T20:45:37.040776Z","steps":["trace[893187057] 'process raft request' (duration: 173.289855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.35254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.927811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-24T20:45:37.3526Z","caller":"traceutil/trace.go:171","msg":"trace[2106125684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23173; }","duration":"184.009843ms","start":"2026-02-24T20:45:37.168577Z","end":"2026-02-24T20:45:37.352586Z","steps":["trace[2106125684] 'range keys from in-memory index tree' (duration: 183.815588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.352886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.584325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-02-24T20:45:37.352931Z","caller":"traceutil/trace.go:171","msg":"trace[169466602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23173; }","duration":"182.650186ms","start":"2026-02-24T20:45:37.170273Z","end":"2026-02-24T20:45:37.352924Z","steps":["trace[169466602] 'range keys from in-memory index tree' (duration: 182.515372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.352954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.996148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:45:37.353114Z","caller":"traceutil/trace.go:171","msg":"trace[1375124373] transaction","detail":"{read_only:false; response_revision:23174; number_of_response:1; }","duration":"181.835125ms","start":"2026-02-24T20:45:37.171269Z","end":"2026-02-24T20:45:37.353104Z","steps":["trace[1375124373] 'process raft request' (duration: 40.640872ms)","trace[1375124373] 'compare' (duration: 140.866284ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:37.503077Z","caller":"traceutil/trace.go:171","msg":"trace[1532270927] transaction","detail":"{read_only:false; response_revision:23175; number_of_response:1; }","duration":"146.500485ms","start":"2026-02-24T20:45:37.356547Z","end":"2026-02-24T20:45:37.503047Z","steps":["trace[1532270927] 'process raft request' (duration: 144.031319ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:37.694726Z","caller":"traceutil/trace.go:171","msg":"trace[1556025859] linearizableReadLoop","detail":"{readStateIndex:23972; appliedIndex:23970; }","duration":"288.658034ms","start":"2026-02-24T20:45:37.406041Z","end":"2026-02-24T20:45:37.694699Z","steps":["trace[1556025859] 'read index received' (duration: 93.484661ms)","trace[1556025859] 'applied index is now lower than readState.Index' (duration: 195.171913ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:37.694878Z","caller":"traceutil/trace.go:171","msg":"trace[1044961738] transaction","detail":"{read_only:false; response_revision:23178; number_of_response:1; }","duration":"226.901595ms","start":"2026-02-24T20:45:37.467965Z","end":"2026-02-24T20:45:37.694867Z","steps":["trace[1044961738] 'process raft request' (duration: 226.69296ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:37.694885Z","caller":"traceutil/trace.go:171","msg":"trace[1207253743] transaction","detail":"{read_only:false; response_revision:23177; number_of_response:1; }","duration":"227.002348ms","start":"2026-02-24T20:45:37.46786Z","end":"2026-02-24T20:45:37.694862Z","steps":["trace[1207253743] 'process raft request' (duration: 226.748071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.695173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.396207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-02-24T20:45:37.695252Z","caller":"traceutil/trace.go:171","msg":"trace[1554994733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23178; }","duration":"227.547502ms","start":"2026-02-24T20:45:37.467684Z","end":"2026-02-24T20:45:37.695231Z","steps":["trace[1554994733] 'agreement among raft nodes before linearized reading' (duration: 227.298195ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:37.695327Z","caller":"traceutil/trace.go:171","msg":"trace[900414387] transaction","detail":"{read_only:false; response_revision:23176; number_of_response:1; }","duration":"337.29201ms","start":"2026-02-24T20:45:37.357994Z","end":"2026-02-24T20:45:37.695286Z","steps":["trace[900414387] 'process raft request' (duration: 319.030749ms)","trace[900414387] 'compare' (duration: 17.397318ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:37.695331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.27922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:45:37.695505Z","caller":"traceutil/trace.go:171","msg":"trace[1792141435] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23178; }","duration":"289.500496ms","start":"2026-02-24T20:45:37.405992Z","end":"2026-02-24T20:45:37.695493Z","steps":["trace[1792141435] 'agreement among raft nodes before linearized reading' (duration: 289.28591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:37.695508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:37.357973Z","time spent":"337.434543ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:37.973456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.927913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:45:37.973532Z","caller":"traceutil/trace.go:171","msg":"trace[2090638766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23179; }","duration":"132.041386ms","start":"2026-02-24T20:45:37.841472Z","end":"2026-02-24T20:45:37.973513Z","steps":["trace[2090638766] 'range keys from in-memory index tree' (duration: 131.749449ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:37.973681Z","caller":"traceutil/trace.go:171","msg":"trace[1189128297] linearizableReadLoop","detail":"{readStateIndex:23976; appliedIndex:23975; }","duration":"117.537857ms","start":"2026-02-24T20:45:37.856118Z","end":"2026-02-24T20:45:37.973656Z","steps":["trace[1189128297] 'read index received' (duration: 102.084263ms)","trace[1189128297] 'applied index is now lower than readState.Index' (duration: 15.452684ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:37.973729Z","caller":"traceutil/trace.go:171","msg":"trace[308501822] transaction","detail":"{read_only:false; response_revision:23180; number_of_response:1; }","duration":"130.510255ms","start":"2026-02-24T20:45:37.843204Z","end":"2026-02-24T20:45:37.973714Z","steps":["trace[308501822] 'process raft request' (duration: 115.03359ms)","trace[308501822] 'compare' (duration: 15.224568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:37.973861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.739462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-02-24T20:45:37.97389Z","caller":"traceutil/trace.go:171","msg":"trace[591503045] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23180; }","duration":"117.813854ms","start":"2026-02-24T20:45:37.856067Z","end":"2026-02-24T20:45:37.973881Z","steps":["trace[591503045] 'agreement among raft nodes before linearized reading' (duration: 117.694721ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:38.198858Z","caller":"traceutil/trace.go:171","msg":"trace[1711545223] transaction","detail":"{read_only:false; response_revision:23182; number_of_response:1; }","duration":"220.130714ms","start":"2026-02-24T20:45:37.978706Z","end":"2026-02-24T20:45:38.198836Z","steps":["trace[1711545223] 'process raft request' (duration: 219.957349ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:38.20142Z","caller":"traceutil/trace.go:171","msg":"trace[404708646] transaction","detail":"{read_only:false; response_revision:23183; number_of_response:1; }","duration":"222.625071ms","start":"2026-02-24T20:45:37.978752Z","end":"2026-02-24T20:45:38.201378Z","steps":["trace[404708646] 'process raft request' (duration: 220.047102ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:38.305933Z","caller":"traceutil/trace.go:171","msg":"trace[1229015521] transaction","detail":"{read_only:false; response_revision:23184; number_of_response:1; }","duration":"183.575192ms","start":"2026-02-24T20:45:38.122329Z","end":"2026-02-24T20:45:38.305904Z","steps":["trace[1229015521] 'process raft request' (duration: 182.510133ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:38.845019Z","caller":"traceutil/trace.go:171","msg":"trace[570490933] linearizableReadLoop","detail":"{readStateIndex:23981; appliedIndex:23980; }","duration":"134.337348ms","start":"2026-02-24T20:45:38.710645Z","end":"2026-02-24T20:45:38.844983Z","steps":["trace[570490933] 'read index received' (duration: 75.045786ms)","trace[570490933] 'applied index is now lower than readState.Index' (duration: 59.290192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:38.845262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.597775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:38.845326Z","caller":"traceutil/trace.go:171","msg":"trace[258001030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23185; }","duration":"134.712839ms","start":"2026-02-24T20:45:38.710593Z","end":"2026-02-24T20:45:38.845306Z","steps":["trace[258001030] 'agreement among raft nodes before linearized reading' (duration: 134.595666ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:38.845433Z","caller":"traceutil/trace.go:171","msg":"trace[103600078] transaction","detail":"{read_only:false; response_revision:23185; number_of_response:1; }","duration":"173.527851ms","start":"2026-02-24T20:45:38.671862Z","end":"2026-02-24T20:45:38.84539Z","steps":["trace[103600078] 'process raft request' (duration: 113.848378ms)","trace[103600078] 'compare' (duration: 59.073536ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:38.845442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.466012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-24T20:45:38.845599Z","caller":"traceutil/trace.go:171","msg":"trace[521211179] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23185; }","duration":"115.610876ms","start":"2026-02-24T20:45:38.729928Z","end":"2026-02-24T20:45:38.845538Z","steps":["trace[521211179] 'agreement among raft nodes before linearized reading' (duration: 115.389109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:39.279286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.371739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:45:39.279483Z","caller":"traceutil/trace.go:171","msg":"trace[2062984530] transaction","detail":"{read_only:false; response_revision:23187; number_of_response:1; }","duration":"229.828933ms","start":"2026-02-24T20:45:39.049634Z","end":"2026-02-24T20:45:39.279463Z","steps":["trace[2062984530] 'process raft request' (duration: 229.756281ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:39.279502Z","caller":"traceutil/trace.go:171","msg":"trace[473450113] transaction","detail":"{read_only:false; response_revision:23186; number_of_response:1; }","duration":"428.543602ms","start":"2026-02-24T20:45:38.8509Z","end":"2026-02-24T20:45:39.279444Z","steps":["trace[473450113] 'process raft request' (duration: 124.69834ms)","trace[473450113] 'compare' (duration: 303.113422ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:39.279628Z","caller":"traceutil/trace.go:171","msg":"trace[1019102253] linearizableReadLoop","detail":"{readStateIndex:23982; appliedIndex:23981; }","duration":"425.946313ms","start":"2026-02-24T20:45:38.853653Z","end":"2026-02-24T20:45:39.279599Z","steps":["trace[1019102253] 'read index received' (duration: 121.908775ms)","trace[1019102253] 'applied index is now lower than readState.Index' (duration: 304.035208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:39.279768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:38.850879Z","time spent":"428.733747ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:39.2798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.121077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:39.279847Z","caller":"traceutil/trace.go:171","msg":"trace[595804156] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:23187; }","duration":"426.21964ms","start":"2026-02-24T20:45:38.85361Z","end":"2026-02-24T20:45:39.27983Z","steps":["trace[595804156] 'agreement among raft nodes before linearized reading' (duration: 426.116507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:39.279887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:38.853595Z","time spent":"426.281791ms","remote":"127.0.0.1:41254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:45:39.280329Z","caller":"traceutil/trace.go:171","msg":"trace[2127364663] transaction","detail":"{read_only:false; response_revision:23188; number_of_response:1; }","duration":"193.96366ms","start":"2026-02-24T20:45:39.08635Z","end":"2026-02-24T20:45:39.280314Z","steps":["trace[2127364663] 'process raft request' (duration: 193.854557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:39.288577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.833884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16845"} {"level":"info","ts":"2026-02-24T20:45:39.288682Z","caller":"traceutil/trace.go:171","msg":"trace[908031705] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23189; }","duration":"385.978618ms","start":"2026-02-24T20:45:38.902682Z","end":"2026-02-24T20:45:39.288661Z","steps":["trace[908031705] 'agreement among raft nodes before linearized reading' (duration: 385.707821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:39.288725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:38.902667Z","time spent":"386.049989ms","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":16869,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-24T20:45:39.485871Z","caller":"traceutil/trace.go:171","msg":"trace[879725421] transaction","detail":"{read_only:false; response_revision:23190; number_of_response:1; }","duration":"127.609996ms","start":"2026-02-24T20:45:39.358216Z","end":"2026-02-24T20:45:39.485826Z","steps":["trace[879725421] 'process raft request' (duration: 103.142369ms)","trace[879725421] 'compare' (duration: 24.303293ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:39.638299Z","caller":"traceutil/trace.go:171","msg":"trace[879420026] transaction","detail":"{read_only:false; response_revision:23191; number_of_response:1; }","duration":"127.353221ms","start":"2026-02-24T20:45:39.510921Z","end":"2026-02-24T20:45:39.638274Z","steps":["trace[879420026] 'process raft request' (duration: 127.250208ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:39.806959Z","caller":"traceutil/trace.go:171","msg":"trace[270887782] linearizableReadLoop","detail":"{readStateIndex:23988; appliedIndex:23987; }","duration":"105.68712ms","start":"2026-02-24T20:45:39.701249Z","end":"2026-02-24T20:45:39.806936Z","steps":["trace[270887782] 'read index received' (duration: 105.522855ms)","trace[270887782] 'applied index is now lower than readState.Index' (duration: 162.825µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:39.807034Z","caller":"traceutil/trace.go:171","msg":"trace[1044598102] transaction","detail":"{read_only:false; response_revision:23192; number_of_response:1; }","duration":"105.813452ms","start":"2026-02-24T20:45:39.701178Z","end":"2026-02-24T20:45:39.806992Z","steps":["trace[1044598102] 'process raft request' (duration: 105.560415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:39.80722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.946436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-24T20:45:39.807263Z","caller":"traceutil/trace.go:171","msg":"trace[264853164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23192; }","duration":"106.041879ms","start":"2026-02-24T20:45:39.701207Z","end":"2026-02-24T20:45:39.807249Z","steps":["trace[264853164] 'agreement among raft nodes before linearized reading' (duration: 105.860564ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:40.11538Z","caller":"traceutil/trace.go:171","msg":"trace[45761392] linearizableReadLoop","detail":"{readStateIndex:23989; appliedIndex:23988; }","duration":"308.311262ms","start":"2026-02-24T20:45:39.807033Z","end":"2026-02-24T20:45:40.115344Z","steps":["trace[45761392] 'read index received' (duration: 273.736113ms)","trace[45761392] 'applied index is now lower than readState.Index' (duration: 34.573279ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:40.115511Z","caller":"traceutil/trace.go:171","msg":"trace[324031836] transaction","detail":"{read_only:false; response_revision:23193; number_of_response:1; }","duration":"413.757944ms","start":"2026-02-24T20:45:39.701717Z","end":"2026-02-24T20:45:40.115475Z","steps":["trace[324031836] 'process raft request' (duration: 379.035832ms)","trace[324031836] 'compare' (duration: 33.439197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:40.115599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.871922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-02-24T20:45:40.115652Z","caller":"traceutil/trace.go:171","msg":"trace[475624900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23193; }","duration":"354.977445ms","start":"2026-02-24T20:45:39.760653Z","end":"2026-02-24T20:45:40.115631Z","steps":["trace[475624900] 'agreement among raft nodes before linearized reading' (duration: 354.855192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:40.115687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:39.701702Z","time spent":"413.884908ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:40.115693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:39.760633Z","time spent":"355.050008ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T20:45:40.116145Z","caller":"traceutil/trace.go:171","msg":"trace[1155709727] transaction","detail":"{read_only:false; response_revision:23194; number_of_response:1; }","duration":"304.284123ms","start":"2026-02-24T20:45:39.811848Z","end":"2026-02-24T20:45:40.116132Z","steps":["trace[1155709727] 'process raft request' (duration: 304.120608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:40.116225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:39.811813Z","time spent":"304.379086ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:45:40.116221Z","caller":"traceutil/trace.go:171","msg":"trace[1274129180] transaction","detail":"{read_only:false; response_revision:23195; number_of_response:1; }","duration":"137.097422ms","start":"2026-02-24T20:45:39.9791Z","end":"2026-02-24T20:45:40.116198Z","steps":["trace[1274129180] 'process raft request' (duration: 136.960109ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:40.327449Z","caller":"traceutil/trace.go:171","msg":"trace[1059384069] linearizableReadLoop","detail":"{readStateIndex:23993; appliedIndex:23991; }","duration":"145.424617ms","start":"2026-02-24T20:45:40.181995Z","end":"2026-02-24T20:45:40.327419Z","steps":["trace[1059384069] 'read index received' (duration: 91.60831ms)","trace[1059384069] 'applied index is now lower than readState.Index' (duration: 53.814857ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:40.327547Z","caller":"traceutil/trace.go:171","msg":"trace[607812365] transaction","detail":"{read_only:false; response_revision:23196; number_of_response:1; }","duration":"207.908076ms","start":"2026-02-24T20:45:40.119589Z","end":"2026-02-24T20:45:40.327497Z","steps":["trace[607812365] 'process raft request' (duration: 153.996307ms)","trace[607812365] 'compare' (duration: 53.564028ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:40.327796Z","caller":"traceutil/trace.go:171","msg":"trace[826618181] transaction","detail":"{read_only:false; response_revision:23197; number_of_response:1; }","duration":"207.766152ms","start":"2026-02-24T20:45:40.11995Z","end":"2026-02-24T20:45:40.327716Z","steps":["trace[826618181] 'process raft request' (duration: 207.397612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:40.328139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.125465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:40.328172Z","caller":"traceutil/trace.go:171","msg":"trace[1679879141] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:23197; }","duration":"146.228497ms","start":"2026-02-24T20:45:40.181929Z","end":"2026-02-24T20:45:40.328157Z","steps":["trace[1679879141] 'agreement among raft nodes before linearized reading' (duration: 146.121644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:40.328436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.022211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T20:45:40.328481Z","caller":"traceutil/trace.go:171","msg":"trace[179747438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23197; }","duration":"121.114864ms","start":"2026-02-24T20:45:40.207353Z","end":"2026-02-24T20:45:40.328468Z","steps":["trace[179747438] 'agreement among raft nodes before linearized reading' (duration: 120.958669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:40.328604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.158204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:45:40.328621Z","caller":"traceutil/trace.go:171","msg":"trace[1501954613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23197; }","duration":"121.201345ms","start":"2026-02-24T20:45:40.207415Z","end":"2026-02-24T20:45:40.328616Z","steps":["trace[1501954613] 'agreement among raft nodes before linearized reading' (duration: 121.078082ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:40.600103Z","caller":"traceutil/trace.go:171","msg":"trace[1729214744] transaction","detail":"{read_only:false; response_revision:23199; number_of_response:1; }","duration":"266.601851ms","start":"2026-02-24T20:45:40.333471Z","end":"2026-02-24T20:45:40.600073Z","steps":["trace[1729214744] 'process raft request' (duration: 213.745251ms)","trace[1729214744] 'compare' (duration: 52.647545ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:49.681345Z","caller":"traceutil/trace.go:171","msg":"trace[786054577] transaction","detail":"{read_only:false; response_revision:23257; number_of_response:1; }","duration":"376.279359ms","start":"2026-02-24T20:45:49.30504Z","end":"2026-02-24T20:45:49.68132Z","steps":["trace[786054577] 'process raft request' (duration: 376.140335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:49.681492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:49.305002Z","time spent":"376.418312ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:45:49.696238Z","caller":"traceutil/trace.go:171","msg":"trace[1057413635] linearizableReadLoop","detail":"{readStateIndex:24057; appliedIndex:24055; }","duration":"330.007505ms","start":"2026-02-24T20:45:49.366212Z","end":"2026-02-24T20:45:49.69622Z","steps":["trace[1057413635] 'read index received' (duration: 315.088524ms)","trace[1057413635] 'applied index is now lower than readState.Index' (duration: 14.918081ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:49.696347Z","caller":"traceutil/trace.go:171","msg":"trace[717469443] transaction","detail":"{read_only:false; response_revision:23259; number_of_response:1; }","duration":"369.754363ms","start":"2026-02-24T20:45:49.326582Z","end":"2026-02-24T20:45:49.696336Z","steps":["trace[717469443] 'process raft request' (duration: 369.559278ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:49.696377Z","caller":"traceutil/trace.go:171","msg":"trace[562855479] transaction","detail":"{read_only:false; response_revision:23258; number_of_response:1; }","duration":"376.890055ms","start":"2026-02-24T20:45:49.319442Z","end":"2026-02-24T20:45:49.696332Z","steps":["trace[562855479] 'process raft request' (duration: 376.590017ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:49.696466Z","caller":"traceutil/trace.go:171","msg":"trace[874148391] transaction","detail":"{read_only:false; response_revision:23260; number_of_response:1; }","duration":"166.601705ms","start":"2026-02-24T20:45:49.529847Z","end":"2026-02-24T20:45:49.696448Z","steps":["trace[874148391] 'process raft request' (duration: 166.335088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:49.696596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:49.319419Z","time spent":"377.057419ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:49.696628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:49.326566Z","time spent":"369.805704ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:49.69667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.419145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:49.697327Z","caller":"traceutil/trace.go:171","msg":"trace[689195133] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:23260; }","duration":"330.867897ms","start":"2026-02-24T20:45:49.366153Z","end":"2026-02-24T20:45:49.697021Z","steps":["trace[689195133] 'agreement among raft nodes before linearized reading' (duration: 330.265101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:49.69743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:49.366127Z","time spent":"331.256188ms","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:45:50.289603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.965008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:45:50.28974Z","caller":"traceutil/trace.go:171","msg":"trace[127193466] linearizableReadLoop","detail":"{readStateIndex:24059; appliedIndex:24058; }","duration":"579.733023ms","start":"2026-02-24T20:45:49.709984Z","end":"2026-02-24T20:45:50.289717Z","steps":["trace[127193466] 'read index received' (duration: 247.696533ms)","trace[127193466] 'applied index is now lower than readState.Index' (duration: 332.03495ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:50.290046Z","caller":"traceutil/trace.go:171","msg":"trace[951048145] transaction","detail":"{read_only:false; response_revision:23261; number_of_response:1; }","duration":"589.69018ms","start":"2026-02-24T20:45:49.700336Z","end":"2026-02-24T20:45:50.290027Z","steps":["trace[951048145] 'process raft request' (duration: 257.177038ms)","trace[951048145] 'compare' (duration: 331.809914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:50.290137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:49.700307Z","time spent":"589.791023ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:50.290392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.414481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:50.290481Z","caller":"traceutil/trace.go:171","msg":"trace[862465863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23261; }","duration":"580.493793ms","start":"2026-02-24T20:45:49.70993Z","end":"2026-02-24T20:45:50.290423Z","steps":["trace[862465863] 'agreement among raft nodes before linearized reading' (duration: 580.419681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:50.290521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:49.709907Z","time spent":"580.606966ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:45:50.341072Z","caller":"traceutil/trace.go:171","msg":"trace[225505503] transaction","detail":"{read_only:false; response_revision:23262; number_of_response:1; }","duration":"492.638833ms","start":"2026-02-24T20:45:49.848404Z","end":"2026-02-24T20:45:50.341043Z","steps":["trace[225505503] 'process raft request' (duration: 492.471189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:50.341228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:49.848373Z","time spent":"492.776857ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:45:50.356001Z","caller":"traceutil/trace.go:171","msg":"trace[2125016110] transaction","detail":"{read_only:false; response_revision:23263; number_of_response:1; }","duration":"209.773724ms","start":"2026-02-24T20:45:50.146209Z","end":"2026-02-24T20:45:50.355982Z","steps":["trace[2125016110] 'process raft request' (duration: 209.422695ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:50.356164Z","caller":"traceutil/trace.go:171","msg":"trace[2040724175] transaction","detail":"{read_only:false; response_revision:23264; number_of_response:1; }","duration":"206.167737ms","start":"2026-02-24T20:45:50.149986Z","end":"2026-02-24T20:45:50.356153Z","steps":["trace[2040724175] 'process raft request' (duration: 205.720675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:50.356508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.056004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-02-24T20:45:50.356545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.561045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:50.356613Z","caller":"traceutil/trace.go:171","msg":"trace[1929409314] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:23264; }","duration":"236.656467ms","start":"2026-02-24T20:45:50.119924Z","end":"2026-02-24T20:45:50.35658Z","steps":["trace[1929409314] 'agreement among raft nodes before linearized reading' (duration: 236.574145ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:50.356593Z","caller":"traceutil/trace.go:171","msg":"trace[162437352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23264; }","duration":"197.157857ms","start":"2026-02-24T20:45:50.159415Z","end":"2026-02-24T20:45:50.356573Z","steps":["trace[162437352] 'agreement among raft nodes before linearized reading' (duration: 196.89966ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:50.636837Z","caller":"traceutil/trace.go:171","msg":"trace[663134531] linearizableReadLoop","detail":"{readStateIndex:24063; appliedIndex:24062; }","duration":"272.094719ms","start":"2026-02-24T20:45:50.364721Z","end":"2026-02-24T20:45:50.636816Z","steps":["trace[663134531] 'read index received' (duration: 258.462842ms)","trace[663134531] 'applied index is now lower than readState.Index' (duration: 13.630777ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:50.637064Z","caller":"traceutil/trace.go:171","msg":"trace[2052056868] transaction","detail":"{read_only:false; response_revision:23265; number_of_response:1; }","duration":"272.418868ms","start":"2026-02-24T20:45:50.364595Z","end":"2026-02-24T20:45:50.637014Z","steps":["trace[2052056868] 'process raft request' (duration: 258.585806ms)","trace[2052056868] 'compare' (duration: 13.545874ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:50.637262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.812003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-02-24T20:45:50.637322Z","caller":"traceutil/trace.go:171","msg":"trace[1637065318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23265; }","duration":"258.892995ms","start":"2026-02-24T20:45:50.378413Z","end":"2026-02-24T20:45:50.637306Z","steps":["trace[1637065318] 'agreement among raft nodes before linearized reading' (duration: 258.7266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:50.637303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.54619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-02-24T20:45:50.637343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.699803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-02-24T20:45:50.637371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.628039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:45:50.637408Z","caller":"traceutil/trace.go:171","msg":"trace[1261210555] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:23265; }","duration":"272.673483ms","start":"2026-02-24T20:45:50.364705Z","end":"2026-02-24T20:45:50.637378Z","steps":["trace[1261210555] 'agreement among raft nodes before linearized reading' (duration: 272.293113ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:50.637432Z","caller":"traceutil/trace.go:171","msg":"trace[933276166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23265; }","duration":"263.848457ms","start":"2026-02-24T20:45:50.373559Z","end":"2026-02-24T20:45:50.637407Z","steps":["trace[933276166] 'agreement among raft nodes before linearized reading' (duration: 263.56917ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:50.637479Z","caller":"traceutil/trace.go:171","msg":"trace[1901269705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23265; }","duration":"238.771313ms","start":"2026-02-24T20:45:50.398677Z","end":"2026-02-24T20:45:50.637448Z","steps":["trace[1901269705] 'agreement among raft nodes before linearized reading' (duration: 238.453615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:51.170131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.846989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:45:51.17029Z","caller":"traceutil/trace.go:171","msg":"trace[1790049559] transaction","detail":"{read_only:false; response_revision:23266; number_of_response:1; }","duration":"529.134273ms","start":"2026-02-24T20:45:50.641126Z","end":"2026-02-24T20:45:51.170261Z","steps":["trace[1790049559] 'process raft request' (duration: 256.037238ms)","trace[1790049559] 'compare' (duration: 272.421518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:45:51.170386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:50.641033Z","time spent":"529.307999ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:45:51.71815Z","caller":"traceutil/trace.go:171","msg":"trace[2030870421] linearizableReadLoop","detail":"{readStateIndex:24066; appliedIndex:24063; }","duration":"1.074338559s","start":"2026-02-24T20:45:50.643785Z","end":"2026-02-24T20:45:51.718123Z","steps":["trace[2030870421] 'read index received' (duration: 253.393548ms)","trace[2030870421] 'applied index is now lower than readState.Index' (duration: 820.943891ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:51.718217Z","caller":"traceutil/trace.go:171","msg":"trace[188793829] transaction","detail":"{read_only:false; response_revision:23267; number_of_response:1; }","duration":"1.076162077s","start":"2026-02-24T20:45:50.642002Z","end":"2026-02-24T20:45:51.718164Z","steps":["trace[188793829] 'process raft request' (duration: 1.008715306s)","trace[188793829] 'compare' (duration: 66.988249ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:51.718266Z","caller":"traceutil/trace.go:171","msg":"trace[903377890] transaction","detail":"{read_only:false; response_revision:23268; number_of_response:1; }","duration":"1.075737585s","start":"2026-02-24T20:45:50.642502Z","end":"2026-02-24T20:45:51.71824Z","steps":["trace[903377890] 'process raft request' (duration: 1.07555091s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:51.718368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:50.641986Z","time spent":"1.07629075s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:45:51.718407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074605616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-24T20:45:51.718417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:50.642495Z","time spent":"1.075824769s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:45:51.718498Z","caller":"traceutil/trace.go:171","msg":"trace[413396575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23268; }","duration":"1.074720649s","start":"2026-02-24T20:45:50.643759Z","end":"2026-02-24T20:45:51.718479Z","steps":["trace[413396575] 'agreement among raft nodes before linearized reading' (duration: 1.074519683s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:51.718697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:50.643751Z","time spent":"1.074916694s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T20:45:51.734468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078787918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-02-24T20:45:51.734519Z","caller":"traceutil/trace.go:171","msg":"trace[2071892043] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23271; }","duration":"1.078842299s","start":"2026-02-24T20:45:50.655664Z","end":"2026-02-24T20:45:51.734506Z","steps":["trace[2071892043] 'agreement among raft nodes before linearized reading' (duration: 1.078673665s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:51.734546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:50.655633Z","time spent":"1.078906482s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":586,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-02-24T20:45:51.734882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.444454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:51.734917Z","caller":"traceutil/trace.go:171","msg":"trace[715598100] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:23271; }","duration":"643.537227ms","start":"2026-02-24T20:45:51.091371Z","end":"2026-02-24T20:45:51.734908Z","steps":["trace[715598100] 'agreement among raft nodes before linearized reading' (duration: 643.483066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:51.734943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:51.091338Z","time spent":"643.597898ms","remote":"127.0.0.1:37230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:45:51.735101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.87125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:45:51.735129Z","caller":"traceutil/trace.go:171","msg":"trace[64350935] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:23271; }","duration":"906.944542ms","start":"2026-02-24T20:45:50.828177Z","end":"2026-02-24T20:45:51.735122Z","steps":["trace[64350935] 'agreement among raft nodes before linearized reading' (duration: 906.88092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:51.735157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:50.828153Z","time spent":"906.996903ms","remote":"127.0.0.1:37428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":21,"response size":32,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:45:51.735284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025040834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:51.735311Z","caller":"traceutil/trace.go:171","msg":"trace[1207689993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23271; }","duration":"1.025096036s","start":"2026-02-24T20:45:50.710208Z","end":"2026-02-24T20:45:51.735304Z","steps":["trace[1207689993] 'agreement among raft nodes before linearized reading' (duration: 1.025057815s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:51.735332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:45:50.710195Z","time spent":"1.025133176s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:45:55.93165Z","caller":"traceutil/trace.go:171","msg":"trace[1533473582] linearizableReadLoop","detail":"{readStateIndex:24156; appliedIndex:24155; }","duration":"237.801408ms","start":"2026-02-24T20:45:55.693815Z","end":"2026-02-24T20:45:55.931617Z","steps":["trace[1533473582] 'read index received' (duration: 237.538681ms)","trace[1533473582] 'applied index is now lower than readState.Index' (duration: 261.077µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:45:55.93168Z","caller":"traceutil/trace.go:171","msg":"trace[1209317293] transaction","detail":"{read_only:false; response_revision:23356; number_of_response:1; }","duration":"261.500265ms","start":"2026-02-24T20:45:55.670151Z","end":"2026-02-24T20:45:55.931651Z","steps":["trace[1209317293] 'process raft request' (duration: 261.255718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:55.931841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.003113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:45:55.931913Z","caller":"traceutil/trace.go:171","msg":"trace[675074786] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:23356; }","duration":"238.131176ms","start":"2026-02-24T20:45:55.693759Z","end":"2026-02-24T20:45:55.93189Z","steps":["trace[675074786] 'agreement among raft nodes before linearized reading' (duration: 237.977822ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:55.934001Z","caller":"traceutil/trace.go:171","msg":"trace[35414902] transaction","detail":"{read_only:false; response_revision:23357; number_of_response:1; }","duration":"197.071974ms","start":"2026-02-24T20:45:55.736904Z","end":"2026-02-24T20:45:55.933976Z","steps":["trace[35414902] 'process raft request' (duration: 196.884749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:55.934377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.009073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-02-24T20:45:55.934429Z","caller":"traceutil/trace.go:171","msg":"trace[1839544384] transaction","detail":"{read_only:false; response_revision:23358; number_of_response:1; }","duration":"190.98119ms","start":"2026-02-24T20:45:55.743435Z","end":"2026-02-24T20:45:55.934416Z","steps":["trace[1839544384] 'process raft request' (duration: 190.498917ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:55.934491Z","caller":"traceutil/trace.go:171","msg":"trace[575669714] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23359; }","duration":"203.171487ms","start":"2026-02-24T20:45:55.731301Z","end":"2026-02-24T20:45:55.934472Z","steps":["trace[575669714] 'agreement among raft nodes before linearized reading' (duration: 202.932151ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:55.934502Z","caller":"traceutil/trace.go:171","msg":"trace[373714393] transaction","detail":"{read_only:false; response_revision:23359; number_of_response:1; }","duration":"186.950882ms","start":"2026-02-24T20:45:55.747522Z","end":"2026-02-24T20:45:55.934473Z","steps":["trace[373714393] 'process raft request' (duration: 186.618363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:55.934767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.026475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-02-24T20:45:55.934809Z","caller":"traceutil/trace.go:171","msg":"trace[722966883] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23359; }","duration":"196.113337ms","start":"2026-02-24T20:45:55.738684Z","end":"2026-02-24T20:45:55.934798Z","steps":["trace[722966883] 'agreement among raft nodes before linearized reading' (duration: 195.968793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:45:55.934842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.106383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T20:45:55.934889Z","caller":"traceutil/trace.go:171","msg":"trace[76904256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23359; }","duration":"150.195834ms","start":"2026-02-24T20:45:55.784681Z","end":"2026-02-24T20:45:55.934877Z","steps":["trace[76904256] 'agreement among raft nodes before linearized reading' (duration: 150.04865ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:56.193205Z","caller":"traceutil/trace.go:171","msg":"trace[197368717] transaction","detail":"{read_only:false; response_revision:23361; number_of_response:1; }","duration":"252.287947ms","start":"2026-02-24T20:45:55.940892Z","end":"2026-02-24T20:45:56.19318Z","steps":["trace[197368717] 'process raft request' (duration: 252.107692ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:56.193602Z","caller":"traceutil/trace.go:171","msg":"trace[1812394362] transaction","detail":"{read_only:false; response_revision:23362; number_of_response:1; }","duration":"250.573171ms","start":"2026-02-24T20:45:55.94302Z","end":"2026-02-24T20:45:56.193593Z","steps":["trace[1812394362] 'process raft request' (duration: 250.126049ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:58.072916Z","caller":"traceutil/trace.go:171","msg":"trace[103906403] transaction","detail":"{read_only:false; response_revision:23372; number_of_response:1; }","duration":"128.886492ms","start":"2026-02-24T20:45:57.944007Z","end":"2026-02-24T20:45:58.072893Z","steps":["trace[103906403] 'process raft request' (duration: 127.535487ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:45:58.073083Z","caller":"traceutil/trace.go:171","msg":"trace[99971464] transaction","detail":"{read_only:false; response_revision:23373; number_of_response:1; }","duration":"128.995555ms","start":"2026-02-24T20:45:57.944078Z","end":"2026-02-24T20:45:58.073073Z","steps":["trace[99971464] 'process raft request' (duration: 128.40787ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:00.816682Z","caller":"traceutil/trace.go:171","msg":"trace[750565386] transaction","detail":"{read_only:false; response_revision:23411; number_of_response:1; }","duration":"122.610064ms","start":"2026-02-24T20:46:00.69405Z","end":"2026-02-24T20:46:00.81666Z","steps":["trace[750565386] 'process raft request' (duration: 122.297855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:00.954058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.858476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8c29919b-138f-47bb-9b86-d6c4f6e2a664\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-02-24T20:46:00.954144Z","caller":"traceutil/trace.go:171","msg":"trace[913697471] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8c29919b-138f-47bb-9b86-d6c4f6e2a664; range_end:; response_count:1; response_revision:23411; }","duration":"133.987179ms","start":"2026-02-24T20:46:00.820138Z","end":"2026-02-24T20:46:00.954125Z","steps":["trace[913697471] 'range keys from in-memory index tree' (duration: 133.63645ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:01.004068Z","caller":"traceutil/trace.go:171","msg":"trace[753517210] transaction","detail":"{read_only:false; response_revision:23412; number_of_response:1; }","duration":"177.721393ms","start":"2026-02-24T20:46:00.826307Z","end":"2026-02-24T20:46:01.004029Z","steps":["trace[753517210] 'process raft request' (duration: 177.450856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.013272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540884119975854,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T20:46:31.216313Z","caller":"traceutil/trace.go:171","msg":"trace[781715047] transaction","detail":"{read_only:false; response_revision:23640; number_of_response:1; }","duration":"716.359892ms","start":"2026-02-24T20:46:30.499924Z","end":"2026-02-24T20:46:31.216284Z","steps":["trace[781715047] 'process raft request' (duration: 716.187398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.216465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:30.499916Z","time spent":"716.472816ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:31.459435Z","caller":"traceutil/trace.go:171","msg":"trace[761483279] linearizableReadLoop","detail":"{readStateIndex:24449; appliedIndex:24447; }","duration":"946.597677ms","start":"2026-02-24T20:46:30.512817Z","end":"2026-02-24T20:46:31.459415Z","steps":["trace[761483279] 'read index received' (duration: 703.307732ms)","trace[761483279] 'applied index is now lower than readState.Index' (duration: 243.289245ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:31.459639Z","caller":"traceutil/trace.go:171","msg":"trace[1254801145] transaction","detail":"{read_only:false; response_revision:23641; number_of_response:1; }","duration":"860.895705ms","start":"2026-02-24T20:46:30.598732Z","end":"2026-02-24T20:46:31.459627Z","steps":["trace[1254801145] 'process raft request' (duration: 789.670222ms)","trace[1254801145] 'compare' (duration: 70.666208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:31.459729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:30.598715Z","time spent":"860.955956ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:31.459815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.979407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-24T20:46:31.459942Z","caller":"traceutil/trace.go:171","msg":"trace[1021151452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23641; }","duration":"947.112351ms","start":"2026-02-24T20:46:30.512812Z","end":"2026-02-24T20:46:31.459924Z","steps":["trace[1021151452] 'agreement among raft nodes before linearized reading' (duration: 946.829013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.459986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:30.512774Z","time spent":"947.204023ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T20:46:31.534371Z","caller":"traceutil/trace.go:171","msg":"trace[248245745] transaction","detail":"{read_only:false; response_revision:23642; number_of_response:1; }","duration":"135.381077ms","start":"2026-02-24T20:46:31.398943Z","end":"2026-02-24T20:46:31.534324Z","steps":["trace[248245745] 'process raft request' (duration: 135.159451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.53454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.658707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:31.534566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.265175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-02-24T20:46:31.534567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.054043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:46:31.534617Z","caller":"traceutil/trace.go:171","msg":"trace[1363358228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23642; }","duration":"790.757841ms","start":"2026-02-24T20:46:30.74384Z","end":"2026-02-24T20:46:31.534598Z","steps":["trace[1363358228] 'agreement among raft nodes before linearized reading' (duration: 790.609397ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:31.534633Z","caller":"traceutil/trace.go:171","msg":"trace[801738164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23642; }","duration":"552.329776ms","start":"2026-02-24T20:46:30.98229Z","end":"2026-02-24T20:46:31.53462Z","steps":["trace[801738164] 'agreement among raft nodes before linearized reading' (duration: 552.114701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.534626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.013207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-02-24T20:46:31.534664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:30.982214Z","time spent":"552.44546ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T20:46:31.534665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:30.743836Z","time spent":"790.822042ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:46:31.534698Z","caller":"traceutil/trace.go:171","msg":"trace[851123514] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:23642; }","duration":"686.12758ms","start":"2026-02-24T20:46:30.848552Z","end":"2026-02-24T20:46:31.53468Z","steps":["trace[851123514] 'agreement among raft nodes before linearized reading' (duration: 685.829852ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:31.534655Z","caller":"traceutil/trace.go:171","msg":"trace[32036103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23642; }","duration":"162.196047ms","start":"2026-02-24T20:46:31.372445Z","end":"2026-02-24T20:46:31.534641Z","steps":["trace[32036103] 'agreement among raft nodes before linearized reading' (duration: 161.901979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.534737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:30.848538Z","time spent":"686.192362ms","remote":"127.0.0.1:37444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":58,"response size":32,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:46:31.534461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.016984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:31.534834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.07965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:46:31.534892Z","caller":"traceutil/trace.go:171","msg":"trace[660048880] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:23642; }","duration":"791.188353ms","start":"2026-02-24T20:46:30.74369Z","end":"2026-02-24T20:46:31.534878Z","steps":["trace[660048880] 'agreement among raft nodes before linearized reading' (duration: 790.567927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.534931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:30.743676Z","time spent":"791.245124ms","remote":"127.0.0.1:37348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":97,"response size":32,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:46:31.534853Z","caller":"traceutil/trace.go:171","msg":"trace[1309333072] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:23642; }","duration":"308.491486ms","start":"2026-02-24T20:46:31.226345Z","end":"2026-02-24T20:46:31.534837Z","steps":["trace[1309333072] 'agreement among raft nodes before linearized reading' (duration: 307.931481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:31.535295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:31.226329Z","time spent":"308.878777ms","remote":"127.0.0.1:50166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:46:31.68248Z","caller":"traceutil/trace.go:171","msg":"trace[556308006] linearizableReadLoop","detail":"{readStateIndex:24451; appliedIndex:24450; }","duration":"146.789894ms","start":"2026-02-24T20:46:31.535667Z","end":"2026-02-24T20:46:31.682457Z","steps":["trace[556308006] 'read index received' (duration: 129.114209ms)","trace[556308006] 'applied index is now lower than readState.Index' (duration: 17.674625ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:31.682485Z","caller":"traceutil/trace.go:171","msg":"trace[211035589] transaction","detail":"{read_only:false; response_revision:23643; number_of_response:1; }","duration":"218.603272ms","start":"2026-02-24T20:46:31.463822Z","end":"2026-02-24T20:46:31.682426Z","steps":["trace[211035589] 'process raft request' (duration: 201.006969ms)","trace[211035589] 'compare' (duration: 17.289334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:31.682749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.064961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:31.682807Z","caller":"traceutil/trace.go:171","msg":"trace[831688191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23643; }","duration":"147.161504ms","start":"2026-02-24T20:46:31.535633Z","end":"2026-02-24T20:46:31.682795Z","steps":["trace[831688191] 'agreement among raft nodes before linearized reading' (duration: 146.956508ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:31.682856Z","caller":"traceutil/trace.go:171","msg":"trace[579043461] transaction","detail":"{read_only:false; response_revision:23644; number_of_response:1; }","duration":"143.485175ms","start":"2026-02-24T20:46:31.539358Z","end":"2026-02-24T20:46:31.682843Z","steps":["trace[579043461] 'process raft request' (duration: 143.018193ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:31.682996Z","caller":"traceutil/trace.go:171","msg":"trace[1221405000] transaction","detail":"{read_only:false; response_revision:23645; number_of_response:1; }","duration":"142.982691ms","start":"2026-02-24T20:46:31.539991Z","end":"2026-02-24T20:46:31.682973Z","steps":["trace[1221405000] 'process raft request' (duration: 142.694473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.086008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.704495ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-02-24T20:46:32.086102Z","caller":"traceutil/trace.go:171","msg":"trace[1346069653] linearizableReadLoop","detail":"{readStateIndex:24454; appliedIndex:24453; }","duration":"122.790119ms","start":"2026-02-24T20:46:31.963294Z","end":"2026-02-24T20:46:32.086084Z","steps":["trace[1346069653] 'read index received' (duration: 19.003731ms)","trace[1346069653] 'applied index is now lower than readState.Index' (duration: 103.784578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:32.086186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.889711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:32.086213Z","caller":"traceutil/trace.go:171","msg":"trace[1280779640] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:23645; }","duration":"123.691212ms","start":"2026-02-24T20:46:31.962513Z","end":"2026-02-24T20:46:32.086204Z","steps":["trace[1280779640] 'agreement among raft nodes before linearized reading' (duration: 123.61613ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:32.232946Z","caller":"traceutil/trace.go:171","msg":"trace[1837112581] transaction","detail":"{read_only:false; response_revision:23646; number_of_response:1; }","duration":"161.994602ms","start":"2026-02-24T20:46:32.070932Z","end":"2026-02-24T20:46:32.232927Z","steps":["trace[1837112581] 'process raft request' (duration: 161.850958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.487075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.68556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:32.487327Z","caller":"traceutil/trace.go:171","msg":"trace[301247168] linearizableReadLoop","detail":"{readStateIndex:24457; appliedIndex:24454; }","duration":"263.909289ms","start":"2026-02-24T20:46:32.223396Z","end":"2026-02-24T20:46:32.487305Z","steps":["trace[301247168] 'read index received' (duration: 9.342821ms)","trace[301247168] 'applied index is now lower than readState.Index' (duration: 254.565658ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:32.487461Z","caller":"traceutil/trace.go:171","msg":"trace[1016037738] transaction","detail":"{read_only:false; response_revision:23648; number_of_response:1; }","duration":"292.110986ms","start":"2026-02-24T20:46:32.195331Z","end":"2026-02-24T20:46:32.487442Z","steps":["trace[1016037738] 'process raft request' (duration: 291.919501ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:32.487409Z","caller":"traceutil/trace.go:171","msg":"trace[667351234] transaction","detail":"{read_only:false; response_revision:23647; number_of_response:1; }","duration":"294.324935ms","start":"2026-02-24T20:46:32.193063Z","end":"2026-02-24T20:46:32.487388Z","steps":["trace[667351234] 'process raft request' (duration: 166.228934ms)","trace[667351234] 'compare' (duration: 127.573648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:32.487655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.118236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:32.488055Z","caller":"traceutil/trace.go:171","msg":"trace[1489134144] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:23648; }","duration":"222.514826ms","start":"2026-02-24T20:46:32.265475Z","end":"2026-02-24T20:46:32.48799Z","steps":["trace[1489134144] 'agreement among raft nodes before linearized reading' (duration: 222.084815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.48765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.240678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-02-24T20:46:32.48811Z","caller":"traceutil/trace.go:171","msg":"trace[1540641580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23648; }","duration":"264.756971ms","start":"2026-02-24T20:46:32.223344Z","end":"2026-02-24T20:46:32.488101Z","steps":["trace[1540641580] 'agreement among raft nodes before linearized reading' (duration: 264.131495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.48777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.485873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-02-24T20:46:32.488207Z","caller":"traceutil/trace.go:171","msg":"trace[1144931261] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:23648; }","duration":"150.961875ms","start":"2026-02-24T20:46:32.337237Z","end":"2026-02-24T20:46:32.488199Z","steps":["trace[1144931261] 'agreement among raft nodes before linearized reading' (duration: 150.481882ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:32.641704Z","caller":"traceutil/trace.go:171","msg":"trace[1766443592] transaction","detail":"{read_only:false; response_revision:23649; number_of_response:1; }","duration":"149.393853ms","start":"2026-02-24T20:46:32.492278Z","end":"2026-02-24T20:46:32.641671Z","steps":["trace[1766443592] 'process raft request' (duration: 138.87747ms)","trace[1766443592] 'compare' (duration: 10.352978ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:32.839887Z","caller":"traceutil/trace.go:171","msg":"trace[23125299] transaction","detail":"{read_only:false; response_revision:23650; number_of_response:1; }","duration":"347.497225ms","start":"2026-02-24T20:46:32.492372Z","end":"2026-02-24T20:46:32.839869Z","steps":["trace[23125299] 'process raft request' (duration: 313.310877ms)","trace[23125299] 'compare' (duration: 33.924241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:32.839989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:32.492343Z","time spent":"347.593326ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:32.840153Z","caller":"traceutil/trace.go:171","msg":"trace[1059689187] transaction","detail":"{read_only:false; response_revision:23651; number_of_response:1; }","duration":"347.061493ms","start":"2026-02-24T20:46:32.493068Z","end":"2026-02-24T20:46:32.84013Z","steps":["trace[1059689187] 'process raft request' (duration: 346.767085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.840286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:32.493058Z","time spent":"347.159325ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:32.840298Z","caller":"traceutil/trace.go:171","msg":"trace[40570000] linearizableReadLoop","detail":"{readStateIndex:24461; appliedIndex:24458; }","duration":"129.965481ms","start":"2026-02-24T20:46:32.710307Z","end":"2026-02-24T20:46:32.840272Z","steps":["trace[40570000] 'read index received' (duration: 95.273009ms)","trace[40570000] 'applied index is now lower than readState.Index' (duration: 34.691332ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:32.840393Z","caller":"traceutil/trace.go:171","msg":"trace[1016721115] transaction","detail":"{read_only:false; response_revision:23652; number_of_response:1; }","duration":"339.685724ms","start":"2026-02-24T20:46:32.500681Z","end":"2026-02-24T20:46:32.840367Z","steps":["trace[1016721115] 'process raft request' (duration: 339.354005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.840428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.129326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:32.840465Z","caller":"traceutil/trace.go:171","msg":"trace[814116575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23652; }","duration":"130.201448ms","start":"2026-02-24T20:46:32.710253Z","end":"2026-02-24T20:46:32.840455Z","steps":["trace[814116575] 'agreement among raft nodes before linearized reading' (duration: 130.146137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.840466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.954122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:32.840505Z","caller":"traceutil/trace.go:171","msg":"trace[222961243] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:23652; }","duration":"120.021844ms","start":"2026-02-24T20:46:32.72047Z","end":"2026-02-24T20:46:32.840492Z","steps":["trace[222961243] 'agreement among raft nodes before linearized reading' (duration: 119.940782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:32.840531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:32.500666Z","time spent":"339.791827ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:33.889731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.801077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-02-24T20:46:33.889766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.257163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-02-24T20:46:33.889821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.256804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-02-24T20:46:33.88986Z","caller":"traceutil/trace.go:171","msg":"trace[1671200142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23655; }","duration":"202.408938ms","start":"2026-02-24T20:46:33.687432Z","end":"2026-02-24T20:46:33.889841Z","steps":["trace[1671200142] 'range keys from in-memory index tree' (duration: 202.176061ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:33.889891Z","caller":"traceutil/trace.go:171","msg":"trace[1187595526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23655; }","duration":"202.372467ms","start":"2026-02-24T20:46:33.687501Z","end":"2026-02-24T20:46:33.889873Z","steps":["trace[1187595526] 'range keys from in-memory index tree' (duration: 202.164171ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:33.889811Z","caller":"traceutil/trace.go:171","msg":"trace[1186468628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23655; }","duration":"202.935051ms","start":"2026-02-24T20:46:33.686856Z","end":"2026-02-24T20:46:33.889791Z","steps":["trace[1186468628] 'range keys from in-memory index tree' (duration: 202.680834ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:34.073675Z","caller":"traceutil/trace.go:171","msg":"trace[621102931] transaction","detail":"{read_only:false; response_revision:23656; number_of_response:1; }","duration":"178.820444ms","start":"2026-02-24T20:46:33.894829Z","end":"2026-02-24T20:46:34.073649Z","steps":["trace[621102931] 'process raft request' (duration: 178.640819ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:34.167998Z","caller":"traceutil/trace.go:171","msg":"trace[100104403] linearizableReadLoop","detail":"{readStateIndex:24467; appliedIndex:24464; }","duration":"112.361159ms","start":"2026-02-24T20:46:34.055612Z","end":"2026-02-24T20:46:34.167974Z","steps":["trace[100104403] 'read index received' (duration: 17.85505ms)","trace[100104403] 'applied index is now lower than readState.Index' (duration: 94.505099ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:34.168052Z","caller":"traceutil/trace.go:171","msg":"trace[256136746] transaction","detail":"{read_only:false; response_revision:23658; number_of_response:1; }","duration":"272.939722ms","start":"2026-02-24T20:46:33.895083Z","end":"2026-02-24T20:46:34.168022Z","steps":["trace[256136746] 'process raft request' (duration: 272.826349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:34.168125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.497172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:46:34.168171Z","caller":"traceutil/trace.go:171","msg":"trace[1561145408] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:23658; }","duration":"112.596375ms","start":"2026-02-24T20:46:34.05556Z","end":"2026-02-24T20:46:34.168156Z","steps":["trace[1561145408] 'agreement among raft nodes before linearized reading' (duration: 112.510722ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:34.168041Z","caller":"traceutil/trace.go:171","msg":"trace[2055989115] transaction","detail":"{read_only:false; response_revision:23657; number_of_response:1; }","duration":"272.921781ms","start":"2026-02-24T20:46:33.895081Z","end":"2026-02-24T20:46:34.168003Z","steps":["trace[2055989115] 'process raft request' (duration: 272.447309ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:34.41673Z","caller":"traceutil/trace.go:171","msg":"trace[727560716] transaction","detail":"{read_only:false; response_revision:23659; number_of_response:1; }","duration":"178.672639ms","start":"2026-02-24T20:46:34.238007Z","end":"2026-02-24T20:46:34.41668Z","steps":["trace[727560716] 'process raft request' (duration: 101.443474ms)","trace[727560716] 'compare' (duration: 76.833404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:35.14563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540884119975907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T20:46:35.204978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.599263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:35.205118Z","caller":"traceutil/trace.go:171","msg":"trace[910410710] transaction","detail":"{read_only:false; response_revision:23660; number_of_response:1; }","duration":"712.989622ms","start":"2026-02-24T20:46:34.492102Z","end":"2026-02-24T20:46:35.205092Z","steps":["trace[910410710] 'process raft request' (duration: 144.172733ms)","trace[910410710] 'compare' (duration: 568.400288ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:35.205217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.49208Z","time spent":"713.091054ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:35.255323Z","caller":"traceutil/trace.go:171","msg":"trace[763355209] transaction","detail":"{read_only:false; response_revision:23661; number_of_response:1; }","duration":"761.460084ms","start":"2026-02-24T20:46:34.493838Z","end":"2026-02-24T20:46:35.255298Z","steps":["trace[763355209] 'process raft request' (duration: 761.343491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.255475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.493812Z","time spent":"761.582937ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:35.566352Z","caller":"traceutil/trace.go:171","msg":"trace[1727154122] transaction","detail":"{read_only:false; response_revision:23662; number_of_response:1; }","duration":"789.278991ms","start":"2026-02-24T20:46:34.777047Z","end":"2026-02-24T20:46:35.566326Z","steps":["trace[1727154122] 'process raft request' (duration: 771.610687ms)","trace[1727154122] 'compare' (duration: 17.341175ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:35.566494Z","caller":"traceutil/trace.go:171","msg":"trace[773504801] linearizableReadLoop","detail":"{readStateIndex:24472; appliedIndex:24469; }","duration":"921.302977ms","start":"2026-02-24T20:46:34.645178Z","end":"2026-02-24T20:46:35.566481Z","steps":["trace[773504801] 'read index received' (duration: 610.079618ms)","trace[773504801] 'applied index is now lower than readState.Index' (duration: 311.222619ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:35.566548Z","caller":"traceutil/trace.go:171","msg":"trace[818897471] transaction","detail":"{read_only:false; response_revision:23663; number_of_response:1; }","duration":"720.229176ms","start":"2026-02-24T20:46:34.846311Z","end":"2026-02-24T20:46:35.56654Z","steps":["trace[818897471] 'process raft request' (duration: 719.974359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.56663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.846295Z","time spent":"720.272998ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:35.566742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.553284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T20:46:35.566861Z","caller":"traceutil/trace.go:171","msg":"trace[2050304121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23663; }","duration":"921.720359ms","start":"2026-02-24T20:46:34.645121Z","end":"2026-02-24T20:46:35.566841Z","steps":["trace[2050304121] 'agreement among raft nodes before linearized reading' (duration: 921.489523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:35.566876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.777021Z","time spent":"789.407225ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:35.566904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.645099Z","time spent":"921.79642ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T20:46:36.081404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.02307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:36.081651Z","caller":"traceutil/trace.go:171","msg":"trace[936867814] linearizableReadLoop","detail":"{readStateIndex:24474; appliedIndex:24472; }","duration":"515.119767ms","start":"2026-02-24T20:46:35.566514Z","end":"2026-02-24T20:46:36.081634Z","steps":["trace[936867814] 'read index received' (duration: 109.780629ms)","trace[936867814] 'applied index is now lower than readState.Index' (duration: 405.338248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:36.081732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.477661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:36.081784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136096257s","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:6"} {"level":"warn","ts":"2026-02-24T20:46:36.081812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237566703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-02-24T20:46:36.081836Z","caller":"traceutil/trace.go:171","msg":"trace[1223180460] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:23666; }","duration":"1.13620346s","start":"2026-02-24T20:46:34.945619Z","end":"2026-02-24T20:46:36.081822Z","steps":["trace[1223180460] 'agreement among raft nodes before linearized reading' (duration: 1.136101947s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.081805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37297575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:36.081847Z","caller":"traceutil/trace.go:171","msg":"trace[674356092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23666; }","duration":"1.237645595s","start":"2026-02-24T20:46:34.844191Z","end":"2026-02-24T20:46:36.081836Z","steps":["trace[674356092] 'agreement among raft nodes before linearized reading' (duration: 1.237531822s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.081864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.945596Z","time spent":"1.136262352s","remote":"127.0.0.1:34026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:46:36.08186Z","caller":"traceutil/trace.go:171","msg":"trace[772638198] transaction","detail":"{read_only:false; response_revision:23666; number_of_response:1; }","duration":"510.507823ms","start":"2026-02-24T20:46:35.57134Z","end":"2026-02-24T20:46:36.081848Z","steps":["trace[772638198] 'process raft request' (duration: 510.250186ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:36.081877Z","caller":"traceutil/trace.go:171","msg":"trace[47244099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23666; }","duration":"1.373090113s","start":"2026-02-24T20:46:34.708771Z","end":"2026-02-24T20:46:36.081861Z","steps":["trace[47244099] 'agreement among raft nodes before linearized reading' (duration: 1.37297586s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.081885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.844166Z","time spent":"1.237708816s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T20:46:36.081898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29925216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-02-24T20:46:36.081911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.708725Z","time spent":"1.373180785s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:46:36.081881Z","caller":"traceutil/trace.go:171","msg":"trace[1347575781] transaction","detail":"{read_only:false; response_revision:23665; number_of_response:1; }","duration":"532.633737ms","start":"2026-02-24T20:46:35.549192Z","end":"2026-02-24T20:46:36.081826Z","steps":["trace[1347575781] 'process raft request' (duration: 532.341389ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:36.08191Z","caller":"traceutil/trace.go:171","msg":"trace[768084934] transaction","detail":"{read_only:false; response_revision:23664; number_of_response:1; }","duration":"776.082906ms","start":"2026-02-24T20:46:35.305782Z","end":"2026-02-24T20:46:36.081865Z","steps":["trace[768084934] 'process raft request' (duration: 370.521352ms)","trace[768084934] 'compare' (duration: 404.202758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:36.081964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.041325ms","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:6"} {"level":"warn","ts":"2026-02-24T20:46:36.081979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23672491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-02-24T20:46:36.082009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.686185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:36.082019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.126992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:36.082042Z","caller":"traceutil/trace.go:171","msg":"trace[1429945364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23666; }","duration":"157.756747ms","start":"2026-02-24T20:46:35.924277Z","end":"2026-02-24T20:46:36.082033Z","steps":["trace[1429945364] 'agreement among raft nodes before linearized reading' (duration: 157.712225ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:36.082032Z","caller":"traceutil/trace.go:171","msg":"trace[1430501310] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:23666; }","duration":"206.149347ms","start":"2026-02-24T20:46:35.875863Z","end":"2026-02-24T20:46:36.082012Z","steps":["trace[1430501310] 'agreement among raft nodes before linearized reading' (duration: 206.049674ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:36.082048Z","caller":"traceutil/trace.go:171","msg":"trace[1657715797] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23666; }","duration":"1.236871174s","start":"2026-02-24T20:46:34.845158Z","end":"2026-02-24T20:46:36.08203Z","steps":["trace[1657715797] 'agreement among raft nodes before linearized reading' (duration: 1.236521514s)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:36.082053Z","caller":"traceutil/trace.go:171","msg":"trace[1269814737] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:23666; }","duration":"437.191943ms","start":"2026-02-24T20:46:35.644851Z","end":"2026-02-24T20:46:36.082043Z","steps":["trace[1269814737] 'agreement among raft nodes before linearized reading' (duration: 437.140552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.082073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.648383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:36.082096Z","caller":"traceutil/trace.go:171","msg":"trace[2077199922] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:23666; }","duration":"454.690353ms","start":"2026-02-24T20:46:35.627398Z","end":"2026-02-24T20:46:36.082088Z","steps":["trace[2077199922] 'agreement among raft nodes before linearized reading' (duration: 454.655152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.082092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.644833Z","time spent":"437.251695ms","remote":"127.0.0.1:44462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-02-24T20:46:36.082112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.627388Z","time spent":"454.719944ms","remote":"127.0.0.1:44508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:46:36.082094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.845137Z","time spent":"1.236947776s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-24T20:46:36.082114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.549169Z","time spent":"532.839382ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:36.081947Z","caller":"traceutil/trace.go:171","msg":"trace[73680869] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:23666; }","duration":"1.299341052s","start":"2026-02-24T20:46:34.782592Z","end":"2026-02-24T20:46:36.081933Z","steps":["trace[73680869] 'agreement among raft nodes before linearized reading' (duration: 1.2992615s)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:36.081808Z","caller":"traceutil/trace.go:171","msg":"trace[1219392995] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:23666; }","duration":"966.602634ms","start":"2026-02-24T20:46:35.115184Z","end":"2026-02-24T20:46:36.081786Z","steps":["trace[1219392995] 'agreement among raft nodes before linearized reading' (duration: 966.471041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.082174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:34.78257Z","time spent":"1.299589259s","remote":"127.0.0.1:58872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":32,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:46:36.082115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.305761Z","time spent":"776.253241ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:36.082182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.115161Z","time spent":"967.004735ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:46:36.081948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.571325Z","time spent":"510.566204ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:36.082305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.996655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:36.082376Z","caller":"traceutil/trace.go:171","msg":"trace[309424356] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:23666; }","duration":"457.113008ms","start":"2026-02-24T20:46:35.625245Z","end":"2026-02-24T20:46:36.082359Z","steps":["trace[309424356] 'agreement among raft nodes before linearized reading' (duration: 456.622575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.082418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:35.625218Z","time spent":"457.189741ms","remote":"127.0.0.1:49124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:46:36.431776Z","caller":"traceutil/trace.go:171","msg":"trace[723175125] transaction","detail":"{read_only:false; response_revision:23667; number_of_response:1; }","duration":"344.35452ms","start":"2026-02-24T20:46:36.087382Z","end":"2026-02-24T20:46:36.431737Z","steps":["trace[723175125] 'process raft request' (duration: 334.580167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.432001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.087367Z","time spent":"344.510834ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:36.437001Z","caller":"traceutil/trace.go:171","msg":"trace[1280330429] transaction","detail":"{read_only:false; response_revision:23668; number_of_response:1; }","duration":"349.503858ms","start":"2026-02-24T20:46:36.087463Z","end":"2026-02-24T20:46:36.436967Z","steps":["trace[1280330429] 'process raft request' (duration: 349.266812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.437184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.08745Z","time spent":"349.635362ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:36.437263Z","caller":"traceutil/trace.go:171","msg":"trace[1628981880] transaction","detail":"{read_only:false; response_revision:23669; number_of_response:1; }","duration":"348.333786ms","start":"2026-02-24T20:46:36.088905Z","end":"2026-02-24T20:46:36.437238Z","steps":["trace[1628981880] 'process raft request' (duration: 348.019258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.437355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.088898Z","time spent":"348.412238ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:36.43755Z","caller":"traceutil/trace.go:171","msg":"trace[377178889] linearizableReadLoop","detail":"{readStateIndex:24477; appliedIndex:24475; }","duration":"349.429646ms","start":"2026-02-24T20:46:36.088096Z","end":"2026-02-24T20:46:36.437525Z","steps":["trace[377178889] 'read index received' (duration: 333.773605ms)","trace[377178889] 'applied index is now lower than readState.Index' (duration: 15.654711ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:36.437604Z","caller":"traceutil/trace.go:171","msg":"trace[1439514167] transaction","detail":"{read_only:false; response_revision:23670; number_of_response:1; }","duration":"328.520574ms","start":"2026-02-24T20:46:36.10907Z","end":"2026-02-24T20:46:36.437591Z","steps":["trace[1439514167] 'process raft request' (duration: 328.150964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.43767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.109043Z","time spent":"328.590186ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:36.437792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.675333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:36.43791Z","caller":"traceutil/trace.go:171","msg":"trace[1208738433] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:23670; }","duration":"349.763865ms","start":"2026-02-24T20:46:36.08806Z","end":"2026-02-24T20:46:36.437824Z","steps":["trace[1208738433] 'agreement among raft nodes before linearized reading' (duration: 349.651742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.43801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.088049Z","time spent":"349.945751ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-02-24T20:46:36.514493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.422727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-02-24T20:46:36.51454Z","caller":"traceutil/trace.go:171","msg":"trace[289527232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23671; }","duration":"342.51894ms","start":"2026-02-24T20:46:36.172011Z","end":"2026-02-24T20:46:36.51453Z","steps":["trace[289527232] 'agreement among raft nodes before linearized reading' (duration: 342.339205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.514567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.171984Z","time spent":"342.576472ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T20:46:36.514618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.620683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-02-24T20:46:36.514715Z","caller":"traceutil/trace.go:171","msg":"trace[832913007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23671; }","duration":"342.763938ms","start":"2026-02-24T20:46:36.171928Z","end":"2026-02-24T20:46:36.514692Z","steps":["trace[832913007] 'agreement among raft nodes before linearized reading' (duration: 342.448029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.514783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.171915Z","time spent":"342.85384ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T20:46:36.514873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.2071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-24T20:46:36.514934Z","caller":"traceutil/trace.go:171","msg":"trace[1960323799] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:23671; }","duration":"416.290852ms","start":"2026-02-24T20:46:36.098628Z","end":"2026-02-24T20:46:36.514919Z","steps":["trace[1960323799] 'agreement among raft nodes before linearized reading' (duration: 416.105587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:36.514975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.09862Z","time spent":"416.345263ms","remote":"127.0.0.1:37050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":115,"response size":32,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T20:46:36.725479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.436409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:36.725585Z","caller":"traceutil/trace.go:171","msg":"trace[1553606371] transaction","detail":"{read_only:false; response_revision:23672; number_of_response:1; }","duration":"207.054502ms","start":"2026-02-24T20:46:36.518512Z","end":"2026-02-24T20:46:36.725566Z","steps":["trace[1553606371] 'process raft request' (duration: 68.450518ms)","trace[1553606371] 'compare' (duration: 138.334976ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:37.0719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.668255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:37.072042Z","caller":"traceutil/trace.go:171","msg":"trace[222428927] linearizableReadLoop","detail":"{readStateIndex:24482; appliedIndex:24480; }","duration":"551.516514ms","start":"2026-02-24T20:46:36.520501Z","end":"2026-02-24T20:46:37.072017Z","steps":["trace[222428927] 'read index received' (duration: 66.427904ms)","trace[222428927] 'applied index is now lower than readState.Index' (duration: 485.08697ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:37.072156Z","caller":"traceutil/trace.go:171","msg":"trace[389556280] transaction","detail":"{read_only:false; response_revision:23673; number_of_response:1; }","duration":"553.171849ms","start":"2026-02-24T20:46:36.518968Z","end":"2026-02-24T20:46:37.07214Z","steps":["trace[389556280] 'process raft request' (duration: 312.142934ms)","trace[389556280] 'compare' (duration: 240.461159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:37.072229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.518958Z","time spent":"553.237152ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:37.072519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.885736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-02-24T20:46:37.072568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.230284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-02-24T20:46:37.072569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.037999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:37.072678Z","caller":"traceutil/trace.go:171","msg":"trace[611243921] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:23673; }","duration":"552.173522ms","start":"2026-02-24T20:46:36.52048Z","end":"2026-02-24T20:46:37.072653Z","steps":["trace[611243921] 'agreement among raft nodes before linearized reading' (duration: 551.992087ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:37.072615Z","caller":"traceutil/trace.go:171","msg":"trace[1342256180] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23673; }","duration":"246.271665ms","start":"2026-02-24T20:46:36.826323Z","end":"2026-02-24T20:46:37.072595Z","steps":["trace[1342256180] 'agreement among raft nodes before linearized reading' (duration: 246.153082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:37.072744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.520471Z","time spent":"552.257455ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-02-24T20:46:37.072604Z","caller":"traceutil/trace.go:171","msg":"trace[665193895] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:23673; }","duration":"443.02718ms","start":"2026-02-24T20:46:36.629556Z","end":"2026-02-24T20:46:37.072583Z","steps":["trace[665193895] 'agreement among raft nodes before linearized reading' (duration: 442.779183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:37.073023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:36.629534Z","time spent":"443.38741ms","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":164,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"info","ts":"2026-02-24T20:46:37.354045Z","caller":"traceutil/trace.go:171","msg":"trace[937924493] transaction","detail":"{read_only:false; response_revision:23674; number_of_response:1; }","duration":"276.108456ms","start":"2026-02-24T20:46:37.077912Z","end":"2026-02-24T20:46:37.354021Z","steps":["trace[937924493] 'process raft request' (duration: 252.78776ms)","trace[937924493] 'compare' (duration: 23.09696ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:37.413566Z","caller":"traceutil/trace.go:171","msg":"trace[447053720] linearizableReadLoop","detail":"{readStateIndex:24485; appliedIndex:24483; }","duration":"221.707815ms","start":"2026-02-24T20:46:37.191842Z","end":"2026-02-24T20:46:37.413549Z","steps":["trace[447053720] 'read index received' (duration: 138.87268ms)","trace[447053720] 'applied index is now lower than readState.Index' (duration: 82.834505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:37.413711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.870659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:37.413694Z","caller":"traceutil/trace.go:171","msg":"trace[272213427] transaction","detail":"{read_only:false; response_revision:23677; number_of_response:1; }","duration":"152.187918ms","start":"2026-02-24T20:46:37.261473Z","end":"2026-02-24T20:46:37.413661Z","steps":["trace[272213427] 'process raft request' (duration: 152.051104ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:37.413742Z","caller":"traceutil/trace.go:171","msg":"trace[1984832709] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:23677; }","duration":"221.993673ms","start":"2026-02-24T20:46:37.191737Z","end":"2026-02-24T20:46:37.413731Z","steps":["trace[1984832709] 'agreement among raft nodes before linearized reading' (duration: 221.89716ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:37.413758Z","caller":"traceutil/trace.go:171","msg":"trace[1943078232] transaction","detail":"{read_only:false; response_revision:23676; number_of_response:1; }","duration":"202.450937ms","start":"2026-02-24T20:46:37.211261Z","end":"2026-02-24T20:46:37.413712Z","steps":["trace[1943078232] 'process raft request' (duration: 202.233841ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:37.413779Z","caller":"traceutil/trace.go:171","msg":"trace[280145978] transaction","detail":"{read_only:false; response_revision:23675; number_of_response:1; }","duration":"321.146916ms","start":"2026-02-24T20:46:37.092599Z","end":"2026-02-24T20:46:37.413746Z","steps":["trace[280145978] 'process raft request' (duration: 320.793437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:37.413984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:37.092506Z","time spent":"321.405834ms","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:38.145463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.729417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:38.145544Z","caller":"traceutil/trace.go:171","msg":"trace[1670177395] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:23678; }","duration":"246.855081ms","start":"2026-02-24T20:46:37.89867Z","end":"2026-02-24T20:46:38.145525Z","steps":["trace[1670177395] 'count revisions from in-memory index tree' (duration: 246.669386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.145758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.288753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:38.145897Z","caller":"traceutil/trace.go:171","msg":"trace[758227539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23678; }","duration":"435.478288ms","start":"2026-02-24T20:46:37.710386Z","end":"2026-02-24T20:46:38.145864Z","steps":["trace[758227539] 'range keys from in-memory index tree' (duration: 435.144299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.145949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:37.710367Z","time spent":"435.570711ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:46:38.328482Z","caller":"traceutil/trace.go:171","msg":"trace[2105382246] transaction","detail":"{read_only:false; response_revision:23679; number_of_response:1; }","duration":"239.440683ms","start":"2026-02-24T20:46:38.088765Z","end":"2026-02-24T20:46:38.328206Z","steps":["trace[2105382246] 'process raft request' (duration: 239.231937ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:38.488947Z","caller":"traceutil/trace.go:171","msg":"trace[1903402731] transaction","detail":"{read_only:false; response_revision:23680; number_of_response:1; }","duration":"398.626617ms","start":"2026-02-24T20:46:38.090297Z","end":"2026-02-24T20:46:38.488924Z","steps":["trace[1903402731] 'process raft request' (duration: 398.327239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.489054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.090264Z","time spent":"398.73431ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:38.48927Z","caller":"traceutil/trace.go:171","msg":"trace[548935265] transaction","detail":"{read_only:false; response_revision:23681; number_of_response:1; }","duration":"380.231582ms","start":"2026-02-24T20:46:38.10903Z","end":"2026-02-24T20:46:38.489261Z","steps":["trace[548935265] 'process raft request' (duration: 379.903174ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:38.489248Z","caller":"traceutil/trace.go:171","msg":"trace[1827841402] linearizableReadLoop","detail":"{readStateIndex:24491; appliedIndex:24489; }","duration":"342.184941ms","start":"2026-02-24T20:46:38.14704Z","end":"2026-02-24T20:46:38.489225Z","steps":["trace[1827841402] 'read index received' (duration: 181.359532ms)","trace[1827841402] 'applied index is now lower than readState.Index' (duration: 160.824559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:38.48931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.108984Z","time spent":"380.306775ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418314,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:38.489356Z","caller":"traceutil/trace.go:171","msg":"trace[2036260032] transaction","detail":"{read_only:false; response_revision:23682; number_of_response:1; }","duration":"338.683066ms","start":"2026-02-24T20:46:38.150653Z","end":"2026-02-24T20:46:38.489336Z","steps":["trace[2036260032] 'process raft request' (duration: 338.525302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.489439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.406057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-02-24T20:46:38.489456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.150625Z","time spent":"338.76742ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:38.489469Z","caller":"traceutil/trace.go:171","msg":"trace[26367376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23682; }","duration":"342.467578ms","start":"2026-02-24T20:46:38.146992Z","end":"2026-02-24T20:46:38.489459Z","steps":["trace[26367376] 'agreement among raft nodes before linearized reading' (duration: 342.358626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.489497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.146963Z","time spent":"342.52848ms","remote":"127.0.0.1:37002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T20:46:38.489512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.329859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12013"} {"level":"info","ts":"2026-02-24T20:46:38.489556Z","caller":"traceutil/trace.go:171","msg":"trace[1032754345] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23682; }","duration":"181.427953ms","start":"2026-02-24T20:46:38.308116Z","end":"2026-02-24T20:46:38.489544Z","steps":["trace[1032754345] 'agreement among raft nodes before linearized reading' (duration: 181.244768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.968653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.859051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:38.968976Z","caller":"traceutil/trace.go:171","msg":"trace[1886407807] transaction","detail":"{read_only:false; response_revision:23684; number_of_response:1; }","duration":"474.805864ms","start":"2026-02-24T20:46:38.49411Z","end":"2026-02-24T20:46:38.968916Z","steps":["trace[1886407807] 'process raft request' (duration: 170.574982ms)","trace[1886407807] 'compare' (duration: 302.977618ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:38.969014Z","caller":"traceutil/trace.go:171","msg":"trace[15714260] transaction","detail":"{read_only:false; response_revision:23685; number_of_response:1; }","duration":"473.848589ms","start":"2026-02-24T20:46:38.495141Z","end":"2026-02-24T20:46:38.96899Z","steps":["trace[15714260] 'process raft request' (duration: 473.663344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.969118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.495129Z","time spent":"473.939811ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:38.969152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.494085Z","time spent":"474.973579ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:38.969206Z","caller":"traceutil/trace.go:171","msg":"trace[1270196175] linearizableReadLoop","detail":"{readStateIndex:24494; appliedIndex:24492; }","duration":"474.980709ms","start":"2026-02-24T20:46:38.494196Z","end":"2026-02-24T20:46:38.969177Z","steps":["trace[1270196175] 'read index received' (duration: 58.748868ms)","trace[1270196175] 'applied index is now lower than readState.Index' (duration: 416.230541ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:38.969343Z","caller":"traceutil/trace.go:171","msg":"trace[2019957832] transaction","detail":"{read_only:false; response_revision:23686; number_of_response:1; }","duration":"448.525188ms","start":"2026-02-24T20:46:38.520803Z","end":"2026-02-24T20:46:38.969329Z","steps":["trace[2019957832] 'process raft request' (duration: 448.124918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.96948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.7393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-24T20:46:38.96953Z","caller":"traceutil/trace.go:171","msg":"trace[1993457058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23686; }","duration":"239.817072ms","start":"2026-02-24T20:46:38.729699Z","end":"2026-02-24T20:46:38.969517Z","steps":["trace[1993457058] 'agreement among raft nodes before linearized reading' (duration: 239.677668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.969482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.520788Z","time spent":"448.586219ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:38.969781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.576775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16845"} {"level":"info","ts":"2026-02-24T20:46:38.969822Z","caller":"traceutil/trace.go:171","msg":"trace[399920965] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23686; }","duration":"475.629846ms","start":"2026-02-24T20:46:38.494181Z","end":"2026-02-24T20:46:38.96981Z","steps":["trace[399920965] 'agreement among raft nodes before linearized reading' (duration: 475.503593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:38.969859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:38.494169Z","time spent":"475.680327ms","remote":"127.0.0.1:37182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":16869,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-24T20:46:39.456416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.869773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:39.456483Z","caller":"traceutil/trace.go:171","msg":"trace[1418842598] linearizableReadLoop","detail":"{readStateIndex:24498; appliedIndex:24497; }","duration":"405.171944ms","start":"2026-02-24T20:46:39.051301Z","end":"2026-02-24T20:46:39.456473Z","steps":["trace[1418842598] 'read index received' (duration: 114.101215ms)","trace[1418842598] 'applied index is now lower than readState.Index' (duration: 291.069729ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:39.456527Z","caller":"traceutil/trace.go:171","msg":"trace[102716185] transaction","detail":"{read_only:false; response_revision:23688; number_of_response:1; }","duration":"417.614878ms","start":"2026-02-24T20:46:39.038904Z","end":"2026-02-24T20:46:39.456519Z","steps":["trace[102716185] 'process raft request' (duration: 126.56963ms)","trace[102716185] 'compare' (duration: 290.188344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:39.456555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.038873Z","time spent":"417.66634ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:39.456666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.350338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:39.456711Z","caller":"traceutil/trace.go:171","msg":"trace[680936731] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23688; }","duration":"405.438241ms","start":"2026-02-24T20:46:39.051261Z","end":"2026-02-24T20:46:39.456699Z","steps":["trace[680936731] 'agreement among raft nodes before linearized reading' (duration: 405.345238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:39.456735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.051245Z","time spent":"405.486053ms","remote":"127.0.0.1:37426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-02-24T20:46:39.747313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.72749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:39.747415Z","caller":"traceutil/trace.go:171","msg":"trace[5506031] transaction","detail":"{read_only:false; response_revision:23689; number_of_response:1; }","duration":"328.804462ms","start":"2026-02-24T20:46:39.418593Z","end":"2026-02-24T20:46:39.747397Z","steps":["trace[5506031] 'process raft request' (duration: 168.912707ms)","trace[5506031] 'compare' (duration: 158.924589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:39.747472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.418567Z","time spent":"328.877815ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:39.757053Z","caller":"traceutil/trace.go:171","msg":"trace[1314785330] transaction","detail":"{read_only:false; response_revision:23690; number_of_response:1; }","duration":"337.625269ms","start":"2026-02-24T20:46:39.419412Z","end":"2026-02-24T20:46:39.757037Z","steps":["trace[1314785330] 'process raft request' (duration: 337.471205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:39.757136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.419383Z","time spent":"337.705672ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:39.757199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.584312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-24T20:46:39.757247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.935935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:39.757272Z","caller":"traceutil/trace.go:171","msg":"trace[1861309157] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23690; }","duration":"438.695945ms","start":"2026-02-24T20:46:39.31856Z","end":"2026-02-24T20:46:39.757256Z","steps":["trace[1861309157] 'agreement among raft nodes before linearized reading' (duration: 438.552371ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:39.757277Z","caller":"traceutil/trace.go:171","msg":"trace[232212629] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:23690; }","duration":"597.007516ms","start":"2026-02-24T20:46:39.16026Z","end":"2026-02-24T20:46:39.757268Z","steps":["trace[232212629] 'agreement among raft nodes before linearized reading' (duration: 596.952625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:39.757307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.318544Z","time spent":"438.757356ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":557,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-02-24T20:46:39.757309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.16024Z","time spent":"597.061958ms","remote":"127.0.0.1:50084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"info","ts":"2026-02-24T20:46:39.75699Z","caller":"traceutil/trace.go:171","msg":"trace[769060735] linearizableReadLoop","detail":"{readStateIndex:24500; appliedIndex:24498; }","duration":"300.481302ms","start":"2026-02-24T20:46:39.456496Z","end":"2026-02-24T20:46:39.756978Z","steps":["trace[769060735] 'read index received' (duration: 130.949697ms)","trace[769060735] 'applied index is now lower than readState.Index' (duration: 169.530805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:39.757773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.892686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-02-24T20:46:39.757815Z","caller":"traceutil/trace.go:171","msg":"trace[1577329754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23690; }","duration":"681.959429ms","start":"2026-02-24T20:46:39.075846Z","end":"2026-02-24T20:46:39.757805Z","steps":["trace[1577329754] 'agreement among raft nodes before linearized reading' (duration: 681.786344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:39.757851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.075834Z","time spent":"682.00638ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T20:46:40.083304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.0987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:40.083524Z","caller":"traceutil/trace.go:171","msg":"trace[1856607404] transaction","detail":"{read_only:false; response_revision:23693; number_of_response:1; }","duration":"319.77995ms","start":"2026-02-24T20:46:39.763725Z","end":"2026-02-24T20:46:40.083504Z","steps":["trace[1856607404] 'process raft request' (duration: 319.735289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:40.083577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.763692Z","time spent":"319.862912ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:40.083859Z","caller":"traceutil/trace.go:171","msg":"trace[1303838424] transaction","detail":"{read_only:false; response_revision:23691; number_of_response:1; }","duration":"409.721916ms","start":"2026-02-24T20:46:39.674129Z","end":"2026-02-24T20:46:40.08385Z","steps":["trace[1303838424] 'process raft request' (duration: 200.972398ms)","trace[1303838424] 'compare' (duration: 207.881654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:40.083898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.674094Z","time spent":"409.781608ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T20:46:40.083992Z","caller":"traceutil/trace.go:171","msg":"trace[1210515542] linearizableReadLoop","detail":"{readStateIndex:24501; appliedIndex:24500; }","duration":"326.378548ms","start":"2026-02-24T20:46:39.757604Z","end":"2026-02-24T20:46:40.083983Z","steps":["trace[1210515542] 'read index received' (duration: 117.519747ms)","trace[1210515542] 'applied index is now lower than readState.Index' (duration: 208.857201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:40.084044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.4988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:40.084062Z","caller":"traceutil/trace.go:171","msg":"trace[2010177483] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23693; }","duration":"619.516541ms","start":"2026-02-24T20:46:39.464537Z","end":"2026-02-24T20:46:40.084054Z","steps":["trace[2010177483] 'agreement among raft nodes before linearized reading' (duration: 619.47286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:40.084082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.4645Z","time spent":"619.578242ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-02-24T20:46:40.084197Z","caller":"traceutil/trace.go:171","msg":"trace[1406595347] transaction","detail":"{read_only:false; response_revision:23692; number_of_response:1; }","duration":"321.340042ms","start":"2026-02-24T20:46:39.76285Z","end":"2026-02-24T20:46:40.08419Z","steps":["trace[1406595347] 'process raft request' (duration: 320.567511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:40.08423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.762829Z","time spent":"321.381983ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T20:46:40.084369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.058145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:40.084387Z","caller":"traceutil/trace.go:171","msg":"trace[815573235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23693; }","duration":"375.114126ms","start":"2026-02-24T20:46:39.709267Z","end":"2026-02-24T20:46:40.084381Z","steps":["trace[815573235] 'agreement among raft nodes before linearized reading' (duration: 375.079795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:40.084403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T20:46:39.709247Z","time spent":"375.152568ms","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T20:46:40.601252Z","caller":"traceutil/trace.go:171","msg":"trace[1954512229] transaction","detail":"{read_only:false; response_revision:23695; number_of_response:1; }","duration":"106.905011ms","start":"2026-02-24T20:46:40.49432Z","end":"2026-02-24T20:46:40.601225Z","steps":["trace[1954512229] 'process raft request' (duration: 106.752967ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:40.605795Z","caller":"traceutil/trace.go:171","msg":"trace[373978546] transaction","detail":"{read_only:false; response_revision:23696; number_of_response:1; }","duration":"108.497824ms","start":"2026-02-24T20:46:40.49727Z","end":"2026-02-24T20:46:40.605768Z","steps":["trace[373978546] 'process raft request' (duration: 107.932939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:40.900791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.596895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-24T20:46:40.900928Z","caller":"traceutil/trace.go:171","msg":"trace[154641427] linearizableReadLoop","detail":"{readStateIndex:24508; appliedIndex:24507; }","duration":"191.134064ms","start":"2026-02-24T20:46:40.709772Z","end":"2026-02-24T20:46:40.900906Z","steps":["trace[154641427] 'read index received' (duration: 5.116258ms)","trace[154641427] 'applied index is now lower than readState.Index' (duration: 186.016276ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:40.900948Z","caller":"traceutil/trace.go:171","msg":"trace[561109343] transaction","detail":"{read_only:false; response_revision:23698; number_of_response:1; }","duration":"291.916641ms","start":"2026-02-24T20:46:40.608993Z","end":"2026-02-24T20:46:40.90091Z","steps":["trace[561109343] 'process raft request' (duration: 106.068949ms)","trace[561109343] 'compare' (duration: 185.445221ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T20:46:40.901013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.248337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-02-24T20:46:40.90105Z","caller":"traceutil/trace.go:171","msg":"trace[852772293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23698; }","duration":"191.327479ms","start":"2026-02-24T20:46:40.709711Z","end":"2026-02-24T20:46:40.901038Z","steps":["trace[852772293] 'agreement among raft nodes before linearized reading' (duration: 191.252007ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:41.138352Z","caller":"traceutil/trace.go:171","msg":"trace[1915550550] transaction","detail":"{read_only:false; response_revision:23699; number_of_response:1; }","duration":"163.184283ms","start":"2026-02-24T20:46:40.975135Z","end":"2026-02-24T20:46:41.13832Z","steps":["trace[1915550550] 'process raft request' (duration: 68.084059ms)","trace[1915550550] 'compare' (duration: 94.787356ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:41.169341Z","caller":"traceutil/trace.go:171","msg":"trace[1677792447] transaction","detail":"{read_only:false; response_revision:23700; number_of_response:1; }","duration":"191.415001ms","start":"2026-02-24T20:46:40.977903Z","end":"2026-02-24T20:46:41.169318Z","steps":["trace[1677792447] 'process raft request' (duration: 191.251377ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:46:41.169452Z","caller":"traceutil/trace.go:171","msg":"trace[1321161949] linearizableReadLoop","detail":"{readStateIndex:24511; appliedIndex:24509; }","duration":"125.052529ms","start":"2026-02-24T20:46:41.044383Z","end":"2026-02-24T20:46:41.169435Z","steps":["trace[1321161949] 'read index received' (duration: 124.726691ms)","trace[1321161949] 'applied index is now lower than readState.Index' (duration: 324.778µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T20:46:41.16949Z","caller":"traceutil/trace.go:171","msg":"trace[1088259415] transaction","detail":"{read_only:false; response_revision:23701; number_of_response:1; }","duration":"190.486377ms","start":"2026-02-24T20:46:40.978983Z","end":"2026-02-24T20:46:41.169469Z","steps":["trace[1088259415] 'process raft request' (duration: 190.293552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T20:46:41.169573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.179122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-02-24T20:46:41.169594Z","caller":"traceutil/trace.go:171","msg":"trace[652403514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23701; }","duration":"125.211753ms","start":"2026-02-24T20:46:41.044376Z","end":"2026-02-24T20:46:41.169588Z","steps":["trace[652403514] 'agreement among raft nodes before linearized reading' (duration: 125.117311ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:47:51.359716Z","caller":"traceutil/trace.go:171","msg":"trace[491579671] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"170.006966ms","start":"2026-02-24T20:47:51.189677Z","end":"2026-02-24T20:47:51.359684Z","steps":["trace[491579671] 'process raft request' (duration: 169.803981ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T20:48:13.646186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21843} {"level":"info","ts":"2026-02-24T20:48:13.781897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21843,"took":"131.544903ms","hash":4079604367,"current-db-size-bytes":47828992,"current-db-size":"48 MB","current-db-size-in-use-bytes":32182272,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-02-24T20:48:13.781976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4079604367,"revision":21843,"compact-revision":18848} {"level":"info","ts":"2026-02-24T20:53:13.65707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24570} {"level":"info","ts":"2026-02-24T20:53:13.787649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24570,"took":"127.375562ms","hash":3343706205,"current-db-size-bytes":47828992,"current-db-size":"48 MB","current-db-size-in-use-bytes":32649216,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-02-24T20:53:13.787701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3343706205,"revision":24570,"compact-revision":21843} {"level":"info","ts":"2026-02-24T20:57:58.669575Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b66d12fd59101739","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-24T20:57:58.6758Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-02-24T20:57:58.6759Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-02-24T20:58:13.666407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27065} {"level":"info","ts":"2026-02-24T20:58:13.78865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27065,"took":"117.765494ms","hash":948224023,"current-db-size-bytes":47828992,"current-db-size":"48 MB","current-db-size-in-use-bytes":27791360,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-24T20:58:13.788727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":948224023,"revision":27065,"compact-revision":24570} {"level":"info","ts":"2026-02-24T21:03:13.673233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29136} {"level":"info","ts":"2026-02-24T21:03:13.784691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29136,"took":"108.134925ms","hash":671545553,"current-db-size-bytes":47828992,"current-db-size":"48 MB","current-db-size-in-use-bytes":28975104,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-24T21:03:13.784759Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":671545553,"revision":29136,"compact-revision":27065} {"level":"info","ts":"2026-02-24T21:08:13.679033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31382} {"level":"info","ts":"2026-02-24T21:08:13.827147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31382,"took":"143.789964ms","hash":2784029959,"current-db-size-bytes":47828992,"current-db-size":"48 MB","current-db-size-in-use-bytes":29376512,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-02-24T21:08:13.827212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2784029959,"revision":31382,"compact-revision":29136} {"level":"info","ts":"2026-02-24T21:12:38.009394Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008e9b.wal"} {"level":"info","ts":"2026-02-24T21:13:13.685636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33497} {"level":"info","ts":"2026-02-24T21:13:13.812948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33497,"took":"122.758619ms","hash":2323430355,"current-db-size-bytes":49975296,"current-db-size":"50 MB","current-db-size-in-use-bytes":29745152,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-02-24T21:13:13.813012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2323430355,"revision":33497,"compact-revision":31382}