{"level":"warn","ts":"2026-10-27T20:30:25.052607Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T20:30:25.052742Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.166: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.166:2380","--initial-cluster=instance=https://162.253.55.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.166:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-27T20:30:25.052828Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T20:30:25.052846Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-10-27T20:30:25.05287Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-27T20:30:25.053688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"]} {"level":"info","ts":"2026-10-27T20:30:25.053793Z","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.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.166:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-27T20:30:25.056754Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.688409ms"} {"level":"info","ts":"2026-10-27T20:30:25.068975Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7481851e1bcdf3c","cluster-id":"bb367d182eb83f54"} {"level":"info","ts":"2026-10-27T20:30:25.069079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=()"} {"level":"info","ts":"2026-10-27T20:30:25.069119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 0"} {"level":"info","ts":"2026-10-27T20:30:25.069139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7481851e1bcdf3c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-27T20:30:25.069161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became follower at term 1"} {"level":"info","ts":"2026-10-27T20:30:25.069237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"warn","ts":"2026-10-27T20:30:25.075089Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-27T20:30:25.076985Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-27T20:30:25.078465Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-27T20:30:25.082258Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7481851e1bcdf3c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-27T20:30:25.082838Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7481851e1bcdf3c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-27T20:30:25.083059Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-27T20:30:25.083268Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-27T20:30:25.083284Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-27T20:30:25.083884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c switched to configuration voters=(13206832647222648636)"} {"level":"info","ts":"2026-10-27T20:30:25.084036Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","added-peer-id":"b7481851e1bcdf3c","added-peer-peer-urls":["https://162.253.55.166:2380"]} {"level":"info","ts":"2026-10-27T20:30:25.091462Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-27T20:30:25.091642Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-10-27T20:30:25.091696Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.166:2380"} {"level":"info","ts":"2026-10-27T20:30:25.092103Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7481851e1bcdf3c","initial-advertise-peer-urls":["https://162.253.55.166:2380"],"listen-peer-urls":["https://162.253.55.166:2380"],"advertise-client-urls":["https://162.253.55.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-27T20:30:25.092154Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-27T20:30:26.069914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c is starting a new election at term 1"} {"level":"info","ts":"2026-10-27T20:30:26.069983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became pre-candidate at term 1"} {"level":"info","ts":"2026-10-27T20:30:26.070008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgPreVoteResp from b7481851e1bcdf3c at term 1"} {"level":"info","ts":"2026-10-27T20:30:26.07003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became candidate at term 2"} {"level":"info","ts":"2026-10-27T20:30:26.070061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c received MsgVoteResp from b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-10-27T20:30:26.070077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7481851e1bcdf3c became leader at term 2"} {"level":"info","ts":"2026-10-27T20:30:26.070101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7481851e1bcdf3c elected leader b7481851e1bcdf3c at term 2"} {"level":"info","ts":"2026-10-27T20:30:26.071578Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7481851e1bcdf3c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.166:2379]}","request-path":"/0/members/b7481851e1bcdf3c/attributes","cluster-id":"bb367d182eb83f54","publish-timeout":"7s"} {"level":"info","ts":"2026-10-27T20:30:26.071643Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T20:30:26.071862Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T20:30:26.072211Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-27T20:30:26.072243Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-27T20:30:26.07516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.166:2379"} {"level":"info","ts":"2026-10-27T20:30:26.075337Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T20:30:26.077973Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-27T20:30:26.08982Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb367d182eb83f54","local-member-id":"b7481851e1bcdf3c","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T20:30:26.089998Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T20:30:26.090048Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T20:30:39.037886Z","caller":"traceutil/trace.go:171","msg":"trace[1046959412] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"140.941195ms","start":"2026-10-27T20:30:38.896916Z","end":"2026-10-27T20:30:39.037857Z","steps":["trace[1046959412] 'process raft request' (duration: 76.612399ms)","trace[1046959412] 'compare' (duration: 64.193113ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:30:43.695619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.986656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:30:43.69577Z","caller":"traceutil/trace.go:171","msg":"trace[1877897677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"167.206822ms","start":"2026-10-27T20:30:43.528533Z","end":"2026-10-27T20:30:43.695739Z","steps":["trace[1877897677] 'range keys from in-memory index tree' (duration: 166.880984ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:31:21.49691Z","caller":"traceutil/trace.go:171","msg":"trace[1119716760] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"217.632757ms","start":"2026-10-27T20:31:21.279241Z","end":"2026-10-27T20:31:21.496874Z","steps":["trace[1119716760] 'read index received' (duration: 216.487299ms)","trace[1119716760] 'applied index is now lower than readState.Index' (duration: 1.143768ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:31:21.497031Z","caller":"traceutil/trace.go:171","msg":"trace[1250778615] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"395.341221ms","start":"2026-10-27T20:31:21.101637Z","end":"2026-10-27T20:31:21.496979Z","steps":["trace[1250778615] 'process raft request' (duration: 394.393638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:31:21.497226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.939575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-27T20:31:21.497277Z","caller":"traceutil/trace.go:171","msg":"trace[288504951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"218.117299ms","start":"2026-10-27T20:31:21.279146Z","end":"2026-10-27T20:31:21.497264Z","steps":["trace[288504951] 'agreement among raft nodes before linearized reading' (duration: 217.866463ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:31:21.497879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:31:21.101595Z","time spent":"395.549316ms","remote":"127.0.0.1:54854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:31:22.00152Z","caller":"traceutil/trace.go:171","msg":"trace[1398995135] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"399.699173ms","start":"2026-10-27T20:31:21.601787Z","end":"2026-10-27T20:31:22.001486Z","steps":["trace[1398995135] 'read index received' (duration: 344.397971ms)","trace[1398995135] 'applied index is now lower than readState.Index' (duration: 55.299432ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:31:22.001754Z","caller":"traceutil/trace.go:171","msg":"trace[776139175] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"426.863451ms","start":"2026-10-27T20:31:21.57485Z","end":"2026-10-27T20:31:22.001714Z","steps":["trace[776139175] 'process raft request' (duration: 371.21407ms)","trace[776139175] 'compare' (duration: 54.907132ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:31:22.001953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:31:21.574827Z","time spent":"427.029205ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:31:22.002065Z","caller":"traceutil/trace.go:171","msg":"trace[1690901115] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"409.45049ms","start":"2026-10-27T20:31:21.592358Z","end":"2026-10-27T20:31:22.001809Z","steps":["trace[1690901115] 'process raft request' (duration: 409.0615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:31:22.00217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:31:21.592326Z","time spent":"409.799409ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-27T20:31:22.002107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.300978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T20:31:22.002401Z","caller":"traceutil/trace.go:171","msg":"trace[890955660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"400.659637ms","start":"2026-10-27T20:31:21.601721Z","end":"2026-10-27T20:31:22.00238Z","steps":["trace[890955660] 'agreement among raft nodes before linearized reading' (duration: 400.039291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:31:22.002512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:31:21.601697Z","time spent":"400.793981ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-27T20:31:22.189086Z","caller":"traceutil/trace.go:171","msg":"trace[1731325378] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"171.805205ms","start":"2026-10-27T20:31:22.017253Z","end":"2026-10-27T20:31:22.189058Z","steps":["trace[1731325378] 'read index received' (duration: 142.190504ms)","trace[1731325378] 'applied index is now lower than readState.Index' (duration: 29.61331ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:31:22.18918Z","caller":"traceutil/trace.go:171","msg":"trace[1789362354] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"180.381582ms","start":"2026-10-27T20:31:22.008775Z","end":"2026-10-27T20:31:22.189157Z","steps":["trace[1789362354] 'process raft request' (duration: 150.68106ms)","trace[1789362354] 'compare' (duration: 29.475406ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:31:22.189349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.064312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:31:22.18941Z","caller":"traceutil/trace.go:171","msg":"trace[166878053] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:660; }","duration":"172.243426ms","start":"2026-10-27T20:31:22.017147Z","end":"2026-10-27T20:31:22.18939Z","steps":["trace[166878053] 'agreement among raft nodes before linearized reading' (duration: 172.112113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:09.479786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.790709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:32:09.479928Z","caller":"traceutil/trace.go:171","msg":"trace[791714479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"152.018005ms","start":"2026-10-27T20:32:09.327878Z","end":"2026-10-27T20:32:09.479896Z","steps":["trace[791714479] 'range keys from in-memory index tree' (duration: 151.637606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:24.038756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.711999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-9mmfb\" ","response":"range_response_count:1 size:4344"} {"level":"info","ts":"2026-10-27T20:32:24.03891Z","caller":"traceutil/trace.go:171","msg":"trace[1914071932] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-9mmfb; range_end:; response_count:1; response_revision:1365; }","duration":"101.953875ms","start":"2026-10-27T20:32:23.936928Z","end":"2026-10-27T20:32:24.038882Z","steps":["trace[1914071932] 'range keys from in-memory index tree' (duration: 101.458353ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:32.326904Z","caller":"traceutil/trace.go:171","msg":"trace[1546938350] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"235.451491ms","start":"2026-10-27T20:32:32.091423Z","end":"2026-10-27T20:32:32.326875Z","steps":["trace[1546938350] 'process raft request' (duration: 235.232425ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:32.360987Z","caller":"traceutil/trace.go:171","msg":"trace[1365169095] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"267.232717ms","start":"2026-10-27T20:32:32.093715Z","end":"2026-10-27T20:32:32.360948Z","steps":["trace[1365169095] 'process raft request' (duration: 267.067373ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:42.297836Z","caller":"traceutil/trace.go:171","msg":"trace[726781000] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"104.91618ms","start":"2026-10-27T20:32:42.192875Z","end":"2026-10-27T20:32:42.297791Z","steps":["trace[726781000] 'process raft request' (duration: 55.95954ms)","trace[726781000] 'compare' (duration: 48.445128ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:32:42.298006Z","caller":"traceutil/trace.go:171","msg":"trace[425393413] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"104.411107ms","start":"2026-10-27T20:32:42.193557Z","end":"2026-10-27T20:32:42.297968Z","steps":["trace[425393413] 'process raft request' (duration: 104.074838ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:42.298111Z","caller":"traceutil/trace.go:171","msg":"trace[1667867074] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"104.08019ms","start":"2026-10-27T20:32:42.194005Z","end":"2026-10-27T20:32:42.298085Z","steps":["trace[1667867074] 'process raft request' (duration: 103.68009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:57.353175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16085909536138279922,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-27T20:32:57.415655Z","caller":"traceutil/trace.go:171","msg":"trace[969113747] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"563.09864ms","start":"2026-10-27T20:32:56.852529Z","end":"2026-10-27T20:32:57.415627Z","steps":["trace[969113747] 'read index received' (duration: 563.034078ms)","trace[969113747] 'applied index is now lower than readState.Index' (duration: 62.712µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:32:57.416022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.462229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T20:32:57.415713Z","caller":"traceutil/trace.go:171","msg":"trace[1400290183] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"576.683114ms","start":"2026-10-27T20:32:56.838994Z","end":"2026-10-27T20:32:57.415677Z","steps":["trace[1400290183] 'process raft request' (duration: 576.454278ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:57.416124Z","caller":"traceutil/trace.go:171","msg":"trace[1949437251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1892; }","duration":"563.613393ms","start":"2026-10-27T20:32:56.852486Z","end":"2026-10-27T20:32:57.416099Z","steps":["trace[1949437251] 'agreement among raft nodes before linearized reading' (duration: 563.284244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:57.416178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:56.852467Z","time spent":"563.702536ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-27T20:32:57.416296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:56.838931Z","time spent":"577.207208ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:32:57.481388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.196064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78fcbd5b-5d44-4470-9949-52c2da52e9eb\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-10-27T20:32:57.481452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.234695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78fcbd5b-5d44-4470-9949-52c2da52e9eb\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-27T20:32:57.481513Z","caller":"traceutil/trace.go:171","msg":"trace[837081635] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-78fcbd5b-5d44-4470-9949-52c2da52e9eb; range_end:; response_count:1; response_revision:1893; }","duration":"317.350949ms","start":"2026-10-27T20:32:57.164144Z","end":"2026-10-27T20:32:57.481495Z","steps":["trace[837081635] 'agreement among raft nodes before linearized reading' (duration: 317.118433ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:57.481508Z","caller":"traceutil/trace.go:171","msg":"trace[502309017] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-78fcbd5b-5d44-4470-9949-52c2da52e9eb; range_end:; response_count:1; response_revision:1893; }","duration":"541.381039ms","start":"2026-10-27T20:32:56.9401Z","end":"2026-10-27T20:32:57.481481Z","steps":["trace[502309017] 'agreement among raft nodes before linearized reading' (duration: 540.983829ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:57.481551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.164116Z","time spent":"317.42565ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78fcbd5b-5d44-4470-9949-52c2da52e9eb\" "} {"level":"warn","ts":"2026-10-27T20:32:57.481561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:56.940074Z","time spent":"541.476512ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-78fcbd5b-5d44-4470-9949-52c2da52e9eb\" "} {"level":"warn","ts":"2026-10-27T20:32:57.481954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.347564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-27T20:32:57.4823Z","caller":"traceutil/trace.go:171","msg":"trace[971932368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1893; }","duration":"292.761285ms","start":"2026-10-27T20:32:57.189516Z","end":"2026-10-27T20:32:57.482277Z","steps":["trace[971932368] 'agreement among raft nodes before linearized reading' (duration: 291.687097ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:57.644884Z","caller":"traceutil/trace.go:171","msg":"trace[335422587] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"159.32365ms","start":"2026-10-27T20:32:57.485528Z","end":"2026-10-27T20:32:57.644852Z","steps":["trace[335422587] 'read index received' (duration: 117.999353ms)","trace[335422587] 'applied index is now lower than readState.Index' (duration: 41.322667ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:32:57.645028Z","caller":"traceutil/trace.go:171","msg":"trace[1926152755] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"223.337174ms","start":"2026-10-27T20:32:57.421644Z","end":"2026-10-27T20:32:57.644981Z","steps":["trace[1926152755] 'process raft request' (duration: 181.897583ms)","trace[1926152755] 'compare' (duration: 41.136153ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:32:57.645145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.584667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-10-27T20:32:57.645211Z","caller":"traceutil/trace.go:171","msg":"trace[91767239] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1894; }","duration":"159.72421ms","start":"2026-10-27T20:32:57.485466Z","end":"2026-10-27T20:32:57.64519Z","steps":["trace[91767239] 'agreement among raft nodes before linearized reading' (duration: 159.508025ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:58.081197Z","caller":"traceutil/trace.go:171","msg":"trace[510884866] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"590.871274ms","start":"2026-10-27T20:32:57.490292Z","end":"2026-10-27T20:32:58.081164Z","steps":["trace[510884866] 'process raft request' (duration: 590.685749ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:58.081295Z","caller":"traceutil/trace.go:171","msg":"trace[227813259] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"594.387013ms","start":"2026-10-27T20:32:57.486857Z","end":"2026-10-27T20:32:58.081244Z","steps":["trace[227813259] 'process raft request' (duration: 581.066534ms)","trace[227813259] 'compare' (duration: 12.669532ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:32:58.081446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.490266Z","time spent":"591.057199ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:32:58.081732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.486836Z","time spent":"594.556946ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:32:58.096077Z","caller":"traceutil/trace.go:171","msg":"trace[754663379] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"486.63208ms","start":"2026-10-27T20:32:57.6094Z","end":"2026-10-27T20:32:58.096032Z","steps":["trace[754663379] 'process raft request' (duration: 486.391804ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:32:58.096135Z","caller":"traceutil/trace.go:171","msg":"trace[779384224] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1955; }","duration":"451.119ms","start":"2026-10-27T20:32:57.644998Z","end":"2026-10-27T20:32:58.096117Z","steps":["trace[779384224] 'read index received' (duration: 422.948076ms)","trace[779384224] 'applied index is now lower than readState.Index' (duration: 28.163724ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:32:58.096327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.609366Z","time spent":"486.814255ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:32:58.096383Z","caller":"traceutil/trace.go:171","msg":"trace[1216439298] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"445.860527ms","start":"2026-10-27T20:32:57.650475Z","end":"2026-10-27T20:32:58.096335Z","steps":["trace[1216439298] 'process raft request' (duration: 445.473897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:58.096508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.69309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-10-27T20:32:58.096631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.650459Z","time spent":"446.082413ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:32:58.096696Z","caller":"traceutil/trace.go:171","msg":"trace[232168492] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1898; }","duration":"385.904736ms","start":"2026-10-27T20:32:57.710755Z","end":"2026-10-27T20:32:58.09666Z","steps":["trace[232168492] 'agreement among raft nodes before linearized reading' (duration: 385.597918ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:58.096746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.54147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-27T20:32:58.096764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.710737Z","time spent":"386.007548ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-27T20:32:58.096795Z","caller":"traceutil/trace.go:171","msg":"trace[843181563] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1898; }","duration":"296.589501ms","start":"2026-10-27T20:32:57.800192Z","end":"2026-10-27T20:32:58.096781Z","steps":["trace[843181563] 'agreement among raft nodes before linearized reading' (duration: 296.303154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:58.09711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.468111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:32:58.097162Z","caller":"traceutil/trace.go:171","msg":"trace[761080113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1898; }","duration":"569.541524ms","start":"2026-10-27T20:32:57.527609Z","end":"2026-10-27T20:32:58.09715Z","steps":["trace[761080113] 'agreement among raft nodes before linearized reading' (duration: 569.120173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:58.097168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.702901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4807"} {"level":"info","ts":"2026-10-27T20:32:58.097326Z","caller":"traceutil/trace.go:171","msg":"trace[400984444] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1898; }","duration":"357.931016ms","start":"2026-10-27T20:32:57.739366Z","end":"2026-10-27T20:32:58.097297Z","steps":["trace[400984444] 'agreement among raft nodes before linearized reading' (duration: 357.28205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:32:58.097472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.739342Z","time spent":"358.115502ms","remote":"127.0.0.1:38266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4831,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-10-27T20:32:58.097334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:32:57.527598Z","time spent":"569.588065ms","remote":"127.0.0.1:54738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-27T20:33:40.10842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:33:39.779578Z","time spent":"328.834068ms","remote":"127.0.0.1:54788","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-10-27T20:33:40.108424Z","caller":"traceutil/trace.go:171","msg":"trace[449719033] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2310; }","duration":"166.336368ms","start":"2026-10-27T20:33:39.942056Z","end":"2026-10-27T20:33:40.108393Z","steps":["trace[449719033] 'read index received' (duration: 156.798416ms)","trace[449719033] 'applied index is now lower than readState.Index' (duration: 9.535892ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:33:40.10863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.551023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T20:33:40.1087Z","caller":"traceutil/trace.go:171","msg":"trace[1273611892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2232; }","duration":"166.729697ms","start":"2026-10-27T20:33:39.941955Z","end":"2026-10-27T20:33:40.108685Z","steps":["trace[1273611892] 'agreement among raft nodes before linearized reading' (duration: 166.474141ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:33:40.302625Z","caller":"traceutil/trace.go:171","msg":"trace[851337434] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"188.48619ms","start":"2026-10-27T20:33:40.114108Z","end":"2026-10-27T20:33:40.302594Z","steps":["trace[851337434] 'process raft request' (duration: 149.46124ms)","trace[851337434] 'compare' (duration: 38.839505ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:33:40.302613Z","caller":"traceutil/trace.go:171","msg":"trace[477503014] linearizableReadLoop","detail":"{readStateIndex:2313; appliedIndex:2312; }","duration":"165.647811ms","start":"2026-10-27T20:33:40.136924Z","end":"2026-10-27T20:33:40.302572Z","steps":["trace[477503014] 'read index received' (duration: 126.56984ms)","trace[477503014] 'applied index is now lower than readState.Index' (duration: 39.076091ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:33:40.302845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.891667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-27T20:33:40.302972Z","caller":"traceutil/trace.go:171","msg":"trace[765272391] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2234; }","duration":"166.093983ms","start":"2026-10-27T20:33:40.13685Z","end":"2026-10-27T20:33:40.302944Z","steps":["trace[765272391] 'agreement among raft nodes before linearized reading' (duration: 165.845086ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:33:40.578198Z","caller":"traceutil/trace.go:171","msg":"trace[1296862368] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2313; }","duration":"191.970929ms","start":"2026-10-27T20:33:40.386196Z","end":"2026-10-27T20:33:40.578167Z","steps":["trace[1296862368] 'read index received' (duration: 191.771784ms)","trace[1296862368] 'applied index is now lower than readState.Index' (duration: 197.835µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:33:40.578278Z","caller":"traceutil/trace.go:171","msg":"trace[921937684] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"226.633807ms","start":"2026-10-27T20:33:40.351586Z","end":"2026-10-27T20:33:40.57822Z","steps":["trace[921937684] 'process raft request' (duration: 226.415231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:33:40.578584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.369969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-27T20:33:40.578663Z","caller":"traceutil/trace.go:171","msg":"trace[1512840221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2235; }","duration":"192.524571ms","start":"2026-10-27T20:33:40.386122Z","end":"2026-10-27T20:33:40.578646Z","steps":["trace[1512840221] 'agreement among raft nodes before linearized reading' (duration: 192.215944ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:33:40.578689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.291902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4807"} {"level":"info","ts":"2026-10-27T20:33:40.578809Z","caller":"traceutil/trace.go:171","msg":"trace[589003394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2235; }","duration":"167.465607ms","start":"2026-10-27T20:33:40.41132Z","end":"2026-10-27T20:33:40.578786Z","steps":["trace[589003394] 'agreement among raft nodes before linearized reading' (duration: 167.132819ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:34:53.227374Z","caller":"traceutil/trace.go:171","msg":"trace[693789748] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"179.399038ms","start":"2026-10-27T20:34:53.047951Z","end":"2026-10-27T20:34:53.22735Z","steps":["trace[693789748] 'process raft request' (duration: 179.166632ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:34:53.36453Z","caller":"traceutil/trace.go:171","msg":"trace[2064121419] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"314.04666ms","start":"2026-10-27T20:34:53.050444Z","end":"2026-10-27T20:34:53.364491Z","steps":["trace[2064121419] 'process raft request' (duration: 308.304424ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:34:53.364513Z","caller":"traceutil/trace.go:171","msg":"trace[1119460573] linearizableReadLoop","detail":"{readStateIndex:2892; appliedIndex:2890; }","duration":"188.98207ms","start":"2026-10-27T20:34:53.175492Z","end":"2026-10-27T20:34:53.364474Z","steps":["trace[1119460573] 'read index received' (duration: 51.612247ms)","trace[1119460573] 'applied index is now lower than readState.Index' (duration: 137.368153ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:34:53.364762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:34:53.050429Z","time spent":"314.213594ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":507,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:34:53.364774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.277848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18e27d3626719b16\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-10-27T20:34:53.364841Z","caller":"traceutil/trace.go:171","msg":"trace[1397227522] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18e27d3626719b16; range_end:; response_count:1; response_revision:2783; }","duration":"189.392321ms","start":"2026-10-27T20:34:53.175429Z","end":"2026-10-27T20:34:53.364822Z","steps":["trace[1397227522] 'agreement among raft nodes before linearized reading' (duration: 189.138014ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:34:53.365102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.916621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-27T20:34:53.365193Z","caller":"traceutil/trace.go:171","msg":"trace[499403883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2783; }","duration":"141.086836ms","start":"2026-10-27T20:34:53.224061Z","end":"2026-10-27T20:34:53.365147Z","steps":["trace[499403883] 'agreement among raft nodes before linearized reading' (duration: 140.785778ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:34:53.365239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.924682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-27T20:34:53.365652Z","caller":"traceutil/trace.go:171","msg":"trace[210896172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2783; }","duration":"126.380104ms","start":"2026-10-27T20:34:53.239252Z","end":"2026-10-27T20:34:53.365632Z","steps":["trace[210896172] 'agreement among raft nodes before linearized reading' (duration: 125.778298ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:34:53.599992Z","caller":"traceutil/trace.go:171","msg":"trace[2037598264] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"231.873568ms","start":"2026-10-27T20:34:53.368069Z","end":"2026-10-27T20:34:53.599943Z","steps":["trace[2037598264] 'process raft request' (duration: 144.914813ms)","trace[2037598264] 'compare' (duration: 86.641956ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:34:53.600007Z","caller":"traceutil/trace.go:171","msg":"trace[2123542886] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"228.910403ms","start":"2026-10-27T20:34:53.371075Z","end":"2026-10-27T20:34:53.599985Z","steps":["trace[2123542886] 'process raft request' (duration: 228.81034ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:34:53.600252Z","caller":"traceutil/trace.go:171","msg":"trace[2036165424] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"230.592235ms","start":"2026-10-27T20:34:53.369637Z","end":"2026-10-27T20:34:53.600229Z","steps":["trace[2036165424] 'process raft request' (duration: 230.167104ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:37:26.018699Z","caller":"traceutil/trace.go:171","msg":"trace[166664071] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"115.859881ms","start":"2026-10-27T20:37:25.902801Z","end":"2026-10-27T20:37:26.018661Z","steps":["trace[166664071] 'process raft request' (duration: 79.958963ms)","trace[166664071] 'compare' (duration: 35.722943ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:37:26.059452Z","caller":"traceutil/trace.go:171","msg":"trace[981162384] linearizableReadLoop","detail":"{readStateIndex:4802; appliedIndex:4800; }","duration":"139.688914ms","start":"2026-10-27T20:37:25.919731Z","end":"2026-10-27T20:37:26.05942Z","steps":["trace[981162384] 'read index received' (duration: 62.993854ms)","trace[981162384] 'applied index is now lower than readState.Index' (duration: 76.69356ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:37:26.059613Z","caller":"traceutil/trace.go:171","msg":"trace[1562746389] transaction","detail":"{read_only:false; response_revision:4604; number_of_response:1; }","duration":"154.019476ms","start":"2026-10-27T20:37:25.905561Z","end":"2026-10-27T20:37:26.05958Z","steps":["trace[1562746389] 'process raft request' (duration: 153.666657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:26.065692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.910292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:48 size:2361926"} {"level":"info","ts":"2026-10-27T20:37:26.065816Z","caller":"traceutil/trace.go:171","msg":"trace[2061305179] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:48; response_revision:4604; }","duration":"146.086666ms","start":"2026-10-27T20:37:25.919704Z","end":"2026-10-27T20:37:26.065791Z","steps":["trace[2061305179] 'agreement among raft nodes before linearized reading' (duration: 139.874818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:27.78958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.539409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:801"} {"level":"info","ts":"2026-10-27T20:37:27.789681Z","caller":"traceutil/trace.go:171","msg":"trace[1492910874] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:4674; }","duration":"101.690642ms","start":"2026-10-27T20:37:27.687966Z","end":"2026-10-27T20:37:27.789656Z","steps":["trace[1492910874] 'range keys from in-memory index tree' (duration: 101.416035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:27.78964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.717528ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:37:27.789842Z","caller":"traceutil/trace.go:171","msg":"trace[1278666241] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4674; }","duration":"130.941694ms","start":"2026-10-27T20:37:27.658876Z","end":"2026-10-27T20:37:27.789817Z","steps":["trace[1278666241] 'range keys from in-memory index tree' (duration: 130.688787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:27.790034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.463731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:732946"} {"level":"info","ts":"2026-10-27T20:37:27.790126Z","caller":"traceutil/trace.go:171","msg":"trace[199729481] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4674; }","duration":"115.574824ms","start":"2026-10-27T20:37:27.67453Z","end":"2026-10-27T20:37:27.790104Z","steps":["trace[199729481] 'range keys from in-memory index tree' (duration: 114.838816ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:37:29.807414Z","caller":"traceutil/trace.go:171","msg":"trace[36375974] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4904; }","duration":"116.018736ms","start":"2026-10-27T20:37:29.691365Z","end":"2026-10-27T20:37:29.807384Z","steps":["trace[36375974] 'read index received' (duration: 115.746229ms)","trace[36375974] 'applied index is now lower than readState.Index' (duration: 271.147µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:37:29.807571Z","caller":"traceutil/trace.go:171","msg":"trace[1509110431] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"150.679591ms","start":"2026-10-27T20:37:29.65688Z","end":"2026-10-27T20:37:29.807559Z","steps":["trace[1509110431] 'process raft request' (duration: 150.272951ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:29.808431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.029891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:732946"} {"level":"info","ts":"2026-10-27T20:37:29.808523Z","caller":"traceutil/trace.go:171","msg":"trace[1548425031] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4704; }","duration":"117.219775ms","start":"2026-10-27T20:37:29.69128Z","end":"2026-10-27T20:37:29.8085Z","steps":["trace[1548425031] 'agreement among raft nodes before linearized reading' (duration: 116.520088ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:37:34.055973Z","caller":"traceutil/trace.go:171","msg":"trace[458527541] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"140.038403ms","start":"2026-10-27T20:37:33.915901Z","end":"2026-10-27T20:37:34.055939Z","steps":["trace[458527541] 'process raft request' (duration: 131.800905ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:37:36.318267Z","caller":"traceutil/trace.go:171","msg":"trace[1235117011] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4842; }","duration":"102.441751ms","start":"2026-10-27T20:37:36.215799Z","end":"2026-10-27T20:37:36.318241Z","steps":["trace[1235117011] 'process raft request' (duration: 83.857001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:48.691456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.097003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T20:37:48.691547Z","caller":"traceutil/trace.go:171","msg":"trace[1270281677] linearizableReadLoop","detail":"{readStateIndex:5248; appliedIndex:5247; }","duration":"557.082943ms","start":"2026-10-27T20:37:48.13445Z","end":"2026-10-27T20:37:48.691533Z","steps":["trace[1270281677] 'read index received' (duration: 133.593149ms)","trace[1270281677] 'applied index is now lower than readState.Index' (duration: 423.488554ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:37:48.691618Z","caller":"traceutil/trace.go:171","msg":"trace[201544760] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"720.600431ms","start":"2026-10-27T20:37:47.971011Z","end":"2026-10-27T20:37:48.691611Z","steps":["trace[201544760] 'process raft request' (duration: 297.148438ms)","trace[201544760] 'compare' (duration: 422.795136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:37:48.691652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:37:47.970979Z","time spent":"720.654321ms","remote":"127.0.0.1:55024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:37:48.692206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.650727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-27T20:37:48.692234Z","caller":"traceutil/trace.go:171","msg":"trace[149310380] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5033; }","duration":"117.703208ms","start":"2026-10-27T20:37:48.574524Z","end":"2026-10-27T20:37:48.692227Z","steps":["trace[149310380] 'agreement among raft nodes before linearized reading' (duration: 117.555914ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:48.692219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.433459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:37:48.692341Z","caller":"traceutil/trace.go:171","msg":"trace[1455866851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5033; }","duration":"164.593723ms","start":"2026-10-27T20:37:48.5277Z","end":"2026-10-27T20:37:48.692293Z","steps":["trace[1455866851] 'agreement among raft nodes before linearized reading' (duration: 164.435249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:48.692389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.588072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-10-27T20:37:48.692402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.932384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T20:37:48.692476Z","caller":"traceutil/trace.go:171","msg":"trace[1295206890] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5033; }","duration":"126.717625ms","start":"2026-10-27T20:37:48.565726Z","end":"2026-10-27T20:37:48.692444Z","steps":["trace[1295206890] 'agreement among raft nodes before linearized reading' (duration: 126.449908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:48.69246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.033871ms","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-10-27T20:37:48.692519Z","caller":"traceutil/trace.go:171","msg":"trace[802563211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5033; }","duration":"558.095248ms","start":"2026-10-27T20:37:48.134389Z","end":"2026-10-27T20:37:48.692484Z","steps":["trace[802563211] 'agreement among raft nodes before linearized reading' (duration: 557.802341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:48.692494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.486433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-27T20:37:48.692519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.399056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-10-27T20:37:48.692581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:37:48.134363Z","time spent":"558.201111ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-27T20:37:48.692614Z","caller":"traceutil/trace.go:171","msg":"trace[21514221] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5033; }","duration":"251.206155ms","start":"2026-10-27T20:37:48.441332Z","end":"2026-10-27T20:37:48.692538Z","steps":["trace[21514221] 'agreement among raft nodes before linearized reading' (duration: 250.923117ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:37:48.692647Z","caller":"traceutil/trace.go:171","msg":"trace[121563009] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5033; }","duration":"220.56375ms","start":"2026-10-27T20:37:48.472051Z","end":"2026-10-27T20:37:48.692615Z","steps":["trace[121563009] 'agreement among raft nodes before linearized reading' (duration: 220.161879ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:37:48.692656Z","caller":"traceutil/trace.go:171","msg":"trace[1289348970] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5033; }","duration":"104.637516ms","start":"2026-10-27T20:37:48.587957Z","end":"2026-10-27T20:37:48.692594Z","steps":["trace[1289348970] 'agreement among raft nodes before linearized reading' (duration: 104.241676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:48.693525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.766291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-27T20:37:48.693652Z","caller":"traceutil/trace.go:171","msg":"trace[831107107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5033; }","duration":"306.979516ms","start":"2026-10-27T20:37:48.386653Z","end":"2026-10-27T20:37:48.693632Z","steps":["trace[831107107] 'agreement among raft nodes before linearized reading' (duration: 305.113449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:37:48.693713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:37:48.386613Z","time spent":"307.083708ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-27T20:37:57.497687Z","caller":"traceutil/trace.go:171","msg":"trace[316318614] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"143.019128ms","start":"2026-10-27T20:37:57.354633Z","end":"2026-10-27T20:37:57.497652Z","steps":["trace[316318614] 'process raft request' (duration: 54.333775ms)","trace[316318614] 'compare' (duration: 88.402246ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:37:57.498094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.202268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-03dc36ea-a5f3-42d1-a573-115236d92a5c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-27T20:37:57.498142Z","caller":"traceutil/trace.go:171","msg":"trace[410138022] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-03dc36ea-a5f3-42d1-a573-115236d92a5c; range_end:; response_count:1; response_revision:5214; }","duration":"212.322321ms","start":"2026-10-27T20:37:57.285805Z","end":"2026-10-27T20:37:57.498128Z","steps":["trace[410138022] 'range keys from in-memory index tree' (duration: 211.314275ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:38:08.283955Z","caller":"traceutil/trace.go:171","msg":"trace[1852297590] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"114.886796ms","start":"2026-10-27T20:38:08.169024Z","end":"2026-10-27T20:38:08.283911Z","steps":["trace[1852297590] 'process raft request' (duration: 114.596629ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:38:08.405198Z","caller":"traceutil/trace.go:171","msg":"trace[987055710] transaction","detail":"{read_only:false; response_revision:5339; number_of_response:1; }","duration":"112.398134ms","start":"2026-10-27T20:38:08.292776Z","end":"2026-10-27T20:38:08.405174Z","steps":["trace[987055710] 'process raft request' (duration: 112.354982ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:38:08.405246Z","caller":"traceutil/trace.go:171","msg":"trace[1038920571] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"112.946067ms","start":"2026-10-27T20:38:08.292251Z","end":"2026-10-27T20:38:08.405197Z","steps":["trace[1038920571] 'process raft request' (duration: 112.65163ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:38:08.405403Z","caller":"traceutil/trace.go:171","msg":"trace[1386431908] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"112.62231ms","start":"2026-10-27T20:38:08.292745Z","end":"2026-10-27T20:38:08.405367Z","steps":["trace[1386431908] 'process raft request' (duration: 112.325422ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:40:26.111463Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3038} {"level":"info","ts":"2026-10-27T20:40:26.278807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3038,"took":"165.740076ms","hash":1448818368,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":33849344,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T20:40:26.278897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1448818368,"revision":3038,"compact-revision":-1} {"level":"info","ts":"2026-10-27T20:43:50.758138Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002245.wal"} {"level":"info","ts":"2026-10-27T20:43:55.116437Z","caller":"traceutil/trace.go:171","msg":"trace[1330827285] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"139.973094ms","start":"2026-10-27T20:43:54.976408Z","end":"2026-10-27T20:43:55.116381Z","steps":["trace[1330827285] 'process raft request' (duration: 139.801919ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:43:55.269198Z","caller":"traceutil/trace.go:171","msg":"trace[1158738956] linearizableReadLoop","detail":"{readStateIndex:8809; appliedIndex:8807; }","duration":"187.902052ms","start":"2026-10-27T20:43:55.081261Z","end":"2026-10-27T20:43:55.269163Z","steps":["trace[1158738956] 'read index received' (duration: 35.096962ms)","trace[1158738956] 'applied index is now lower than readState.Index' (duration: 152.80289ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:43:55.269508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.971793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-27T20:43:55.269509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.213809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-27T20:43:55.269563Z","caller":"traceutil/trace.go:171","msg":"trace[576752111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8430; }","duration":"177.072136ms","start":"2026-10-27T20:43:55.092472Z","end":"2026-10-27T20:43:55.269544Z","steps":["trace[576752111] 'agreement among raft nodes before linearized reading' (duration: 176.869481ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:43:55.269567Z","caller":"traceutil/trace.go:171","msg":"trace[69018079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8430; }","duration":"188.343133ms","start":"2026-10-27T20:43:55.08121Z","end":"2026-10-27T20:43:55.269553Z","steps":["trace[69018079] 'agreement among raft nodes before linearized reading' (duration: 188.054855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:43:55.269808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.730613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-10-27T20:43:55.269848Z","caller":"traceutil/trace.go:171","msg":"trace[548330972] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8430; }","duration":"151.814475ms","start":"2026-10-27T20:43:55.118019Z","end":"2026-10-27T20:43:55.269833Z","steps":["trace[548330972] 'agreement among raft nodes before linearized reading' (duration: 151.710402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:43:55.270107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.774567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-27T20:43:55.270152Z","caller":"traceutil/trace.go:171","msg":"trace[372506690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8430; }","duration":"170.841858ms","start":"2026-10-27T20:43:55.099297Z","end":"2026-10-27T20:43:55.270139Z","steps":["trace[372506690] 'agreement among raft nodes before linearized reading' (duration: 170.722035ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:44:08.13959Z","caller":"traceutil/trace.go:171","msg":"trace[2107512490] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"310.150525ms","start":"2026-10-27T20:44:07.82941Z","end":"2026-10-27T20:44:08.139561Z","steps":["trace[2107512490] 'read index received' (duration: 309.333274ms)","trace[2107512490] 'applied index is now lower than readState.Index' (duration: 816.151µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:44:08.139679Z","caller":"traceutil/trace.go:171","msg":"trace[32363437] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"352.620744ms","start":"2026-10-27T20:44:07.787007Z","end":"2026-10-27T20:44:08.139628Z","steps":["trace[32363437] 'process raft request' (duration: 351.674779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:44:08.139919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:44:07.786977Z","time spent":"352.821339ms","remote":"127.0.0.1:55188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16939,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:44:08.139931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.495754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-29f42\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-27T20:44:08.14002Z","caller":"traceutil/trace.go:171","msg":"trace[1131129134] range","detail":"{range_begin:/registry/pods/openstack/keepalived-29f42; range_end:; response_count:1; response_revision:8515; }","duration":"310.650978ms","start":"2026-10-27T20:44:07.829347Z","end":"2026-10-27T20:44:08.139998Z","steps":["trace[1131129134] 'agreement among raft nodes before linearized reading' (duration: 310.362391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:44:08.140078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:44:07.829319Z","time spent":"310.74987ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-29f42\" "} {"level":"info","ts":"2026-10-27T20:44:08.20757Z","caller":"traceutil/trace.go:171","msg":"trace[1917483772] transaction","detail":"{read_only:false; response_revision:8516; number_of_response:1; }","duration":"217.094903ms","start":"2026-10-27T20:44:07.990435Z","end":"2026-10-27T20:44:08.20753Z","steps":["trace[1917483772] 'process raft request' (duration: 216.815696ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:44:08.208055Z","caller":"traceutil/trace.go:171","msg":"trace[1495329496] transaction","detail":"{read_only:false; response_revision:8517; number_of_response:1; }","duration":"129.384595ms","start":"2026-10-27T20:44:08.078632Z","end":"2026-10-27T20:44:08.208016Z","steps":["trace[1495329496] 'process raft request' (duration: 128.832121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:44:08.208587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.956987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-27T20:44:08.208693Z","caller":"traceutil/trace.go:171","msg":"trace[1367454252] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8517; }","duration":"189.127411ms","start":"2026-10-27T20:44:08.019538Z","end":"2026-10-27T20:44:08.208665Z","steps":["trace[1367454252] 'agreement among raft nodes before linearized reading' (duration: 188.788913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:44:08.208729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.477587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-27T20:44:08.20879Z","caller":"traceutil/trace.go:171","msg":"trace[199171377] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8517; }","duration":"153.59457ms","start":"2026-10-27T20:44:08.055178Z","end":"2026-10-27T20:44:08.208772Z","steps":["trace[199171377] 'agreement among raft nodes before linearized reading' (duration: 153.367374ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:44:23.900248Z","caller":"traceutil/trace.go:171","msg":"trace[1026294410] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8626; }","duration":"151.22498ms","start":"2026-10-27T20:44:23.749006Z","end":"2026-10-27T20:44:23.900231Z","steps":["trace[1026294410] 'process raft request' (duration: 128.38432ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:44:23.900232Z","caller":"traceutil/trace.go:171","msg":"trace[650500420] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"149.327391ms","start":"2026-10-27T20:44:23.75087Z","end":"2026-10-27T20:44:23.900197Z","steps":["trace[650500420] 'process raft request' (duration: 148.8885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:44:36.381308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.65207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:44:36.381415Z","caller":"traceutil/trace.go:171","msg":"trace[148497081] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8758; }","duration":"162.883576ms","start":"2026-10-27T20:44:36.218504Z","end":"2026-10-27T20:44:36.381387Z","steps":["trace[148497081] 'count revisions from in-memory index tree' (duration: 162.504097ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:44:50.096941Z","caller":"traceutil/trace.go:171","msg":"trace[2059823151] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"147.120046ms","start":"2026-10-27T20:44:49.94979Z","end":"2026-10-27T20:44:50.09691Z","steps":["trace[2059823151] 'process raft request' (duration: 146.830268ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:44:50.320975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.701084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T20:44:50.321155Z","caller":"traceutil/trace.go:171","msg":"trace[1955044073] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8894; }","duration":"358.837821ms","start":"2026-10-27T20:44:49.962292Z","end":"2026-10-27T20:44:50.321129Z","steps":["trace[1955044073] 'process raft request' (duration: 200.789208ms)","trace[1955044073] 'compare' (duration: 157.504269ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:44:50.321249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:44:49.962259Z","time spent":"358.946744ms","remote":"127.0.0.1:55880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-10-27T20:44:51.027869Z","caller":"traceutil/trace.go:171","msg":"trace[281112304] transaction","detail":"{read_only:false; response_revision:8902; number_of_response:1; }","duration":"103.673022ms","start":"2026-10-27T20:44:50.924154Z","end":"2026-10-27T20:44:51.027827Z","steps":["trace[281112304] 'process raft request' (duration: 60.321262ms)","trace[281112304] 'compare' (duration: 43.146705ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:44:51.397247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.565672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:44:51.397316Z","caller":"traceutil/trace.go:171","msg":"trace[63375537] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8902; }","duration":"237.660724ms","start":"2026-10-27T20:44:51.159639Z","end":"2026-10-27T20:44:51.3973Z","steps":["trace[63375537] 'count revisions from in-memory index tree' (duration: 237.425767ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:44:51.926032Z","caller":"traceutil/trace.go:171","msg":"trace[1395890308] transaction","detail":"{read_only:false; response_revision:8909; number_of_response:1; }","duration":"189.493281ms","start":"2026-10-27T20:44:51.736508Z","end":"2026-10-27T20:44:51.926001Z","steps":["trace[1395890308] 'process raft request' (duration: 189.319877ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:45:26.121738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6514} {"level":"info","ts":"2026-10-27T20:45:26.254609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6514,"took":"130.854582ms","hash":969387721,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":26140672,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T20:45:26.2547Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":969387721,"revision":6514,"compact-revision":3038} {"level":"info","ts":"2026-10-27T20:45:51.520872Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T20:45:51.562477Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-27T20:45:51.562602Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-27T20:46:08.677545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.206395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T20:46:08.6778Z","caller":"traceutil/trace.go:171","msg":"trace[183154204] linearizableReadLoop","detail":"{readStateIndex:10139; appliedIndex:10137; }","duration":"202.520393ms","start":"2026-10-27T20:46:08.475256Z","end":"2026-10-27T20:46:08.677776Z","steps":["trace[183154204] 'read index received' (duration: 45.895526ms)","trace[183154204] 'applied index is now lower than readState.Index' (duration: 156.623436ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:46:08.678008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.681836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:46:08.677979Z","caller":"traceutil/trace.go:171","msg":"trace[737175085] transaction","detail":"{read_only:false; response_revision:9696; number_of_response:1; }","duration":"316.222579ms","start":"2026-10-27T20:46:08.361685Z","end":"2026-10-27T20:46:08.677907Z","steps":["trace[737175085] 'process raft request' (duration: 315.999603ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:46:08.678068Z","caller":"traceutil/trace.go:171","msg":"trace[990860576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9696; }","duration":"150.772049ms","start":"2026-10-27T20:46:08.52728Z","end":"2026-10-27T20:46:08.678052Z","steps":["trace[990860576] 'agreement among raft nodes before linearized reading' (duration: 150.673786ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:46:08.677974Z","caller":"traceutil/trace.go:171","msg":"trace[228576825] transaction","detail":"{read_only:false; response_revision:9695; number_of_response:1; }","duration":"317.05213ms","start":"2026-10-27T20:46:08.360824Z","end":"2026-10-27T20:46:08.677876Z","steps":["trace[228576825] 'process raft request' (duration: 160.360812ms)","trace[228576825] 'compare' (duration: 156.00062ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:46:08.678265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:46:08.361671Z","time spent":"316.416484ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:46:08.678441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.046736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-27T20:46:08.678599Z","caller":"traceutil/trace.go:171","msg":"trace[1433264505] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9696; }","duration":"203.383283ms","start":"2026-10-27T20:46:08.475191Z","end":"2026-10-27T20:46:08.678575Z","steps":["trace[1433264505] 'agreement among raft nodes before linearized reading' (duration: 202.767738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:46:08.678793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:46:08.360811Z","time spent":"317.925951ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T20:46:08.87299Z","caller":"traceutil/trace.go:171","msg":"trace[1794581420] transaction","detail":"{read_only:false; response_revision:9697; number_of_response:1; }","duration":"189.528152ms","start":"2026-10-27T20:46:08.683422Z","end":"2026-10-27T20:46:08.872951Z","steps":["trace[1794581420] 'process raft request' (duration: 148.777828ms)","trace[1794581420] 'compare' (duration: 40.561719ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:46:20.894079Z","caller":"traceutil/trace.go:171","msg":"trace[873472404] transaction","detail":"{read_only:false; response_revision:9811; number_of_response:1; }","duration":"105.138379ms","start":"2026-10-27T20:46:20.788905Z","end":"2026-10-27T20:46:20.894044Z","steps":["trace[873472404] 'process raft request' (duration: 104.948634ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:49:35.979142Z","caller":"traceutil/trace.go:171","msg":"trace[1835540992] linearizableReadLoop","detail":"{readStateIndex:12325; appliedIndex:12324; }","duration":"103.270828ms","start":"2026-10-27T20:49:35.875849Z","end":"2026-10-27T20:49:35.97912Z","steps":["trace[1835540992] 'read index received' (duration: 103.100643ms)","trace[1835540992] 'applied index is now lower than readState.Index' (duration: 169.495µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:49:35.979238Z","caller":"traceutil/trace.go:171","msg":"trace[915118120] transaction","detail":"{read_only:false; response_revision:11791; number_of_response:1; }","duration":"122.184678ms","start":"2026-10-27T20:49:35.856992Z","end":"2026-10-27T20:49:35.979176Z","steps":["trace[915118120] 'process raft request' (duration: 121.978493ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:49:35.979431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.551075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T20:49:35.979484Z","caller":"traceutil/trace.go:171","msg":"trace[1783187600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11791; }","duration":"103.662268ms","start":"2026-10-27T20:49:35.875806Z","end":"2026-10-27T20:49:35.979468Z","steps":["trace[1783187600] 'agreement among raft nodes before linearized reading' (duration: 103.448252ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:49:50.726127Z","caller":"traceutil/trace.go:171","msg":"trace[1817818844] linearizableReadLoop","detail":"{readStateIndex:12435; appliedIndex:12434; }","duration":"123.217484ms","start":"2026-10-27T20:49:50.602881Z","end":"2026-10-27T20:49:50.726098Z","steps":["trace[1817818844] 'read index received' (duration: 87.627632ms)","trace[1817818844] 'applied index is now lower than readState.Index' (duration: 35.588392ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:49:50.726259Z","caller":"traceutil/trace.go:171","msg":"trace[1873360749] transaction","detail":"{read_only:false; response_revision:11895; number_of_response:1; }","duration":"125.470311ms","start":"2026-10-27T20:49:50.600777Z","end":"2026-10-27T20:49:50.726247Z","steps":["trace[1873360749] 'process raft request' (duration: 89.861278ms)","trace[1873360749] 'compare' (duration: 35.088139ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:49:50.726437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.544572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T20:49:50.726476Z","caller":"traceutil/trace.go:171","msg":"trace[1763194145] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:11895; }","duration":"123.635354ms","start":"2026-10-27T20:49:50.602829Z","end":"2026-10-27T20:49:50.726465Z","steps":["trace[1763194145] 'agreement among raft nodes before linearized reading' (duration: 123.550912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:49:50.726573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.182514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:49:50.726663Z","caller":"traceutil/trace.go:171","msg":"trace[403992439] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:11895; }","duration":"110.343248ms","start":"2026-10-27T20:49:50.616295Z","end":"2026-10-27T20:49:50.726639Z","steps":["trace[403992439] 'agreement among raft nodes before linearized reading' (duration: 110.164433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:49:51.030257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.823934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T20:49:51.030376Z","caller":"traceutil/trace.go:171","msg":"trace[1466063626] linearizableReadLoop","detail":"{readStateIndex:12437; appliedIndex:12436; }","duration":"214.843337ms","start":"2026-10-27T20:49:50.815491Z","end":"2026-10-27T20:49:51.030335Z","steps":["trace[1466063626] 'read index received' (duration: 29.802246ms)","trace[1466063626] 'applied index is now lower than readState.Index' (duration: 185.039491ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:49:51.030501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.007101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-29f42\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-27T20:49:51.030551Z","caller":"traceutil/trace.go:171","msg":"trace[531409661] range","detail":"{range_begin:/registry/pods/openstack/keepalived-29f42; range_end:; response_count:1; response_revision:11897; }","duration":"215.030231ms","start":"2026-10-27T20:49:50.815486Z","end":"2026-10-27T20:49:51.030516Z","steps":["trace[531409661] 'agreement among raft nodes before linearized reading' (duration: 214.915578ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:49:51.030518Z","caller":"traceutil/trace.go:171","msg":"trace[1429316460] transaction","detail":"{read_only:false; response_revision:11897; number_of_response:1; }","duration":"242.399385ms","start":"2026-10-27T20:49:50.788089Z","end":"2026-10-27T20:49:51.030489Z","steps":["trace[1429316460] 'process raft request' (duration: 57.257112ms)","trace[1429316460] 'compare' (duration: 184.536998ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:49:51.192396Z","caller":"traceutil/trace.go:171","msg":"trace[1617980025] transaction","detail":"{read_only:false; response_revision:11898; number_of_response:1; }","duration":"154.133398ms","start":"2026-10-27T20:49:51.038226Z","end":"2026-10-27T20:49:51.192359Z","steps":["trace[1617980025] 'process raft request' (duration: 103.567226ms)","trace[1617980025] 'compare' (duration: 50.395818ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:49:51.412942Z","caller":"traceutil/trace.go:171","msg":"trace[1750912983] transaction","detail":"{read_only:false; response_revision:11899; number_of_response:1; }","duration":"188.009246ms","start":"2026-10-27T20:49:51.224893Z","end":"2026-10-27T20:49:51.412902Z","steps":["trace[1750912983] 'process raft request' (duration: 107.903445ms)","trace[1750912983] 'compare' (duration: 79.888795ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:50:26.129842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9286} {"level":"info","ts":"2026-10-27T20:50:26.224617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9286,"took":"91.572259ms","hash":1581407258,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":28016640,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T20:50:26.224761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1581407258,"revision":9286,"compact-revision":6514} {"level":"info","ts":"2026-10-27T20:55:26.138576Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12184} {"level":"info","ts":"2026-10-27T20:55:26.239952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12184,"took":"99.039617ms","hash":121366293,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":25616384,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-27T20:55:26.240029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":121366293,"revision":12184,"compact-revision":9286} {"level":"info","ts":"2026-10-27T20:56:03.350805Z","caller":"traceutil/trace.go:171","msg":"trace[1399709265] transaction","detail":"{read_only:false; response_revision:14916; number_of_response:1; }","duration":"158.77221ms","start":"2026-10-27T20:56:03.192004Z","end":"2026-10-27T20:56:03.350776Z","steps":["trace[1399709265] 'process raft request' (duration: 158.585175ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:56:03.508591Z","caller":"traceutil/trace.go:171","msg":"trace[1927547784] linearizableReadLoop","detail":"{readStateIndex:15618; appliedIndex:15617; }","duration":"120.220985ms","start":"2026-10-27T20:56:03.388338Z","end":"2026-10-27T20:56:03.508559Z","steps":["trace[1927547784] 'read index received' (duration: 119.611321ms)","trace[1927547784] 'applied index is now lower than readState.Index' (duration: 608.584µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:56:03.508728Z","caller":"traceutil/trace.go:171","msg":"trace[1529880437] transaction","detail":"{read_only:false; response_revision:14917; number_of_response:1; }","duration":"148.480987ms","start":"2026-10-27T20:56:03.360215Z","end":"2026-10-27T20:56:03.508696Z","steps":["trace[1529880437] 'process raft request' (duration: 147.745019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:03.508849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.499063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-27T20:56:03.508892Z","caller":"traceutil/trace.go:171","msg":"trace[549364393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14917; }","duration":"120.636107ms","start":"2026-10-27T20:56:03.388247Z","end":"2026-10-27T20:56:03.508884Z","steps":["trace[549364393] 'agreement among raft nodes before linearized reading' (duration: 120.457372ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:56:22.001006Z","caller":"traceutil/trace.go:171","msg":"trace[770090295] transaction","detail":"{read_only:false; response_revision:15059; number_of_response:1; }","duration":"143.629594ms","start":"2026-10-27T20:56:21.857343Z","end":"2026-10-27T20:56:22.000972Z","steps":["trace[770090295] 'process raft request' (duration: 143.366477ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.761293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.289496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T20:56:22.7614Z","caller":"traceutil/trace.go:171","msg":"trace[1176472816] linearizableReadLoop","detail":"{readStateIndex:15770; appliedIndex:15769; }","duration":"738.299099ms","start":"2026-10-27T20:56:22.023085Z","end":"2026-10-27T20:56:22.761384Z","steps":["trace[1176472816] 'read index received' (duration: 341.90328ms)","trace[1176472816] 'applied index is now lower than readState.Index' (duration: 396.394449ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:56:22.761472Z","caller":"traceutil/trace.go:171","msg":"trace[1997561529] transaction","detail":"{read_only:false; response_revision:15060; number_of_response:1; }","duration":"854.547424ms","start":"2026-10-27T20:56:21.906913Z","end":"2026-10-27T20:56:22.761461Z","steps":["trace[1997561529] 'process raft request' (duration: 457.986521ms)","trace[1997561529] 'compare' (duration: 395.883775ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:56:22.761533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:21.906888Z","time spent":"854.614955ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:56:22.761639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.521304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-27T20:56:22.761765Z","caller":"traceutil/trace.go:171","msg":"trace[1550679503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15060; }","duration":"738.696509ms","start":"2026-10-27T20:56:22.023041Z","end":"2026-10-27T20:56:22.761738Z","steps":["trace[1550679503] 'agreement among raft nodes before linearized reading' (duration: 738.415042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.761825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.023021Z","time spent":"738.789261ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-27T20:56:22.957763Z","caller":"traceutil/trace.go:171","msg":"trace[1743914785] linearizableReadLoop","detail":"{readStateIndex:15771; appliedIndex:15770; }","duration":"196.311406ms","start":"2026-10-27T20:56:22.761424Z","end":"2026-10-27T20:56:22.957735Z","steps":["trace[1743914785] 'read index received' (duration: 196.019809ms)","trace[1743914785] 'applied index is now lower than readState.Index' (duration: 290.677µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:56:22.957899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.587892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-27T20:56:22.957936Z","caller":"traceutil/trace.go:171","msg":"trace[823635688] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:15061; }","duration":"463.663094ms","start":"2026-10-27T20:56:22.494264Z","end":"2026-10-27T20:56:22.957927Z","steps":["trace[823635688] 'agreement among raft nodes before linearized reading' (duration: 463.522051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.957958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.494246Z","time spent":"463.706405ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-27T20:56:22.95799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.896788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-27T20:56:22.958028Z","caller":"traceutil/trace.go:171","msg":"trace[508829673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15061; }","duration":"626.96823ms","start":"2026-10-27T20:56:22.331049Z","end":"2026-10-27T20:56:22.958017Z","steps":["trace[508829673] 'agreement among raft nodes before linearized reading' (duration: 626.872527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.958062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.331029Z","time spent":"627.025481ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-27T20:56:22.957903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.651971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-27T20:56:22.958149Z","caller":"traceutil/trace.go:171","msg":"trace[407942014] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:15061; }","duration":"566.95851ms","start":"2026-10-27T20:56:22.391185Z","end":"2026-10-27T20:56:22.958143Z","steps":["trace[407942014] 'agreement among raft nodes before linearized reading' (duration: 566.670623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.958173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.391161Z","time spent":"567.004331ms","remote":"127.0.0.1:55044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-10-27T20:56:22.958264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.790291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-27T20:56:22.958302Z","caller":"traceutil/trace.go:171","msg":"trace[1060173139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15061; }","duration":"647.854742ms","start":"2026-10-27T20:56:22.310438Z","end":"2026-10-27T20:56:22.958293Z","steps":["trace[1060173139] 'agreement among raft nodes before linearized reading' (duration: 647.759359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.95833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.310423Z","time spent":"647.901494ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-27T20:56:22.958429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.006593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-29f42\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-27T20:56:22.958539Z","caller":"traceutil/trace.go:171","msg":"trace[579845703] range","detail":"{range_begin:/registry/pods/openstack/keepalived-29f42; range_end:; response_count:1; response_revision:15061; }","duration":"324.215279ms","start":"2026-10-27T20:56:22.634296Z","end":"2026-10-27T20:56:22.958511Z","steps":["trace[579845703] 'agreement among raft nodes before linearized reading' (duration: 323.806908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.958625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.634274Z","time spent":"324.338531ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-29f42\" "} {"level":"info","ts":"2026-10-27T20:56:22.958778Z","caller":"traceutil/trace.go:171","msg":"trace[1027146668] transaction","detail":"{read_only:false; response_revision:15061; number_of_response:1; }","duration":"686.071187ms","start":"2026-10-27T20:56:22.272694Z","end":"2026-10-27T20:56:22.958765Z","steps":["trace[1027146668] 'process raft request' (duration: 684.453135ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.958865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.272644Z","time spent":"686.168229ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:56:22.958989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.287752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-27T20:56:22.95913Z","caller":"traceutil/trace.go:171","msg":"trace[1331604982] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:15061; }","duration":"833.420515ms","start":"2026-10-27T20:56:22.125631Z","end":"2026-10-27T20:56:22.959051Z","steps":["trace[1331604982] 'agreement among raft nodes before linearized reading' (duration: 833.107566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.959346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:56:22.125612Z","time spent":"833.719343ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-27T20:56:22.959523Z","caller":"traceutil/trace.go:171","msg":"trace[1465694406] transaction","detail":"{read_only:false; response_revision:15062; number_of_response:1; }","duration":"193.314929ms","start":"2026-10-27T20:56:22.766185Z","end":"2026-10-27T20:56:22.9595Z","steps":["trace[1465694406] 'process raft request' (duration: 192.92941ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:56:22.959507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.451992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T20:56:22.959714Z","caller":"traceutil/trace.go:171","msg":"trace[1886227037] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:15062; }","duration":"189.651116ms","start":"2026-10-27T20:56:22.769998Z","end":"2026-10-27T20:56:22.959649Z","steps":["trace[1886227037] 'agreement among raft nodes before linearized reading' (duration: 189.265957ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:58:11.421017Z","caller":"traceutil/trace.go:171","msg":"trace[1911261539] linearizableReadLoop","detail":"{readStateIndex:17211; appliedIndex:17210; }","duration":"162.104931ms","start":"2026-10-27T20:58:11.258881Z","end":"2026-10-27T20:58:11.420986Z","steps":["trace[1911261539] 'read index received' (duration: 131.77277ms)","trace[1911261539] 'applied index is now lower than readState.Index' (duration: 30.331011ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:58:11.421408Z","caller":"traceutil/trace.go:171","msg":"trace[1110081382] transaction","detail":"{read_only:false; response_revision:16441; number_of_response:1; }","duration":"177.843483ms","start":"2026-10-27T20:58:11.243545Z","end":"2026-10-27T20:58:11.421388Z","steps":["trace[1110081382] 'process raft request' (duration: 147.118242ms)","trace[1110081382] 'compare' (duration: 29.279134ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:58:11.421981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.408339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-10-27T20:58:11.422093Z","caller":"traceutil/trace.go:171","msg":"trace[743271115] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16441; }","duration":"162.553572ms","start":"2026-10-27T20:58:11.259516Z","end":"2026-10-27T20:58:11.422069Z","steps":["trace[743271115] 'agreement among raft nodes before linearized reading' (duration: 162.305906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:58:11.422673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.083735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T20:58:11.422739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.714634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T20:58:11.422774Z","caller":"traceutil/trace.go:171","msg":"trace[1078290824] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16441; }","duration":"163.221479ms","start":"2026-10-27T20:58:11.259534Z","end":"2026-10-27T20:58:11.422756Z","steps":["trace[1078290824] 'agreement among raft nodes before linearized reading' (duration: 163.065225ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:58:11.422803Z","caller":"traceutil/trace.go:171","msg":"trace[1634109677] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:16441; }","duration":"156.840698ms","start":"2026-10-27T20:58:11.265946Z","end":"2026-10-27T20:58:11.422786Z","steps":["trace[1634109677] 'agreement among raft nodes before linearized reading' (duration: 156.718165ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:58:11.421807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.908172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:669"} {"level":"info","ts":"2026-10-27T20:58:11.423188Z","caller":"traceutil/trace.go:171","msg":"trace[425428833] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16441; }","duration":"164.294037ms","start":"2026-10-27T20:58:11.258848Z","end":"2026-10-27T20:58:11.423142Z","steps":["trace[425428833] 'agreement among raft nodes before linearized reading' (duration: 162.783569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:58:11.423424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.345128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-scl22\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-10-27T20:58:11.423477Z","caller":"traceutil/trace.go:171","msg":"trace[516068387] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-scl22; range_end:; response_count:1; response_revision:16441; }","duration":"162.401199ms","start":"2026-10-27T20:58:11.261062Z","end":"2026-10-27T20:58:11.423463Z","steps":["trace[516068387] 'agreement among raft nodes before linearized reading' (duration: 161.677621ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:58:11.423892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.865316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-scl22\" ","response":"range_response_count:1 size:22090"} {"level":"info","ts":"2026-10-27T20:58:11.423953Z","caller":"traceutil/trace.go:171","msg":"trace[1854864896] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-scl22; range_end:; response_count:1; response_revision:16441; }","duration":"115.960108ms","start":"2026-10-27T20:58:11.307977Z","end":"2026-10-27T20:58:11.423937Z","steps":["trace[1854864896] 'agreement among raft nodes before linearized reading' (duration: 114.592713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:58:12.448844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.496445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T20:58:12.44894Z","caller":"traceutil/trace.go:171","msg":"trace[1589576173] linearizableReadLoop","detail":"{readStateIndex:17217; appliedIndex:17216; }","duration":"223.346198ms","start":"2026-10-27T20:58:12.225577Z","end":"2026-10-27T20:58:12.448924Z","steps":["trace[1589576173] 'read index received' (duration: 28.637327ms)","trace[1589576173] 'applied index is now lower than readState.Index' (duration: 194.707131ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T20:58:12.449053Z","caller":"traceutil/trace.go:171","msg":"trace[1683346382] transaction","detail":"{read_only:false; response_revision:16447; number_of_response:1; }","duration":"327.391434ms","start":"2026-10-27T20:58:12.121627Z","end":"2026-10-27T20:58:12.449018Z","steps":["trace[1683346382] 'process raft request' (duration: 132.640152ms)","trace[1683346382] 'compare' (duration: 194.227198ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T20:58:12.449143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.572074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-29f42\" ","response":"range_response_count:1 size:6525"} {"level":"info","ts":"2026-10-27T20:58:12.449178Z","caller":"traceutil/trace.go:171","msg":"trace[1341095915] range","detail":"{range_begin:/registry/pods/openstack/keepalived-29f42; range_end:; response_count:1; response_revision:16447; }","duration":"223.646226ms","start":"2026-10-27T20:58:12.225523Z","end":"2026-10-27T20:58:12.449169Z","steps":["trace[1341095915] 'agreement among raft nodes before linearized reading' (duration: 223.500162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:58:12.449226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T20:58:12.121602Z","time spent":"327.540568ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T20:58:12.44927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.06555ms","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-10-27T20:58:12.449352Z","caller":"traceutil/trace.go:171","msg":"trace[792457311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16447; }","duration":"171.199643ms","start":"2026-10-27T20:58:12.27813Z","end":"2026-10-27T20:58:12.44933Z","steps":["trace[792457311] 'agreement among raft nodes before linearized reading' (duration: 170.964057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T20:58:12.449427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.12176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-10-27T20:58:12.449492Z","caller":"traceutil/trace.go:171","msg":"trace[1100881191] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:16447; }","duration":"108.217963ms","start":"2026-10-27T20:58:12.341254Z","end":"2026-10-27T20:58:12.449472Z","steps":["trace[1100881191] 'agreement among raft nodes before linearized reading' (duration: 108.071109ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:58:12.590395Z","caller":"traceutil/trace.go:171","msg":"trace[1265447332] transaction","detail":"{read_only:false; response_revision:16448; number_of_response:1; }","duration":"135.819593ms","start":"2026-10-27T20:58:12.454547Z","end":"2026-10-27T20:58:12.590367Z","steps":["trace[1265447332] 'process raft request' (duration: 135.28348ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:58:12.601231Z","caller":"traceutil/trace.go:171","msg":"trace[1025459471] transaction","detail":"{read_only:false; response_revision:16450; number_of_response:1; }","duration":"143.123898ms","start":"2026-10-27T20:58:12.458079Z","end":"2026-10-27T20:58:12.601203Z","steps":["trace[1025459471] 'process raft request' (duration: 143.041207ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T20:58:12.601242Z","caller":"traceutil/trace.go:171","msg":"trace[346275920] transaction","detail":"{read_only:false; response_revision:16449; number_of_response:1; }","duration":"145.93876ms","start":"2026-10-27T20:58:12.455275Z","end":"2026-10-27T20:58:12.601214Z","steps":["trace[346275920] 'process raft request' (duration: 145.683574ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:00:26.149994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14627} {"level":"info","ts":"2026-10-27T21:00:26.269384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14627,"took":"115.583438ms","hash":223109991,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":30183424,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-27T21:00:26.26946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":223109991,"revision":14627,"compact-revision":12184} {"level":"info","ts":"2026-10-27T21:03:10.166589Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T21:03:10.173397Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-10-27T21:03:10.173545Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"warn","ts":"2026-10-27T21:03:41.332278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.940429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-senlin-nodes\" ","response":"range_response_count:1 size:959"} {"level":"info","ts":"2026-10-27T21:03:41.332405Z","caller":"traceutil/trace.go:171","msg":"trace[1521304965] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-senlin-nodes; range_end:; response_count:1; response_revision:19422; }","duration":"170.109243ms","start":"2026-10-27T21:03:41.162268Z","end":"2026-10-27T21:03:41.332377Z","steps":["trace[1521304965] 'range keys from in-memory index tree' (duration: 169.767404ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:41.332752Z","caller":"traceutil/trace.go:171","msg":"trace[675054993] transaction","detail":"{read_only:false; response_revision:19423; number_of_response:1; }","duration":"119.367174ms","start":"2026-10-27T21:03:41.213363Z","end":"2026-10-27T21:03:41.33273Z","steps":["trace[675054993] 'process raft request' (duration: 116.655655ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.895874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16085909536138331333,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-27T21:03:48.898793Z","caller":"traceutil/trace.go:171","msg":"trace[90421108] linearizableReadLoop","detail":"{readStateIndex:20402; appliedIndex:20401; }","duration":"503.203948ms","start":"2026-10-27T21:03:48.395565Z","end":"2026-10-27T21:03:48.898769Z","steps":["trace[90421108] 'read index received' (duration: 503.066835ms)","trace[90421108] 'applied index is now lower than readState.Index' (duration: 136.213µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:03:48.898847Z","caller":"traceutil/trace.go:171","msg":"trace[28981380] transaction","detail":"{read_only:false; response_revision:19483; number_of_response:1; }","duration":"746.888642ms","start":"2026-10-27T21:03:48.151909Z","end":"2026-10-27T21:03:48.898798Z","steps":["trace[28981380] 'process raft request' (duration: 746.703227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.899039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.744916ms","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-10-27T21:03:48.899081Z","caller":"traceutil/trace.go:171","msg":"trace[80988000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19483; }","duration":"452.833249ms","start":"2026-10-27T21:03:48.446236Z","end":"2026-10-27T21:03:48.899069Z","steps":["trace[80988000] 'agreement among raft nodes before linearized reading' (duration: 452.687915ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.899113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:48.446215Z","time spent":"452.88916ms","remote":"127.0.0.1:55008","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-10-27T21:03:48.899107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.259065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T21:03:48.899233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.537318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-w42s4\" ","response":"range_response_count:1 size:9788"} {"level":"info","ts":"2026-10-27T21:03:48.899278Z","caller":"traceutil/trace.go:171","msg":"trace[939551193] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-w42s4; range_end:; response_count:1; response_revision:19483; }","duration":"418.61004ms","start":"2026-10-27T21:03:48.480654Z","end":"2026-10-27T21:03:48.899264Z","steps":["trace[939551193] 'agreement among raft nodes before linearized reading' (duration: 418.507957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.899312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:48.480638Z","time spent":"418.666431ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-w42s4\" "} {"level":"warn","ts":"2026-10-27T21:03:48.89935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.105555ms","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-10-27T21:03:48.89929Z","caller":"traceutil/trace.go:171","msg":"trace[92892122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19483; }","duration":"371.472711ms","start":"2026-10-27T21:03:48.527792Z","end":"2026-10-27T21:03:48.899264Z","steps":["trace[92892122] 'agreement among raft nodes before linearized reading' (duration: 371.236205ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:48.899389Z","caller":"traceutil/trace.go:171","msg":"trace[1012247160] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19483; }","duration":"449.182867ms","start":"2026-10-27T21:03:48.450193Z","end":"2026-10-27T21:03:48.899376Z","steps":["trace[1012247160] 'agreement among raft nodes before linearized reading' (duration: 449.088214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.899412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:48.527782Z","time spent":"371.616494ms","remote":"127.0.0.1:54738","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-10-27T21:03:48.899502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.775119ms","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-10-27T21:03:48.89954Z","caller":"traceutil/trace.go:171","msg":"trace[2127117344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19483; }","duration":"406.83194ms","start":"2026-10-27T21:03:48.492699Z","end":"2026-10-27T21:03:48.899531Z","steps":["trace[2127117344] 'agreement among raft nodes before linearized reading' (duration: 406.746057ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.899215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.64252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-scl22\" ","response":"range_response_count:1 size:22090"} {"level":"warn","ts":"2026-10-27T21:03:48.899567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:48.492689Z","time spent":"406.87132ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-27T21:03:48.899582Z","caller":"traceutil/trace.go:171","msg":"trace[1839995055] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-scl22; range_end:; response_count:1; response_revision:19483; }","duration":"504.047661ms","start":"2026-10-27T21:03:48.395524Z","end":"2026-10-27T21:03:48.899572Z","steps":["trace[1839995055] 'agreement among raft nodes before linearized reading' (duration: 503.353753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.899231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:48.151884Z","time spent":"747.151209ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20996,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:03:48.899687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.046775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29885580-z587k\" ","response":"range_response_count:1 size:7037"} {"level":"info","ts":"2026-10-27T21:03:48.899723Z","caller":"traceutil/trace.go:171","msg":"trace[775812242] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29885580-z587k; range_end:; response_count:1; response_revision:19483; }","duration":"165.114666ms","start":"2026-10-27T21:03:48.7346Z","end":"2026-10-27T21:03:48.899715Z","steps":["trace[775812242] 'agreement among raft nodes before linearized reading' (duration: 165.026604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:48.899419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:48.450152Z","time spent":"449.259779ms","remote":"127.0.0.1:55008","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-10-27T21:03:48.899613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:48.395508Z","time spent":"504.094112ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22114,"request content":"key:\"/registry/pods/openstack/nova-compute-default-scl22\" "} {"level":"info","ts":"2026-10-27T21:05:26.157423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17651} {"level":"info","ts":"2026-10-27T21:05:26.280795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17651,"took":"119.990429ms","hash":2690170425,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":31547392,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-27T21:05:26.280886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2690170425,"revision":17651,"compact-revision":14627} {"level":"info","ts":"2026-10-27T21:05:44.558964Z","caller":"traceutil/trace.go:171","msg":"trace[602922002] linearizableReadLoop","detail":"{readStateIndex:21736; appliedIndex:21735; }","duration":"188.835478ms","start":"2026-10-27T21:05:44.37009Z","end":"2026-10-27T21:05:44.558925Z","steps":["trace[602922002] 'read index received' (duration: 188.585021ms)","trace[602922002] 'applied index is now lower than readState.Index' (duration: 248.587µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:05:44.559365Z","caller":"traceutil/trace.go:171","msg":"trace[403826326] transaction","detail":"{read_only:false; response_revision:20759; number_of_response:1; }","duration":"190.497951ms","start":"2026-10-27T21:05:44.368829Z","end":"2026-10-27T21:05:44.559327Z","steps":["trace[403826326] 'process raft request' (duration: 189.779623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:05:44.559505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.385522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-10-27T21:05:44.559577Z","caller":"traceutil/trace.go:171","msg":"trace[723415670] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:20759; }","duration":"189.484505ms","start":"2026-10-27T21:05:44.37007Z","end":"2026-10-27T21:05:44.559555Z","steps":["trace[723415670] 'agreement among raft nodes before linearized reading' (duration: 189.255999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:05:44.586486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.899815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-27T21:05:44.586631Z","caller":"traceutil/trace.go:171","msg":"trace[251021815] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:20760; }","duration":"104.114411ms","start":"2026-10-27T21:05:44.482499Z","end":"2026-10-27T21:05:44.586613Z","steps":["trace[251021815] 'agreement among raft nodes before linearized reading' (duration: 103.749272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:05:44.586512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.502781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-10-27T21:05:44.586856Z","caller":"traceutil/trace.go:171","msg":"trace[1108976229] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20760; }","duration":"106.831939ms","start":"2026-10-27T21:05:44.479955Z","end":"2026-10-27T21:05:44.586787Z","steps":["trace[1108976229] 'agreement among raft nodes before linearized reading' (duration: 106.302287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:05:44.587004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.183059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4799"} {"level":"info","ts":"2026-10-27T21:05:44.587062Z","caller":"traceutil/trace.go:171","msg":"trace[1954937105] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:20760; }","duration":"206.263522ms","start":"2026-10-27T21:05:44.380782Z","end":"2026-10-27T21:05:44.587045Z","steps":["trace[1954937105] 'agreement among raft nodes before linearized reading' (duration: 205.860291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:05:44.588149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.967456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"warn","ts":"2026-10-27T21:05:44.588205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.126695ms","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-10-27T21:05:44.588305Z","caller":"traceutil/trace.go:171","msg":"trace[1293987754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20760; }","duration":"132.254108ms","start":"2026-10-27T21:05:44.456022Z","end":"2026-10-27T21:05:44.588276Z","steps":["trace[1293987754] 'agreement among raft nodes before linearized reading' (duration: 130.541503ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:05:44.588227Z","caller":"traceutil/trace.go:171","msg":"trace[733640849] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:20760; }","duration":"197.10088ms","start":"2026-10-27T21:05:44.391104Z","end":"2026-10-27T21:05:44.588205Z","steps":["trace[733640849] 'agreement among raft nodes before linearized reading' (duration: 195.56265ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:10:26.166758Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20604} {"level":"info","ts":"2026-10-27T21:10:26.316067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20604,"took":"145.751662ms","hash":561209603,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":33615872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T21:10:26.316166Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":561209603,"revision":20604,"compact-revision":17651} {"level":"info","ts":"2026-10-27T21:11:19.062801Z","caller":"traceutil/trace.go:171","msg":"trace[551551305] transaction","detail":"{read_only:false; response_revision:23809; number_of_response:1; }","duration":"175.850399ms","start":"2026-10-27T21:11:18.886918Z","end":"2026-10-27T21:11:19.062768Z","steps":["trace[551551305] 'process raft request' (duration: 174.674689ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:11:34.825643Z","caller":"traceutil/trace.go:171","msg":"trace[1485951422] transaction","detail":"{read_only:false; response_revision:23914; number_of_response:1; }","duration":"143.010021ms","start":"2026-10-27T21:11:34.682605Z","end":"2026-10-27T21:11:34.825615Z","steps":["trace[1485951422] 'process raft request' (duration: 139.882262ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:11:40.199429Z","caller":"traceutil/trace.go:171","msg":"trace[1705778066] transaction","detail":"{read_only:false; response_revision:23950; number_of_response:1; }","duration":"147.156127ms","start":"2026-10-27T21:11:40.052242Z","end":"2026-10-27T21:11:40.199398Z","steps":["trace[1705778066] 'process raft request' (duration: 146.984853ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:11:40.53311Z","caller":"traceutil/trace.go:171","msg":"trace[414207392] linearizableReadLoop","detail":"{readStateIndex:25084; appliedIndex:25083; }","duration":"384.274435ms","start":"2026-10-27T21:11:40.148808Z","end":"2026-10-27T21:11:40.533082Z","steps":["trace[414207392] 'read index received' (duration: 50.592679ms)","trace[414207392] 'applied index is now lower than readState.Index' (duration: 333.680436ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:11:40.533154Z","caller":"traceutil/trace.go:171","msg":"trace[960468297] transaction","detail":"{read_only:false; response_revision:23951; number_of_response:1; }","duration":"455.213161ms","start":"2026-10-27T21:11:40.077882Z","end":"2026-10-27T21:11:40.533095Z","steps":["trace[960468297] 'process raft request' (duration: 387.749054ms)","trace[960468297] 'compare' (duration: 66.936475ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:11:40.533308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.077851Z","time spent":"455.382236ms","remote":"127.0.0.1:55188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16939,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:11:40.533445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.632934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-27T21:11:40.533585Z","caller":"traceutil/trace.go:171","msg":"trace[1214984391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23951; }","duration":"384.83392ms","start":"2026-10-27T21:11:40.148736Z","end":"2026-10-27T21:11:40.53357Z","steps":["trace[1214984391] 'agreement among raft nodes before linearized reading' (duration: 384.535432ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:40.533977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.148681Z","time spent":"385.278171ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-27T21:11:40.894093Z","caller":"traceutil/trace.go:171","msg":"trace[940335183] transaction","detail":"{read_only:false; number_of_response:0; response_revision:23952; }","duration":"353.906192ms","start":"2026-10-27T21:11:40.540164Z","end":"2026-10-27T21:11:40.89407Z","steps":["trace[940335183] 'process raft request' (duration: 353.783609ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:40.894225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.540146Z","time spent":"354.019825ms","remote":"127.0.0.1:55188","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":30,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-27T21:11:40.982083Z","caller":"traceutil/trace.go:171","msg":"trace[1249262387] linearizableReadLoop","detail":"{readStateIndex:25087; appliedIndex:25085; }","duration":"354.585849ms","start":"2026-10-27T21:11:40.627471Z","end":"2026-10-27T21:11:40.982057Z","steps":["trace[1249262387] 'read index received' (duration: 266.578999ms)","trace[1249262387] 'applied index is now lower than readState.Index' (duration: 88.00532ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:11:40.982215Z","caller":"traceutil/trace.go:171","msg":"trace[481797928] transaction","detail":"{read_only:false; response_revision:23953; number_of_response:1; }","duration":"364.291727ms","start":"2026-10-27T21:11:40.617913Z","end":"2026-10-27T21:11:40.982205Z","steps":["trace[481797928] 'process raft request' (duration: 363.887196ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:40.982329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.617895Z","time spent":"364.348558ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:11:40.982463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.852265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-10-27T21:11:40.982539Z","caller":"traceutil/trace.go:171","msg":"trace[875609866] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23953; }","duration":"127.982169ms","start":"2026-10-27T21:11:40.854537Z","end":"2026-10-27T21:11:40.98252Z","steps":["trace[875609866] 'agreement among raft nodes before linearized reading' (duration: 127.765433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:40.982657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.175964ms","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-10-27T21:11:40.982723Z","caller":"traceutil/trace.go:171","msg":"trace[1314988462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23953; }","duration":"355.288507ms","start":"2026-10-27T21:11:40.627416Z","end":"2026-10-27T21:11:40.982704Z","steps":["trace[1314988462] 'agreement among raft nodes before linearized reading' (duration: 355.000149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:40.982758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.627399Z","time spent":"355.351529ms","remote":"127.0.0.1:55008","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-10-27T21:11:41.332057Z","caller":"traceutil/trace.go:171","msg":"trace[1631947447] transaction","detail":"{read_only:false; response_revision:23954; number_of_response:1; }","duration":"345.368735ms","start":"2026-10-27T21:11:40.986621Z","end":"2026-10-27T21:11:41.331989Z","steps":["trace[1631947447] 'process raft request' (duration: 313.830832ms)","trace[1631947447] 'compare' (duration: 31.278286ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:11:41.332252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.986598Z","time spent":"345.55827ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:11:41.570077Z","caller":"traceutil/trace.go:171","msg":"trace[1099938704] transaction","detail":"{read_only:false; response_revision:23955; number_of_response:1; }","duration":"583.362025ms","start":"2026-10-27T21:11:40.98669Z","end":"2026-10-27T21:11:41.570052Z","steps":["trace[1099938704] 'process raft request' (duration: 515.152228ms)","trace[1099938704] 'compare' (duration: 67.94276ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:11:41.570228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.986665Z","time spent":"583.490238ms","remote":"127.0.0.1:55008","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-10-27T21:11:41.570521Z","caller":"traceutil/trace.go:171","msg":"trace[1498475031] linearizableReadLoop","detail":"{readStateIndex:25090; appliedIndex:25087; }","duration":"386.506592ms","start":"2026-10-27T21:11:41.183975Z","end":"2026-10-27T21:11:41.570481Z","steps":["trace[1498475031] 'read index received' (duration: 116.467105ms)","trace[1498475031] 'applied index is now lower than readState.Index' (duration: 270.034597ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:11:41.570632Z","caller":"traceutil/trace.go:171","msg":"trace[1017350103] transaction","detail":"{read_only:false; response_revision:23956; number_of_response:1; }","duration":"574.12622ms","start":"2026-10-27T21:11:40.996457Z","end":"2026-10-27T21:11:41.570583Z","steps":["trace[1017350103] 'process raft request' (duration: 573.507124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:41.570754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.761598ms","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":"warn","ts":"2026-10-27T21:11:41.570805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:40.996441Z","time spent":"574.259003ms","remote":"127.0.0.1:55188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17351,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:11:41.570823Z","caller":"traceutil/trace.go:171","msg":"trace[1261239876] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:23957; }","duration":"386.881781ms","start":"2026-10-27T21:11:41.183921Z","end":"2026-10-27T21:11:41.570803Z","steps":["trace[1261239876] 'agreement among raft nodes before linearized reading' (duration: 386.713237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:41.570837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.13934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-10-27T21:11:41.570865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:41.1839Z","time spent":"386.955084ms","remote":"127.0.0.1:54812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":88,"response size":32,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-10-27T21:11:41.570895Z","caller":"traceutil/trace.go:171","msg":"trace[1450533839] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23957; }","duration":"237.272451ms","start":"2026-10-27T21:11:41.333602Z","end":"2026-10-27T21:11:41.570875Z","steps":["trace[1450533839] 'agreement among raft nodes before linearized reading' (duration: 237.076546ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:11:41.89078Z","caller":"traceutil/trace.go:171","msg":"trace[1477388253] linearizableReadLoop","detail":"{readStateIndex:25093; appliedIndex:25091; }","duration":"302.028801ms","start":"2026-10-27T21:11:41.588728Z","end":"2026-10-27T21:11:41.890757Z","steps":["trace[1477388253] 'read index received' (duration: 6.384483ms)","trace[1477388253] 'applied index is now lower than readState.Index' (duration: 295.643119ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:11:41.890987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.237376ms","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-10-27T21:11:41.891028Z","caller":"traceutil/trace.go:171","msg":"trace[28809159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23959; }","duration":"302.338779ms","start":"2026-10-27T21:11:41.588683Z","end":"2026-10-27T21:11:41.891021Z","steps":["trace[28809159] 'agreement among raft nodes before linearized reading' (duration: 302.175745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:41.891065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:41.588666Z","time spent":"302.382249ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-27T21:11:41.891426Z","caller":"traceutil/trace.go:171","msg":"trace[99555533] transaction","detail":"{read_only:false; response_revision:23959; number_of_response:1; }","duration":"310.733813ms","start":"2026-10-27T21:11:41.580681Z","end":"2026-10-27T21:11:41.891415Z","steps":["trace[99555533] 'process raft request' (duration: 309.934863ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:41.89155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:41.58066Z","time spent":"310.798494ms","remote":"127.0.0.1:54868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:11:41.903935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.250933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-27T21:11:41.904025Z","caller":"traceutil/trace.go:171","msg":"trace[2004072820] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23960; }","duration":"305.356156ms","start":"2026-10-27T21:11:41.598627Z","end":"2026-10-27T21:11:41.903983Z","steps":["trace[2004072820] 'agreement among raft nodes before linearized reading' (duration: 305.15157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:41.904016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.929341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-10-27T21:11:41.904063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:41.598601Z","time spent":"305.453138ms","remote":"127.0.0.1:55008","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-10-27T21:11:41.904092Z","caller":"traceutil/trace.go:171","msg":"trace[2066388206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23960; }","duration":"264.097416ms","start":"2026-10-27T21:11:41.639974Z","end":"2026-10-27T21:11:41.904072Z","steps":["trace[2066388206] 'agreement among raft nodes before linearized reading' (duration: 263.754107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:41.903925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.723803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-10-27T21:11:41.904317Z","caller":"traceutil/trace.go:171","msg":"trace[967989999] transaction","detail":"{read_only:false; response_revision:23960; number_of_response:1; }","duration":"128.205176ms","start":"2026-10-27T21:11:41.776098Z","end":"2026-10-27T21:11:41.904303Z","steps":["trace[967989999] 'process raft request' (duration: 127.390195ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:11:41.904546Z","caller":"traceutil/trace.go:171","msg":"trace[1635542200] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23960; }","duration":"302.357669ms","start":"2026-10-27T21:11:41.602152Z","end":"2026-10-27T21:11:41.90451Z","steps":["trace[1635542200] 'agreement among raft nodes before linearized reading' (duration: 301.584589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:11:41.904616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:11:41.602137Z","time spent":"302.462413ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11738,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-27T21:15:25.955536Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c34.wal"} {"level":"info","ts":"2026-10-27T21:15:26.171708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23467} {"level":"info","ts":"2026-10-27T21:15:26.335863Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23467,"took":"160.150261ms","hash":2163612037,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":39624704,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-10-27T21:15:26.335913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2163612037,"revision":23467,"compact-revision":20604} {"level":"info","ts":"2026-10-27T21:15:34.056806Z","caller":"traceutil/trace.go:171","msg":"trace[497552943] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"120.071429ms","start":"2026-10-27T21:15:33.936707Z","end":"2026-10-27T21:15:34.056778Z","steps":["trace[497552943] 'process raft request' (duration: 119.860463ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:15:34.065181Z","caller":"traceutil/trace.go:171","msg":"trace[1873160967] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"118.079919ms","start":"2026-10-27T21:15:33.947081Z","end":"2026-10-27T21:15:34.065161Z","steps":["trace[1873160967] 'process raft request' (duration: 117.953425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:15:34.52031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.751997ms","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":"warn","ts":"2026-10-27T21:15:34.520358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.369641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-10-27T21:15:34.520416Z","caller":"traceutil/trace.go:171","msg":"trace[1120797431] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26722; }","duration":"246.87017ms","start":"2026-10-27T21:15:34.273521Z","end":"2026-10-27T21:15:34.520391Z","steps":["trace[1120797431] 'count revisions from in-memory index tree' (duration: 246.682115ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:15:34.520434Z","caller":"traceutil/trace.go:171","msg":"trace[932867136] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26722; }","duration":"389.503894ms","start":"2026-10-27T21:15:34.130911Z","end":"2026-10-27T21:15:34.520415Z","steps":["trace[932867136] 'range keys from in-memory index tree' (duration: 389.153435ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:15:34.520484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:15:34.13089Z","time spent":"389.578396ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11738,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-27T21:17:05.461257Z","caller":"traceutil/trace.go:171","msg":"trace[444679204] transaction","detail":"{read_only:false; response_revision:27822; number_of_response:1; }","duration":"145.788815ms","start":"2026-10-27T21:17:05.315437Z","end":"2026-10-27T21:17:05.461226Z","steps":["trace[444679204] 'process raft request' (duration: 113.831891ms)","trace[444679204] 'compare' (duration: 31.739668ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:18:27.817746Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T21:18:27.823581Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-10-27T21:18:27.82371Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"warn","ts":"2026-10-27T21:19:03.50682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.385345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:19:03.506899Z","caller":"traceutil/trace.go:171","msg":"trace[2031582894] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:29059; }","duration":"220.504968ms","start":"2026-10-27T21:19:03.286375Z","end":"2026-10-27T21:19:03.50688Z","steps":["trace[2031582894] 'count revisions from in-memory index tree' (duration: 220.320353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.506862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:03.205472Z","time spent":"301.382438ms","remote":"127.0.0.1:54906","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-10-27T21:19:03.506972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.094347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-27T21:19:03.507075Z","caller":"traceutil/trace.go:171","msg":"trace[2035547198] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29059; }","duration":"302.23389ms","start":"2026-10-27T21:19:03.204819Z","end":"2026-10-27T21:19:03.507053Z","steps":["trace[2035547198] 'range keys from in-memory index tree' (duration: 301.921373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.507129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:03.204803Z","time spent":"302.313813ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-27T21:19:03.849881Z","caller":"traceutil/trace.go:171","msg":"trace[60374507] transaction","detail":"{read_only:false; response_revision:29060; number_of_response:1; }","duration":"450.253212ms","start":"2026-10-27T21:19:03.399601Z","end":"2026-10-27T21:19:03.849854Z","steps":["trace[60374507] 'process raft request' (duration: 450.083158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.850051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:03.399578Z","time spent":"450.390695ms","remote":"127.0.0.1:60896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:19:03.937457Z","caller":"traceutil/trace.go:171","msg":"trace[132549240] transaction","detail":"{read_only:false; response_revision:29061; number_of_response:1; }","duration":"425.782348ms","start":"2026-10-27T21:19:03.511639Z","end":"2026-10-27T21:19:03.937421Z","steps":["trace[132549240] 'process raft request' (duration: 424.009133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.937655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:03.511605Z","time spent":"425.951453ms","remote":"127.0.0.1:55008","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-10-27T21:19:03.937649Z","caller":"traceutil/trace.go:171","msg":"trace[784914004] transaction","detail":"{read_only:false; response_revision:29062; number_of_response:1; }","duration":"422.505996ms","start":"2026-10-27T21:19:03.515114Z","end":"2026-10-27T21:19:03.93762Z","steps":["trace[784914004] 'process raft request' (duration: 422.23854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.937804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:03.515079Z","time spent":"422.635238ms","remote":"127.0.0.1:55008","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-10-27T21:19:03.93804Z","caller":"traceutil/trace.go:171","msg":"trace[584661305] linearizableReadLoop","detail":"{readStateIndex:30408; appliedIndex:30405; }","duration":"409.936574ms","start":"2026-10-27T21:19:03.528028Z","end":"2026-10-27T21:19:03.937965Z","steps":["trace[584661305] 'read index received' (duration: 321.656866ms)","trace[584661305] 'applied index is now lower than readState.Index' (duration: 88.278178ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:19:03.938091Z","caller":"traceutil/trace.go:171","msg":"trace[880008810] transaction","detail":"{read_only:false; response_revision:29063; number_of_response:1; }","duration":"122.50433ms","start":"2026-10-27T21:19:03.815543Z","end":"2026-10-27T21:19:03.938048Z","steps":["trace[880008810] 'process raft request' (duration: 122.007489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.938321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.298173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:19:03.938414Z","caller":"traceutil/trace.go:171","msg":"trace[908215008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29063; }","duration":"410.396245ms","start":"2026-10-27T21:19:03.528003Z","end":"2026-10-27T21:19:03.938399Z","steps":["trace[908215008] 'agreement among raft nodes before linearized reading' (duration: 410.257602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.938451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:03.527896Z","time spent":"410.54715ms","remote":"127.0.0.1:54738","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-10-27T21:19:03.938864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.210921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-27T21:19:03.939071Z","caller":"traceutil/trace.go:171","msg":"trace[1701576240] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:29063; }","duration":"326.437916ms","start":"2026-10-27T21:19:03.612571Z","end":"2026-10-27T21:19:03.939009Z","steps":["trace[1701576240] 'agreement among raft nodes before linearized reading' (duration: 326.020425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:03.93922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:19:03.61254Z","time spent":"326.657022ms","remote":"127.0.0.1:55916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":172,"response size":33,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-10-27T21:19:04.341627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.950917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-10-27T21:19:04.341662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.487495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-10-27T21:19:04.341714Z","caller":"traceutil/trace.go:171","msg":"trace[1671340932] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29063; }","duration":"278.588658ms","start":"2026-10-27T21:19:04.063112Z","end":"2026-10-27T21:19:04.3417Z","steps":["trace[1671340932] 'range keys from in-memory index tree' (duration: 278.263549ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:19:04.341716Z","caller":"traceutil/trace.go:171","msg":"trace[1507869319] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29063; }","duration":"195.0876ms","start":"2026-10-27T21:19:04.146606Z","end":"2026-10-27T21:19:04.341693Z","steps":["trace[1507869319] 'range keys from in-memory index tree' (duration: 194.761232ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:19:04.341859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.478943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-10-27T21:19:04.341854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.083501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:19:04.341917Z","caller":"traceutil/trace.go:171","msg":"trace[340003995] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29063; }","duration":"252.572726ms","start":"2026-10-27T21:19:04.089331Z","end":"2026-10-27T21:19:04.341904Z","steps":["trace[340003995] 'range keys from in-memory index tree' (duration: 252.34608ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:19:04.342053Z","caller":"traceutil/trace.go:171","msg":"trace[1945351577] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:29063; }","duration":"270.218454ms","start":"2026-10-27T21:19:04.071693Z","end":"2026-10-27T21:19:04.341911Z","steps":["trace[1945351577] 'count revisions from in-memory index tree' (duration: 269.983759ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:02.947001Z","caller":"traceutil/trace.go:171","msg":"trace[327707550] linearizableReadLoop","detail":"{readStateIndex:31079; appliedIndex:31078; }","duration":"173.192572ms","start":"2026-10-27T21:20:02.773773Z","end":"2026-10-27T21:20:02.946966Z","steps":["trace[327707550] 'read index received' (duration: 157.938374ms)","trace[327707550] 'applied index is now lower than readState.Index' (duration: 15.248288ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:20:02.947047Z","caller":"traceutil/trace.go:171","msg":"trace[153129423] transaction","detail":"{read_only:false; response_revision:29709; number_of_response:1; }","duration":"177.952943ms","start":"2026-10-27T21:20:02.769041Z","end":"2026-10-27T21:20:02.946994Z","steps":["trace[153129423] 'process raft request' (duration: 162.647914ms)","trace[153129423] 'compare' (duration: 14.842198ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:20:02.947424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.624273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-10-27T21:20:02.947519Z","caller":"traceutil/trace.go:171","msg":"trace[1566465169] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29710; }","duration":"173.769697ms","start":"2026-10-27T21:20:02.773725Z","end":"2026-10-27T21:20:02.947495Z","steps":["trace[1566465169] 'agreement among raft nodes before linearized reading' (duration: 173.449069ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:03.603242Z","caller":"traceutil/trace.go:171","msg":"trace[620413155] transaction","detail":"{read_only:false; response_revision:29719; number_of_response:1; }","duration":"255.08798ms","start":"2026-10-27T21:20:03.348053Z","end":"2026-10-27T21:20:03.603141Z","steps":["trace[620413155] 'process raft request' (duration: 254.857494ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:04.264274Z","caller":"traceutil/trace.go:171","msg":"trace[220947992] transaction","detail":"{read_only:false; response_revision:29720; number_of_response:1; }","duration":"223.812052ms","start":"2026-10-27T21:20:04.040437Z","end":"2026-10-27T21:20:04.264249Z","steps":["trace[220947992] 'process raft request' (duration: 223.590216ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:04.515476Z","caller":"traceutil/trace.go:171","msg":"trace[2136156246] linearizableReadLoop","detail":"{readStateIndex:31093; appliedIndex:31092; }","duration":"191.424367ms","start":"2026-10-27T21:20:04.32402Z","end":"2026-10-27T21:20:04.515444Z","steps":["trace[2136156246] 'read index received' (duration: 189.296603ms)","trace[2136156246] 'applied index is now lower than readState.Index' (duration: 2.126174ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:20:04.515542Z","caller":"traceutil/trace.go:171","msg":"trace[1227886383] transaction","detail":"{read_only:false; response_revision:29722; number_of_response:1; }","duration":"224.342567ms","start":"2026-10-27T21:20:04.291172Z","end":"2026-10-27T21:20:04.515515Z","steps":["trace[1227886383] 'process raft request' (duration: 222.09381ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:04.515656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.609532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:20:04.515703Z","caller":"traceutil/trace.go:171","msg":"trace[1361373952] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:29722; }","duration":"191.707074ms","start":"2026-10-27T21:20:04.323982Z","end":"2026-10-27T21:20:04.515689Z","steps":["trace[1361373952] 'agreement among raft nodes before linearized reading' (duration: 191.583711ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:05.137748Z","caller":"traceutil/trace.go:171","msg":"trace[1612473781] transaction","detail":"{read_only:false; response_revision:29723; number_of_response:1; }","duration":"510.321693ms","start":"2026-10-27T21:20:04.627385Z","end":"2026-10-27T21:20:05.137707Z","steps":["trace[1612473781] 'process raft request' (duration: 510.075326ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:05.13798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:04.627359Z","time spent":"510.493898ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:20:05.177996Z","caller":"traceutil/trace.go:171","msg":"trace[1869869982] transaction","detail":"{read_only:false; response_revision:29724; number_of_response:1; }","duration":"549.342406ms","start":"2026-10-27T21:20:04.62863Z","end":"2026-10-27T21:20:05.177973Z","steps":["trace[1869869982] 'process raft request' (duration: 549.168953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:05.178185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:04.628596Z","time spent":"549.505851ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:20:05.178406Z","caller":"traceutil/trace.go:171","msg":"trace[458768508] transaction","detail":"{read_only:false; response_revision:29725; number_of_response:1; }","duration":"223.768802ms","start":"2026-10-27T21:20:04.9546Z","end":"2026-10-27T21:20:05.178369Z","steps":["trace[458768508] 'process raft request' (duration: 223.32065ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:05.178522Z","caller":"traceutil/trace.go:171","msg":"trace[2002404394] linearizableReadLoop","detail":"{readStateIndex:31095; appliedIndex:31093; }","duration":"451.134615ms","start":"2026-10-27T21:20:04.727203Z","end":"2026-10-27T21:20:05.178337Z","steps":["trace[2002404394] 'read index received' (duration: 410.294444ms)","trace[2002404394] 'applied index is now lower than readState.Index' (duration: 40.834841ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:20:05.178605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.394692ms","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-10-27T21:20:05.178849Z","caller":"traceutil/trace.go:171","msg":"trace[2138656118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29725; }","duration":"451.714798ms","start":"2026-10-27T21:20:04.727104Z","end":"2026-10-27T21:20:05.178819Z","steps":["trace[2138656118] 'agreement among raft nodes before linearized reading' (duration: 451.326008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:05.178964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.032443ms","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-10-27T21:20:05.178964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.921496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-10-27T21:20:05.17889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.139904ms","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-10-27T21:20:05.179022Z","caller":"traceutil/trace.go:171","msg":"trace[1064305336] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29725; }","duration":"226.014628ms","start":"2026-10-27T21:20:04.952991Z","end":"2026-10-27T21:20:05.179006Z","steps":["trace[1064305336] 'agreement among raft nodes before linearized reading' (duration: 225.825023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:05.178899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.721554ms","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-10-27T21:20:05.179056Z","caller":"traceutil/trace.go:171","msg":"trace[1027405727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29725; }","duration":"212.318889ms","start":"2026-10-27T21:20:04.966699Z","end":"2026-10-27T21:20:05.179018Z","steps":["trace[1027405727] 'agreement among raft nodes before linearized reading' (duration: 212.083083ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:05.17912Z","caller":"traceutil/trace.go:171","msg":"trace[267104869] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:29725; }","duration":"366.972001ms","start":"2026-10-27T21:20:04.812134Z","end":"2026-10-27T21:20:05.179106Z","steps":["trace[267104869] 'agreement among raft nodes before linearized reading' (duration: 366.701994ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:05.17915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:04.812115Z","time spent":"367.025241ms","remote":"127.0.0.1:55008","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":"info","ts":"2026-10-27T21:20:05.179152Z","caller":"traceutil/trace.go:171","msg":"trace[1781027125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29725; }","duration":"411.104205ms","start":"2026-10-27T21:20:04.767889Z","end":"2026-10-27T21:20:05.178994Z","steps":["trace[1781027125] 'agreement among raft nodes before linearized reading' (duration: 410.883439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:20:05.178974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:04.727054Z","time spent":"451.885413ms","remote":"127.0.0.1:55008","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":"warn","ts":"2026-10-27T21:20:05.179241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:20:04.767868Z","time spent":"411.32792ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-27T21:20:24.472295Z","caller":"traceutil/trace.go:171","msg":"trace[423801367] transaction","detail":"{read_only:false; response_revision:29935; number_of_response:1; }","duration":"121.68098ms","start":"2026-10-27T21:20:24.350583Z","end":"2026-10-27T21:20:24.472264Z","steps":["trace[423801367] 'process raft request' (duration: 119.409232ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:20:26.190536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26474} {"level":"info","ts":"2026-10-27T21:20:26.380668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26474,"took":"184.646805ms","hash":720296532,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":46202880,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-27T21:20:26.380729Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":720296532,"revision":26474,"compact-revision":23467} {"level":"info","ts":"2026-10-27T21:21:01.579116Z","caller":"traceutil/trace.go:171","msg":"trace[1267759604] transaction","detail":"{read_only:false; response_revision:30426; number_of_response:1; }","duration":"150.455473ms","start":"2026-10-27T21:21:01.428591Z","end":"2026-10-27T21:21:01.579047Z","steps":["trace[1267759604] 'process raft request' (duration: 137.791541ms)","trace[1267759604] 'compare' (duration: 12.321283ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:21:01.582546Z","caller":"traceutil/trace.go:171","msg":"trace[275378843] transaction","detail":"{read_only:false; response_revision:30427; number_of_response:1; }","duration":"153.04594ms","start":"2026-10-27T21:21:01.429463Z","end":"2026-10-27T21:21:01.582509Z","steps":["trace[275378843] 'process raft request' (duration: 152.677401ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:01.582563Z","caller":"traceutil/trace.go:171","msg":"trace[1582503262] linearizableReadLoop","detail":"{readStateIndex:31824; appliedIndex:31822; }","duration":"130.013924ms","start":"2026-10-27T21:21:01.452505Z","end":"2026-10-27T21:21:01.582519Z","steps":["trace[1582503262] 'read index received' (duration: 113.895893ms)","trace[1582503262] 'applied index is now lower than readState.Index' (duration: 16.11072ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:21:01.582704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.180497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:21:01.582772Z","caller":"traceutil/trace.go:171","msg":"trace[1182784941] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:30427; }","duration":"130.317762ms","start":"2026-10-27T21:21:01.452436Z","end":"2026-10-27T21:21:01.582754Z","steps":["trace[1182784941] 'agreement among raft nodes before linearized reading' (duration: 130.172138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:01.909029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.667625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T21:21:01.909141Z","caller":"traceutil/trace.go:171","msg":"trace[1247139760] linearizableReadLoop","detail":"{readStateIndex:31825; appliedIndex:31824; }","duration":"284.233752ms","start":"2026-10-27T21:21:01.624889Z","end":"2026-10-27T21:21:01.909122Z","steps":["trace[1247139760] 'read index received' (duration: 152.361692ms)","trace[1247139760] 'applied index is now lower than readState.Index' (duration: 131.87046ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:21:01.909209Z","caller":"traceutil/trace.go:171","msg":"trace[991343553] transaction","detail":"{read_only:false; response_revision:30428; number_of_response:1; }","duration":"303.681257ms","start":"2026-10-27T21:21:01.605518Z","end":"2026-10-27T21:21:01.909199Z","steps":["trace[991343553] 'process raft request' (duration: 171.755655ms)","trace[991343553] 'compare' (duration: 131.315126ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:21:01.90927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:01.605493Z","time spent":"303.745979ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:21:01.909393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.507778ms","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-10-27T21:21:01.909471Z","caller":"traceutil/trace.go:171","msg":"trace[123316771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:30428; }","duration":"284.624832ms","start":"2026-10-27T21:21:01.624824Z","end":"2026-10-27T21:21:01.909449Z","steps":["trace[123316771] 'agreement among raft nodes before linearized reading' (duration: 284.424697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:01.982495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.828528ms","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":"warn","ts":"2026-10-27T21:21:01.982648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.964063ms","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-10-27T21:21:01.982707Z","caller":"traceutil/trace.go:171","msg":"trace[1071043217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:30429; }","duration":"269.064505ms","start":"2026-10-27T21:21:01.713626Z","end":"2026-10-27T21:21:01.98269Z","steps":["trace[1071043217] 'agreement among raft nodes before linearized reading' (duration: 268.906891ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:01.982698Z","caller":"traceutil/trace.go:171","msg":"trace[350844700] 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:30429; }","duration":"245.090336ms","start":"2026-10-27T21:21:01.737578Z","end":"2026-10-27T21:21:01.982668Z","steps":["trace[350844700] 'agreement among raft nodes before linearized reading' (duration: 244.760297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:01.982777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.970159ms","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-10-27T21:21:01.982837Z","caller":"traceutil/trace.go:171","msg":"trace[1049526266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:30429; }","duration":"261.051472ms","start":"2026-10-27T21:21:01.721771Z","end":"2026-10-27T21:21:01.982822Z","steps":["trace[1049526266] 'agreement among raft nodes before linearized reading' (duration: 260.807255ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:01.983162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.383044ms","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-10-27T21:21:01.983211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.191165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-27T21:21:01.983268Z","caller":"traceutil/trace.go:171","msg":"trace[1269934312] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:30429; }","duration":"323.284097ms","start":"2026-10-27T21:21:01.659971Z","end":"2026-10-27T21:21:01.983255Z","steps":["trace[1269934312] 'agreement among raft nodes before linearized reading' (duration: 322.357503ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:01.983277Z","caller":"traceutil/trace.go:171","msg":"trace[1506991446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:30429; }","duration":"269.567849ms","start":"2026-10-27T21:21:01.713684Z","end":"2026-10-27T21:21:01.983252Z","steps":["trace[1506991446] 'agreement among raft nodes before linearized reading' (duration: 268.83107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:01.983297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:01.659954Z","time spent":"323.333959ms","remote":"127.0.0.1:54946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":91,"response size":32,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-10-27T21:21:02.146963Z","caller":"traceutil/trace.go:171","msg":"trace[1434407024] transaction","detail":"{read_only:false; response_revision:30430; number_of_response:1; }","duration":"233.10735ms","start":"2026-10-27T21:21:01.913791Z","end":"2026-10-27T21:21:02.146898Z","steps":["trace[1434407024] 'process raft request' (duration: 145.99304ms)","trace[1434407024] 'compare' (duration: 86.261578ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:21:02.14722Z","caller":"traceutil/trace.go:171","msg":"trace[1915402362] transaction","detail":"{read_only:false; response_revision:30433; number_of_response:1; }","duration":"158.742404ms","start":"2026-10-27T21:21:01.98847Z","end":"2026-10-27T21:21:02.147213Z","steps":["trace[1915402362] 'process raft request' (duration: 158.456196ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:02.147205Z","caller":"traceutil/trace.go:171","msg":"trace[192993233] transaction","detail":"{read_only:false; response_revision:30431; number_of_response:1; }","duration":"159.604726ms","start":"2026-10-27T21:21:01.987574Z","end":"2026-10-27T21:21:02.147178Z","steps":["trace[192993233] 'process raft request' (duration: 159.236226ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:02.146996Z","caller":"traceutil/trace.go:171","msg":"trace[1924501422] linearizableReadLoop","detail":"{readStateIndex:31830; appliedIndex:31826; }","duration":"156.054916ms","start":"2026-10-27T21:21:01.99092Z","end":"2026-10-27T21:21:02.146975Z","steps":["trace[1924501422] 'read index received' (duration: 68.898385ms)","trace[1924501422] 'applied index is now lower than readState.Index' (duration: 87.155491ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:21:02.147157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.235621ms","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-10-27T21:21:02.147504Z","caller":"traceutil/trace.go:171","msg":"trace[579418560] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:30433; }","duration":"156.59999ms","start":"2026-10-27T21:21:01.990854Z","end":"2026-10-27T21:21:02.147454Z","steps":["trace[579418560] 'agreement among raft nodes before linearized reading' (duration: 156.183859ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:02.147197Z","caller":"traceutil/trace.go:171","msg":"trace[255791558] transaction","detail":"{read_only:false; response_revision:30432; number_of_response:1; }","duration":"158.823316ms","start":"2026-10-27T21:21:01.988365Z","end":"2026-10-27T21:21:02.147188Z","steps":["trace[255791558] 'process raft request' (duration: 158.531239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:24.982812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.719763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T21:21:24.982869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.376075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:24.982954Z","caller":"traceutil/trace.go:171","msg":"trace[61796076] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:30634; }","duration":"167.900018ms","start":"2026-10-27T21:21:24.815017Z","end":"2026-10-27T21:21:24.982917Z","steps":["trace[61796076] 'range keys from in-memory index tree' (duration: 167.595531ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:24.982975Z","caller":"traceutil/trace.go:171","msg":"trace[1204442186] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:30634; }","duration":"167.528179ms","start":"2026-10-27T21:21:24.815427Z","end":"2026-10-27T21:21:24.982955Z","steps":["trace[1204442186] 'range keys from in-memory index tree' (duration: 167.280961ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:24.982989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.657796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T21:21:24.983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.382967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-27T21:21:24.983124Z","caller":"traceutil/trace.go:171","msg":"trace[1677687082] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:30634; }","duration":"162.544371ms","start":"2026-10-27T21:21:24.820555Z","end":"2026-10-27T21:21:24.9831Z","steps":["trace[1677687082] 'range keys from in-memory index tree' (duration: 162.175392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:24.983216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.097617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-10-27T21:21:24.983236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.090287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-27T21:21:24.982905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.733647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:24.983276Z","caller":"traceutil/trace.go:171","msg":"trace[935125648] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:30634; }","duration":"125.163989ms","start":"2026-10-27T21:21:24.858099Z","end":"2026-10-27T21:21:24.983263Z","steps":["trace[935125648] 'range keys from in-memory index tree' (duration: 125.013555ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:24.983272Z","caller":"traceutil/trace.go:171","msg":"trace[603094802] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:30634; }","duration":"125.18482ms","start":"2026-10-27T21:21:24.858074Z","end":"2026-10-27T21:21:24.983258Z","steps":["trace[603094802] 'range keys from in-memory index tree' (duration: 124.911172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:24.983285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.203556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:24.983058Z","caller":"traceutil/trace.go:171","msg":"trace[2016259475] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:30634; }","duration":"124.758408ms","start":"2026-10-27T21:21:24.858282Z","end":"2026-10-27T21:21:24.98304Z","steps":["trace[2016259475] 'range keys from in-memory index tree' (duration: 124.524483ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:24.983377Z","caller":"traceutil/trace.go:171","msg":"trace[138265561] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:30634; }","duration":"168.320969ms","start":"2026-10-27T21:21:24.815037Z","end":"2026-10-27T21:21:24.983358Z","steps":["trace[138265561] 'range keys from in-memory index tree' (duration: 168.117714ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:24.983499Z","caller":"traceutil/trace.go:171","msg":"trace[791719076] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:30634; }","duration":"125.22074ms","start":"2026-10-27T21:21:24.858061Z","end":"2026-10-27T21:21:24.983282Z","steps":["trace[791719076] 'range keys from in-memory index tree' (duration: 124.635265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:25.771917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.264983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:25.772007Z","caller":"traceutil/trace.go:171","msg":"trace[778746792] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:30635; }","duration":"472.455977ms","start":"2026-10-27T21:21:25.299525Z","end":"2026-10-27T21:21:25.771981Z","steps":["trace[778746792] 'count revisions from in-memory index tree' (duration: 472.101438ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:25.772069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:25.299506Z","time spent":"472.54263ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-10-27T21:21:26.150335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.704609ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-27T21:21:26.150991Z","caller":"traceutil/trace.go:171","msg":"trace[1495716598] transaction","detail":"{read_only:false; response_revision:30638; number_of_response:1; }","duration":"464.921356ms","start":"2026-10-27T21:21:25.68605Z","end":"2026-10-27T21:21:26.150971Z","steps":["trace[1495716598] 'process raft request' (duration: 464.873375ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:26.150983Z","caller":"traceutil/trace.go:171","msg":"trace[1012653069] transaction","detail":"{read_only:false; response_revision:30636; number_of_response:1; }","duration":"608.091224ms","start":"2026-10-27T21:21:25.542851Z","end":"2026-10-27T21:21:26.150942Z","steps":["trace[1012653069] 'process raft request' (duration: 607.855678ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:26.151078Z","caller":"traceutil/trace.go:171","msg":"trace[958372792] linearizableReadLoop","detail":"{readStateIndex:32042; appliedIndex:32040; }","duration":"497.983048ms","start":"2026-10-27T21:21:25.653037Z","end":"2026-10-27T21:21:26.15102Z","steps":["trace[958372792] 'read index received' (duration: 187.880347ms)","trace[958372792] 'applied index is now lower than readState.Index' (duration: 310.080371ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:21:26.151098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:25.68602Z","time spent":"465.017128ms","remote":"127.0.0.1:55008","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-10-27T21:21:26.151171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:25.542825Z","time spent":"608.239727ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:21:26.151239Z","caller":"traceutil/trace.go:171","msg":"trace[160188502] transaction","detail":"{read_only:false; response_revision:30637; number_of_response:1; }","duration":"481.389876ms","start":"2026-10-27T21:21:25.669839Z","end":"2026-10-27T21:21:26.151228Z","steps":["trace[160188502] 'process raft request' (duration: 481.002536ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.151297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:25.669811Z","time spent":"481.457127ms","remote":"127.0.0.1:55008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:21:26.151299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.269025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11714"} {"level":"info","ts":"2026-10-27T21:21:26.151334Z","caller":"traceutil/trace.go:171","msg":"trace[1502087444] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:30638; }","duration":"498.350797ms","start":"2026-10-27T21:21:25.652972Z","end":"2026-10-27T21:21:26.151323Z","steps":["trace[1502087444] 'agreement among raft nodes before linearized reading' (duration: 498.180523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.151362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:25.652948Z","time spent":"498.407559ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11738,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-27T21:21:26.174104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.229711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:26.174204Z","caller":"traceutil/trace.go:171","msg":"trace[857968940] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:30639; }","duration":"501.366264ms","start":"2026-10-27T21:21:25.672811Z","end":"2026-10-27T21:21:26.174177Z","steps":["trace[857968940] 'agreement among raft nodes before linearized reading' (duration: 501.19701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.174264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:21:25.6728Z","time spent":"501.447806ms","remote":"127.0.0.1:45658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-27T21:21:26.174187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.833304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-27T21:21:26.174478Z","caller":"traceutil/trace.go:171","msg":"trace[306160102] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:30639; }","duration":"131.161342ms","start":"2026-10-27T21:21:26.043281Z","end":"2026-10-27T21:21:26.174442Z","steps":["trace[306160102] 'agreement among raft nodes before linearized reading' (duration: 130.729951ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:26.369948Z","caller":"traceutil/trace.go:171","msg":"trace[753328169] linearizableReadLoop","detail":"{readStateIndex:32046; appliedIndex:32045; }","duration":"143.518587ms","start":"2026-10-27T21:21:26.226407Z","end":"2026-10-27T21:21:26.369926Z","steps":["trace[753328169] 'read index received' (duration: 143.371503ms)","trace[753328169] 'applied index is now lower than readState.Index' (duration: 145.844µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:21:26.370086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.665021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:26.370117Z","caller":"traceutil/trace.go:171","msg":"trace[5848516] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:30640; }","duration":"143.779144ms","start":"2026-10-27T21:21:26.226327Z","end":"2026-10-27T21:21:26.370106Z","steps":["trace[5848516] 'agreement among raft nodes before linearized reading' (duration: 143.710712ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:26.370095Z","caller":"traceutil/trace.go:171","msg":"trace[1648245681] transaction","detail":"{read_only:false; response_revision:30640; number_of_response:1; }","duration":"189.92606ms","start":"2026-10-27T21:21:26.180132Z","end":"2026-10-27T21:21:26.370058Z","steps":["trace[1648245681] 'process raft request' (duration: 189.609631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.405837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.36522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:26.405967Z","caller":"traceutil/trace.go:171","msg":"trace[527625694] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:30641; }","duration":"179.614446ms","start":"2026-10-27T21:21:26.226327Z","end":"2026-10-27T21:21:26.405942Z","steps":["trace[527625694] 'agreement among raft nodes before linearized reading' (duration: 179.412541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.406071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.72535ms","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-10-27T21:21:26.40607Z","caller":"traceutil/trace.go:171","msg":"trace[1401536646] transaction","detail":"{read_only:false; response_revision:30641; number_of_response:1; }","duration":"170.469393ms","start":"2026-10-27T21:21:26.235552Z","end":"2026-10-27T21:21:26.406021Z","steps":["trace[1401536646] 'process raft request' (duration: 170.014692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.406092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.667368ms","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-10-27T21:21:26.40616Z","caller":"traceutil/trace.go:171","msg":"trace[441810325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:30641; }","duration":"119.833273ms","start":"2026-10-27T21:21:26.286297Z","end":"2026-10-27T21:21:26.40613Z","steps":["trace[441810325] 'agreement among raft nodes before linearized reading' (duration: 119.623688ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.406189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.492272ms","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-10-27T21:21:26.406195Z","caller":"traceutil/trace.go:171","msg":"trace[514451524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:30641; }","duration":"119.832683ms","start":"2026-10-27T21:21:26.286337Z","end":"2026-10-27T21:21:26.40617Z","steps":["trace[514451524] 'agreement among raft nodes before linearized reading' (duration: 119.481984ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:26.40623Z","caller":"traceutil/trace.go:171","msg":"trace[232649626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:30641; }","duration":"113.544983ms","start":"2026-10-27T21:21:26.292669Z","end":"2026-10-27T21:21:26.406214Z","steps":["trace[232649626] 'agreement among raft nodes before linearized reading' (duration: 113.455171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.40607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.324453ms","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-10-27T21:21:26.406261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.825982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-27T21:21:26.406316Z","caller":"traceutil/trace.go:171","msg":"trace[362810282] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:30641; }","duration":"159.911415ms","start":"2026-10-27T21:21:26.24639Z","end":"2026-10-27T21:21:26.406302Z","steps":["trace[362810282] 'agreement among raft nodes before linearized reading' (duration: 159.827883ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:21:26.406316Z","caller":"traceutil/trace.go:171","msg":"trace[1150661147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:30641; }","duration":"125.60167ms","start":"2026-10-27T21:21:26.280678Z","end":"2026-10-27T21:21:26.40628Z","steps":["trace[1150661147] 'agreement among raft nodes before linearized reading' (duration: 125.22341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:21:26.406426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.111545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-10-27T21:21:26.406462Z","caller":"traceutil/trace.go:171","msg":"trace[2112866817] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:30641; }","duration":"170.172677ms","start":"2026-10-27T21:21:26.23628Z","end":"2026-10-27T21:21:26.406452Z","steps":["trace[2112866817] 'agreement among raft nodes before linearized reading' (duration: 170.053754ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:25:26.198913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29951} {"level":"info","ts":"2026-10-27T21:25:26.372876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29951,"took":"169.886423ms","hash":3746610438,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":38051840,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-27T21:25:26.372988Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3746610438,"revision":29951,"compact-revision":26474} {"level":"info","ts":"2026-10-27T21:30:26.207125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33262} {"level":"info","ts":"2026-10-27T21:30:26.370723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33262,"took":"158.694207ms","hash":3156208158,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":35094528,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-27T21:30:26.370794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3156208158,"revision":33262,"compact-revision":29951} {"level":"info","ts":"2026-10-27T21:34:50.937148Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T21:34:50.944587Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-10-27T21:34:50.944881Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-10-27T21:35:26.221707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35933} {"level":"info","ts":"2026-10-27T21:35:26.366326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35933,"took":"140.568584ms","hash":2525790460,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":34807808,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-27T21:35:26.366424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2525790460,"revision":35933,"compact-revision":33262} {"level":"info","ts":"2026-10-27T21:40:25.32212Z","caller":"traceutil/trace.go:171","msg":"trace[1023471142] linearizableReadLoop","detail":"{readStateIndex:43223; appliedIndex:43222; }","duration":"147.014179ms","start":"2026-10-27T21:40:25.17508Z","end":"2026-10-27T21:40:25.322094Z","steps":["trace[1023471142] 'read index received' (duration: 146.847575ms)","trace[1023471142] 'applied index is now lower than readState.Index' (duration: 165.204µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:40:25.3222Z","caller":"traceutil/trace.go:171","msg":"trace[1318299422] transaction","detail":"{read_only:false; response_revision:41340; number_of_response:1; }","duration":"147.278325ms","start":"2026-10-27T21:40:25.174865Z","end":"2026-10-27T21:40:25.322143Z","steps":["trace[1318299422] 'process raft request' (duration: 147.05843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:40:25.322443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.292771ms","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-10-27T21:40:25.322533Z","caller":"traceutil/trace.go:171","msg":"trace[182905341] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:41340; }","duration":"127.416615ms","start":"2026-10-27T21:40:25.195094Z","end":"2026-10-27T21:40:25.322511Z","steps":["trace[182905341] 'agreement among raft nodes before linearized reading' (duration: 127.284781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:40:25.322435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.324687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-10-27T21:40:25.322633Z","caller":"traceutil/trace.go:171","msg":"trace[776578282] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:41340; }","duration":"147.547782ms","start":"2026-10-27T21:40:25.175048Z","end":"2026-10-27T21:40:25.322596Z","steps":["trace[776578282] 'agreement among raft nodes before linearized reading' (duration: 147.208123ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:40:26.240297Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38642} {"level":"info","ts":"2026-10-27T21:40:26.52289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38642,"took":"277.140653ms","hash":1854402674,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":35139584,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-27T21:40:26.522966Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1854402674,"revision":38642,"compact-revision":35933} {"level":"info","ts":"2026-10-27T21:45:26.247693Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41349} {"level":"info","ts":"2026-10-27T21:45:26.402649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41349,"took":"151.514415ms","hash":4100845370,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":34430976,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-27T21:45:26.402719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4100845370,"revision":41349,"compact-revision":38642} {"level":"info","ts":"2026-10-27T21:50:26.254385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44054} {"level":"info","ts":"2026-10-27T21:50:26.404807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44054,"took":"147.186342ms","hash":3955218052,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":33296384,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-27T21:50:26.404872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3955218052,"revision":44054,"compact-revision":41349} {"level":"info","ts":"2026-10-27T21:52:24.071125Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7481851e1bcdf3c","local-member-applied-index":50006,"local-member-snapshot-index":40005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-27T21:52:24.078839Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50006} {"level":"info","ts":"2026-10-27T21:52:24.078999Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45006}