{"level":"warn","ts":"2026-09-05T06:12:00.596397Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-05T06:12:00.596523Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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-09-05T06:12:00.596621Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-05T06:12:00.596641Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-05T06:12:00.596679Z","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-09-05T06:12:00.59801Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-09-05T06:12:00.598143Z","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.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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-09-05T06:12:00.600801Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.294566ms"} {"level":"info","ts":"2026-09-05T06:12:00.605096Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-09-05T06:12:00.605154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-09-05T06:12:00.60517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-09-05T06:12:00.605187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-05T06:12:00.605196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-09-05T06:12:00.605232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-09-05T06:12:00.614352Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-05T06:12:00.616494Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-05T06:12:00.618086Z","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-09-05T06:12:00.622315Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-05T06:12:00.622593Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-05T06:12:00.622736Z","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-09-05T06:12:00.622951Z","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-09-05T06:12:00.622977Z","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-09-05T06:12:00.624365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-09-05T06:12:00.624734Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-05T06:12:00.628003Z","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-09-05T06:12:00.62827Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-05T06:12:00.628341Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-05T06:12:00.628367Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-05T06:12:00.628432Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-05T06:12:01.606326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-09-05T06:12:01.606432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-05T06:12:01.606462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-09-05T06:12:01.606481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-09-05T06:12:01.606492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-05T06:12:01.606507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-09-05T06:12:01.60652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-05T06:12:01.607919Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-05T06:12:01.607914Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:12:01.607988Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-05T06:12:01.607931Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-05T06:12:01.608297Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-05T06:12:01.60853Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-05T06:12:01.609052Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:12:01.609202Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:12:01.609246Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-05T06:12:01.611641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-05T06:12:01.61265Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-09-05T06:12:48.028481Z","caller":"traceutil/trace.go:171","msg":"trace[1555722734] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"117.456174ms","start":"2026-09-05T06:12:47.910997Z","end":"2026-09-05T06:12:48.028454Z","steps":["trace[1555722734] 'read index received' (duration: 116.593555ms)","trace[1555722734] 'applied index is now lower than readState.Index' (duration: 861.439µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:12:48.028586Z","caller":"traceutil/trace.go:171","msg":"trace[1978050291] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"221.771842ms","start":"2026-09-05T06:12:47.806785Z","end":"2026-09-05T06:12:48.028557Z","steps":["trace[1978050291] 'process raft request' (duration: 221.090447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:12:48.028686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.642808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-05T06:12:48.028743Z","caller":"traceutil/trace.go:171","msg":"trace[627571257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"117.799633ms","start":"2026-09-05T06:12:47.910932Z","end":"2026-09-05T06:12:48.028732Z","steps":["trace[627571257] 'agreement among raft nodes before linearized reading' (duration: 117.657679ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:12:48.510004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.612467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-05T06:12:48.510211Z","caller":"traceutil/trace.go:171","msg":"trace[1908185375] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"324.362512ms","start":"2026-09-05T06:12:48.185826Z","end":"2026-09-05T06:12:48.510189Z","steps":["trace[1908185375] 'read index received' (duration: 159.778834ms)","trace[1908185375] 'applied index is now lower than readState.Index' (duration: 164.582358ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:12:48.510239Z","caller":"traceutil/trace.go:171","msg":"trace[301070926] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"436.298326ms","start":"2026-09-05T06:12:48.073907Z","end":"2026-09-05T06:12:48.510206Z","steps":["trace[301070926] 'process raft request' (duration: 271.681067ms)","trace[301070926] 'compare' (duration: 163.453254ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:12:48.510321Z","caller":"traceutil/trace.go:171","msg":"trace[853162708] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"390.92174ms","start":"2026-09-05T06:12:48.119386Z","end":"2026-09-05T06:12:48.510308Z","steps":["trace[853162708] 'process raft request' (duration: 390.722286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:12:48.510339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:12:48.073876Z","time spent":"436.415849ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-05T06:12:48.510434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:12:48.119352Z","time spent":"391.011001ms","remote":"127.0.0.1:35588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-05T06:12:48.510535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.69181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:12:48.510618Z","caller":"traceutil/trace.go:171","msg":"trace[1398987167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"324.796212ms","start":"2026-09-05T06:12:48.185798Z","end":"2026-09-05T06:12:48.510594Z","steps":["trace[1398987167] 'agreement among raft nodes before linearized reading' (duration: 324.603107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:12:48.510597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.022601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9jk2x\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-09-05T06:12:48.51067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:12:48.185784Z","time spent":"324.873874ms","remote":"127.0.0.1:35230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-05T06:12:48.510701Z","caller":"traceutil/trace.go:171","msg":"trace[1077916578] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9jk2x; range_end:; response_count:1; response_revision:643; }","duration":"155.176335ms","start":"2026-09-05T06:12:48.355487Z","end":"2026-09-05T06:12:48.510664Z","steps":["trace[1077916578] 'agreement among raft nodes before linearized reading' (duration: 154.974729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:12:48.510852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.309435ms","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-09-05T06:12:48.510953Z","caller":"traceutil/trace.go:171","msg":"trace[75477070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"314.463708ms","start":"2026-09-05T06:12:48.196463Z","end":"2026-09-05T06:12:48.510927Z","steps":["trace[75477070] 'agreement among raft nodes before linearized reading' (duration: 313.895685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:12:48.511008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:12:48.196441Z","time spent":"314.554581ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-05T06:13:05.576342Z","caller":"traceutil/trace.go:171","msg":"trace[798266229] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"118.331733ms","start":"2026-09-05T06:13:05.457989Z","end":"2026-09-05T06:13:05.576321Z","steps":["trace[798266229] 'process raft request' (duration: 118.16737ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:13:35.279311Z","caller":"traceutil/trace.go:171","msg":"trace[196685416] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"153.308706ms","start":"2026-09-05T06:13:35.125958Z","end":"2026-09-05T06:13:35.279267Z","steps":["trace[196685416] 'process raft request' (duration: 152.849126ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:14:22.673013Z","caller":"traceutil/trace.go:171","msg":"trace[317094641] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"156.962545ms","start":"2026-09-05T06:14:22.516021Z","end":"2026-09-05T06:14:22.672983Z","steps":["trace[317094641] 'process raft request' (duration: 156.800951ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:14:22.708151Z","caller":"traceutil/trace.go:171","msg":"trace[79418723] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"137.050298ms","start":"2026-09-05T06:14:22.571071Z","end":"2026-09-05T06:14:22.708121Z","steps":["trace[79418723] 'process raft request' (duration: 136.865054ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:14:23.113175Z","caller":"traceutil/trace.go:171","msg":"trace[654432443] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"227.349404ms","start":"2026-09-05T06:14:22.885801Z","end":"2026-09-05T06:14:23.11315Z","steps":["trace[654432443] 'read index received' (duration: 227.15027ms)","trace[654432443] 'applied index is now lower than readState.Index' (duration: 197.964µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:14:23.113423Z","caller":"traceutil/trace.go:171","msg":"trace[33446733] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"255.712053ms","start":"2026-09-05T06:14:22.857687Z","end":"2026-09-05T06:14:23.113399Z","steps":["trace[33446733] 'process raft request' (duration: 255.292974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:14:23.113514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.760166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:14:23.113592Z","caller":"traceutil/trace.go:171","msg":"trace[1209753979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1883; }","duration":"167.94651ms","start":"2026-09-05T06:14:22.945626Z","end":"2026-09-05T06:14:23.113573Z","steps":["trace[1209753979] 'agreement among raft nodes before linearized reading' (duration: 167.766247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:14:23.113799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.951197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-09-05T06:14:23.113858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.194664ms","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-09-05T06:14:23.113905Z","caller":"traceutil/trace.go:171","msg":"trace[1497185103] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1883; }","duration":"228.109891ms","start":"2026-09-05T06:14:22.885764Z","end":"2026-09-05T06:14:23.113874Z","steps":["trace[1497185103] 'agreement among raft nodes before linearized reading' (duration: 227.779954ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:14:23.113962Z","caller":"traceutil/trace.go:171","msg":"trace[1687903623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1883; }","duration":"125.390138ms","start":"2026-09-05T06:14:22.988547Z","end":"2026-09-05T06:14:23.113937Z","steps":["trace[1687903623] 'agreement among raft nodes before linearized reading' (duration: 125.065001ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:07.54633Z","caller":"traceutil/trace.go:171","msg":"trace[838329507] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"157.612877ms","start":"2026-09-05T06:15:07.388675Z","end":"2026-09-05T06:15:07.546288Z","steps":["trace[838329507] 'process raft request' (duration: 99.974896ms)","trace[838329507] 'compare' (duration: 57.275473ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:15:07.546673Z","caller":"traceutil/trace.go:171","msg":"trace[2040390393] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"156.918692ms","start":"2026-09-05T06:15:07.389327Z","end":"2026-09-05T06:15:07.546245Z","steps":["trace[2040390393] 'read index received' (duration: 91.643744ms)","trace[2040390393] 'applied index is now lower than readState.Index' (duration: 65.273628ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:15:07.546657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.3017ms","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-09-05T06:15:07.546753Z","caller":"traceutil/trace.go:171","msg":"trace[1189332358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2183; }","duration":"157.430023ms","start":"2026-09-05T06:15:07.389299Z","end":"2026-09-05T06:15:07.546729Z","steps":["trace[1189332358] 'agreement among raft nodes before linearized reading' (duration: 157.096426ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:15:13.733692Z","caller":"traceutil/trace.go:171","msg":"trace[1576697295] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"146.747341ms","start":"2026-09-05T06:15:13.586888Z","end":"2026-09-05T06:15:13.733635Z","steps":["trace[1576697295] 'read index received' (duration: 143.149391ms)","trace[1576697295] 'applied index is now lower than readState.Index' (duration: 3.59631ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:15:13.733744Z","caller":"traceutil/trace.go:171","msg":"trace[1108873343] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"149.725015ms","start":"2026-09-05T06:15:13.583987Z","end":"2026-09-05T06:15:13.733712Z","steps":["trace[1108873343] 'process raft request' (duration: 146.066804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:13.733915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.668608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:15:13.733963Z","caller":"traceutil/trace.go:171","msg":"trace[1594805328] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2216; }","duration":"124.744549ms","start":"2026-09-05T06:15:13.609206Z","end":"2026-09-05T06:15:13.73395Z","steps":["trace[1594805328] 'agreement among raft nodes before linearized reading' (duration: 124.630447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:13.734054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.045417ms","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-09-05T06:15:13.73481Z","caller":"traceutil/trace.go:171","msg":"trace[2086599958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2216; }","duration":"147.295322ms","start":"2026-09-05T06:15:13.586863Z","end":"2026-09-05T06:15:13.734158Z","steps":["trace[2086599958] 'agreement among raft nodes before linearized reading' (duration: 146.949424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:15:13.98987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.925914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-05T06:15:13.990016Z","caller":"traceutil/trace.go:171","msg":"trace[1602285298] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"250.783195ms","start":"2026-09-05T06:15:13.739206Z","end":"2026-09-05T06:15:13.989989Z","steps":["trace[1602285298] 'process raft request' (duration: 130.616816ms)","trace[1602285298] 'compare' (duration: 119.795781ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:17:07.330589Z","caller":"traceutil/trace.go:171","msg":"trace[1481107124] transaction","detail":"{read_only:false; response_revision:3005; number_of_response:1; }","duration":"121.204771ms","start":"2026-09-05T06:17:07.209361Z","end":"2026-09-05T06:17:07.330566Z","steps":["trace[1481107124] 'process raft request' (duration: 121.053117ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:17:41.587849Z","caller":"traceutil/trace.go:171","msg":"trace[222496718] transaction","detail":"{read_only:false; response_revision:3195; number_of_response:1; }","duration":"119.463963ms","start":"2026-09-05T06:17:41.468349Z","end":"2026-09-05T06:17:41.587812Z","steps":["trace[222496718] 'process raft request' (duration: 119.023994ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:20:53.837925Z","caller":"traceutil/trace.go:171","msg":"trace[828413628] transaction","detail":"{read_only:false; response_revision:4399; number_of_response:1; }","duration":"108.326016ms","start":"2026-09-05T06:20:53.729575Z","end":"2026-09-05T06:20:53.837901Z","steps":["trace[828413628] 'process raft request' (duration: 108.167972ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:20:53.86174Z","caller":"traceutil/trace.go:171","msg":"trace[1618158679] transaction","detail":"{read_only:false; response_revision:4400; number_of_response:1; }","duration":"131.889047ms","start":"2026-09-05T06:20:53.729825Z","end":"2026-09-05T06:20:53.861715Z","steps":["trace[1618158679] 'process raft request' (duration: 131.731174ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:01.808275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-09-05T06:22:01.887385Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"77.886909ms","hash":2264481449,"current-db-size-bytes":45178880,"current-db-size":"45 MB","current-db-size-in-use-bytes":20811776,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-09-05T06:22:01.887461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2264481449,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-09-05T06:22:22.47589Z","caller":"traceutil/trace.go:171","msg":"trace[1866780820] transaction","detail":"{read_only:false; response_revision:5624; number_of_response:1; }","duration":"152.032894ms","start":"2026-09-05T06:22:22.323833Z","end":"2026-09-05T06:22:22.475866Z","steps":["trace[1866780820] 'process raft request' (duration: 137.796267ms)","trace[1866780820] 'compare' (duration: 14.091644ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:22:22.475886Z","caller":"traceutil/trace.go:171","msg":"trace[726006451] linearizableReadLoop","detail":"{readStateIndex:5841; appliedIndex:5840; }","duration":"140.647968ms","start":"2026-09-05T06:22:22.335207Z","end":"2026-09-05T06:22:22.475855Z","steps":["trace[726006451] 'read index received' (duration: 126.476602ms)","trace[726006451] 'applied index is now lower than readState.Index' (duration: 14.169896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:22:22.476069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.834262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-753fb93f-9992-4ce3-9ffa-9c54a06fdd73\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-05T06:22:22.476138Z","caller":"traceutil/trace.go:171","msg":"trace[393809855] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-753fb93f-9992-4ce3-9ffa-9c54a06fdd73; range_end:; response_count:1; response_revision:5624; }","duration":"140.955265ms","start":"2026-09-05T06:22:22.335166Z","end":"2026-09-05T06:22:22.476122Z","steps":["trace[393809855] 'agreement among raft nodes before linearized reading' (duration: 140.74784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:22:22.476282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.49247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jfk6j\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-05T06:22:22.476329Z","caller":"traceutil/trace.go:171","msg":"trace[1002444348] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jfk6j; range_end:; response_count:1; response_revision:5624; }","duration":"131.574823ms","start":"2026-09-05T06:22:22.344735Z","end":"2026-09-05T06:22:22.47631Z","steps":["trace[1002444348] 'agreement among raft nodes before linearized reading' (duration: 131.418819ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:22.679927Z","caller":"traceutil/trace.go:171","msg":"trace[1939602078] linearizableReadLoop","detail":"{readStateIndex:5842; appliedIndex:5841; }","duration":"194.87593ms","start":"2026-09-05T06:22:22.485024Z","end":"2026-09-05T06:22:22.6799Z","steps":["trace[1939602078] 'read index received' (duration: 194.634515ms)","trace[1939602078] 'applied index is now lower than readState.Index' (duration: 240.445µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:22:22.680045Z","caller":"traceutil/trace.go:171","msg":"trace[1867704465] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"195.639966ms","start":"2026-09-05T06:22:22.484364Z","end":"2026-09-05T06:22:22.680004Z","steps":["trace[1867704465] 'process raft request' (duration: 195.391511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:22:22.680091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.052004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-05T06:22:22.680131Z","caller":"traceutil/trace.go:171","msg":"trace[274272465] range","detail":"{range_begin:/registry/clusterrolebindings/vector; range_end:; response_count:0; response_revision:5625; }","duration":"195.134436ms","start":"2026-09-05T06:22:22.484985Z","end":"2026-09-05T06:22:22.680119Z","steps":["trace[274272465] 'agreement among raft nodes before linearized reading' (duration: 195.023883ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:22.923348Z","caller":"traceutil/trace.go:171","msg":"trace[96803460] linearizableReadLoop","detail":"{readStateIndex:5847; appliedIndex:5846; }","duration":"115.833763ms","start":"2026-09-05T06:22:22.807491Z","end":"2026-09-05T06:22:22.923325Z","steps":["trace[96803460] 'read index received' (duration: 88.441281ms)","trace[96803460] 'applied index is now lower than readState.Index' (duration: 27.391512ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:22:22.923564Z","caller":"traceutil/trace.go:171","msg":"trace[135467460] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"120.142745ms","start":"2026-09-05T06:22:22.803352Z","end":"2026-09-05T06:22:22.923494Z","steps":["trace[135467460] 'process raft request' (duration: 92.672912ms)","trace[135467460] 'compare' (duration: 27.187727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:22:22.923595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.094479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-nm7b5-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-09-05T06:22:22.92379Z","caller":"traceutil/trace.go:171","msg":"trace[521573829] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-nm7b5-tls; range_end:; response_count:1; response_revision:5629; }","duration":"116.347193ms","start":"2026-09-05T06:22:22.80743Z","end":"2026-09-05T06:22:22.923778Z","steps":["trace[521573829] 'agreement among raft nodes before linearized reading' (duration: 115.986546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:22:37.174821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.699343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125006"} {"level":"info","ts":"2026-09-05T06:22:37.174969Z","caller":"traceutil/trace.go:171","msg":"trace[920927972] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5894; }","duration":"161.953499ms","start":"2026-09-05T06:22:37.012986Z","end":"2026-09-05T06:22:37.174939Z","steps":["trace[920927972] 'range keys from in-memory index tree' (duration: 161.352166ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:37.456871Z","caller":"traceutil/trace.go:171","msg":"trace[815737527] transaction","detail":"{read_only:false; response_revision:5895; number_of_response:1; }","duration":"277.521345ms","start":"2026-09-05T06:22:37.179315Z","end":"2026-09-05T06:22:37.456837Z","steps":["trace[815737527] 'process raft request' (duration: 277.262919ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:37.59933Z","caller":"traceutil/trace.go:171","msg":"trace[741991512] transaction","detail":"{read_only:false; response_revision:5896; number_of_response:1; }","duration":"419.668846ms","start":"2026-09-05T06:22:37.179635Z","end":"2026-09-05T06:22:37.599304Z","steps":["trace[741991512] 'process raft request' (duration: 419.45791ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:22:37.59951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:22:37.179609Z","time spent":"419.797879ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T06:22:37.599708Z","caller":"traceutil/trace.go:171","msg":"trace[693063904] transaction","detail":"{read_only:false; response_revision:5898; number_of_response:1; }","duration":"345.74897ms","start":"2026-09-05T06:22:37.253926Z","end":"2026-09-05T06:22:37.599675Z","steps":["trace[693063904] 'process raft request' (duration: 345.602826ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:37.599752Z","caller":"traceutil/trace.go:171","msg":"trace[1256399323] transaction","detail":"{read_only:false; response_revision:5897; number_of_response:1; }","duration":"419.657055ms","start":"2026-09-05T06:22:37.180062Z","end":"2026-09-05T06:22:37.599719Z","steps":["trace[1256399323] 'process raft request' (duration: 419.198026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:22:37.599898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:22:37.253894Z","time spent":"345.908803ms","remote":"127.0.0.1:35308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-05T06:22:37.599911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:22:37.180036Z","time spent":"419.792529ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T06:22:37.601253Z","caller":"traceutil/trace.go:171","msg":"trace[1617180226] transaction","detail":"{read_only:false; response_revision:5899; number_of_response:1; }","duration":"141.949726ms","start":"2026-09-05T06:22:37.459125Z","end":"2026-09-05T06:22:37.601074Z","steps":["trace[1617180226] 'process raft request' (duration: 141.258832ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:45.720864Z","caller":"traceutil/trace.go:171","msg":"trace[526366595] linearizableReadLoop","detail":"{readStateIndex:6322; appliedIndex:6321; }","duration":"102.240259ms","start":"2026-09-05T06:22:45.618584Z","end":"2026-09-05T06:22:45.720825Z","steps":["trace[526366595] 'read index received' (duration: 101.910932ms)","trace[526366595] 'applied index is now lower than readState.Index' (duration: 327.977µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:22:45.720942Z","caller":"traceutil/trace.go:171","msg":"trace[929669419] transaction","detail":"{read_only:false; response_revision:6091; number_of_response:1; }","duration":"102.386682ms","start":"2026-09-05T06:22:45.618503Z","end":"2026-09-05T06:22:45.72089Z","steps":["trace[929669419] 'process raft request' (duration: 102.092096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:22:45.721281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.669218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19472"} {"level":"info","ts":"2026-09-05T06:22:45.721413Z","caller":"traceutil/trace.go:171","msg":"trace[748247763] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6091; }","duration":"102.824372ms","start":"2026-09-05T06:22:45.618542Z","end":"2026-09-05T06:22:45.721366Z","steps":["trace[748247763] 'agreement among raft nodes before linearized reading' (duration: 102.446254ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:45.828085Z","caller":"traceutil/trace.go:171","msg":"trace[1765142801] transaction","detail":"{read_only:false; response_revision:6093; number_of_response:1; }","duration":"101.138955ms","start":"2026-09-05T06:22:45.726919Z","end":"2026-09-05T06:22:45.828058Z","steps":["trace[1765142801] 'process raft request' (duration: 100.648784ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:56.961059Z","caller":"traceutil/trace.go:171","msg":"trace[696423276] transaction","detail":"{read_only:false; response_revision:6218; number_of_response:1; }","duration":"108.896523ms","start":"2026-09-05T06:22:56.852135Z","end":"2026-09-05T06:22:56.961031Z","steps":["trace[696423276] 'process raft request' (duration: 92.065249ms)","trace[696423276] 'compare' (duration: 16.625369ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:22:56.96126Z","caller":"traceutil/trace.go:171","msg":"trace[1534859788] transaction","detail":"{read_only:false; response_revision:6219; number_of_response:1; }","duration":"109.065946ms","start":"2026-09-05T06:22:56.852184Z","end":"2026-09-05T06:22:56.96125Z","steps":["trace[1534859788] 'process raft request' (duration: 108.78869ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:22:56.961363Z","caller":"traceutil/trace.go:171","msg":"trace[764987222] transaction","detail":"{read_only:false; response_revision:6220; number_of_response:1; }","duration":"108.995275ms","start":"2026-09-05T06:22:56.852336Z","end":"2026-09-05T06:22:56.961332Z","steps":["trace[764987222] 'process raft request' (duration: 108.803521ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:27:01.815083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4845} {"level":"info","ts":"2026-09-05T06:27:01.876648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4845,"took":"59.728299ms","hash":590938922,"current-db-size-bytes":45178880,"current-db-size":"45 MB","current-db-size-in-use-bytes":38932480,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-09-05T06:27:01.87673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":590938922,"revision":4845,"compact-revision":2941} {"level":"warn","ts":"2026-09-05T06:28:57.639867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.759903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-05T06:28:57.639973Z","caller":"traceutil/trace.go:171","msg":"trace[1240547559] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8924; }","duration":"259.886215ms","start":"2026-09-05T06:28:57.380045Z","end":"2026-09-05T06:28:57.639931Z","steps":["trace[1240547559] 'range keys from in-memory index tree' (duration: 259.534979ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:08.914997Z","caller":"traceutil/trace.go:171","msg":"trace[1016058286] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"245.900213ms","start":"2026-09-05T06:29:08.669073Z","end":"2026-09-05T06:29:08.914973Z","steps":["trace[1016058286] 'process raft request' (duration: 245.74674ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:09.716572Z","caller":"traceutil/trace.go:171","msg":"trace[1576328499] transaction","detail":"{read_only:false; response_revision:8988; number_of_response:1; }","duration":"191.824533ms","start":"2026-09-05T06:29:09.524725Z","end":"2026-09-05T06:29:09.716549Z","steps":["trace[1576328499] 'process raft request' (duration: 191.631269ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:09.72988Z","caller":"traceutil/trace.go:171","msg":"trace[890858345] transaction","detail":"{read_only:false; response_revision:8989; number_of_response:1; }","duration":"159.890281ms","start":"2026-09-05T06:29:09.569936Z","end":"2026-09-05T06:29:09.729826Z","steps":["trace[890858345] 'process raft request' (duration: 159.716817ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:10.178454Z","caller":"traceutil/trace.go:171","msg":"trace[860061832] transaction","detail":"{read_only:false; response_revision:8993; number_of_response:1; }","duration":"181.008018ms","start":"2026-09-05T06:29:09.997418Z","end":"2026-09-05T06:29:10.178426Z","steps":["trace[860061832] 'process raft request' (duration: 180.852545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:29:39.254067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.09523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jfk6j\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-09-05T06:29:39.254122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.605152ms","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-09-05T06:29:39.254201Z","caller":"traceutil/trace.go:171","msg":"trace[445151067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9200; }","duration":"182.683924ms","start":"2026-09-05T06:29:39.071494Z","end":"2026-09-05T06:29:39.254178Z","steps":["trace[445151067] 'range keys from in-memory index tree' (duration: 182.47072ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:39.254899Z","caller":"traceutil/trace.go:171","msg":"trace[37080329] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jfk6j; range_end:; response_count:1; response_revision:9200; }","duration":"120.247253ms","start":"2026-09-05T06:29:39.133911Z","end":"2026-09-05T06:29:39.254158Z","steps":["trace[37080329] 'range keys from in-memory index tree' (duration: 119.926697ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:48.286714Z","caller":"traceutil/trace.go:171","msg":"trace[1574266248] linearizableReadLoop","detail":"{readStateIndex:9584; appliedIndex:9582; }","duration":"120.739526ms","start":"2026-09-05T06:29:48.165944Z","end":"2026-09-05T06:29:48.286684Z","steps":["trace[1574266248] 'read index received' (duration: 5.026359ms)","trace[1574266248] 'applied index is now lower than readState.Index' (duration: 115.711797ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:29:48.286841Z","caller":"traceutil/trace.go:171","msg":"trace[429451776] transaction","detail":"{read_only:false; response_revision:9252; number_of_response:1; }","duration":"164.793826ms","start":"2026-09-05T06:29:48.122017Z","end":"2026-09-05T06:29:48.286811Z","steps":["trace[429451776] 'process raft request' (duration: 145.282295ms)","trace[429451776] 'compare' (duration: 19.255766ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:29:48.287018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.056572ms","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-09-05T06:29:48.287075Z","caller":"traceutil/trace.go:171","msg":"trace[1938027258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9252; }","duration":"121.150835ms","start":"2026-09-05T06:29:48.165905Z","end":"2026-09-05T06:29:48.287056Z","steps":["trace[1938027258] 'agreement among raft nodes before linearized reading' (duration: 120.957361ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:52.094068Z","caller":"traceutil/trace.go:171","msg":"trace[1407967662] transaction","detail":"{read_only:false; response_revision:9279; number_of_response:1; }","duration":"163.432237ms","start":"2026-09-05T06:29:51.930601Z","end":"2026-09-05T06:29:52.094033Z","steps":["trace[1407967662] 'process raft request' (duration: 148.169098ms)","trace[1407967662] 'compare' (duration: 15.134196ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:29:52.171897Z","caller":"traceutil/trace.go:171","msg":"trace[1758276629] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9280; }","duration":"241.198676ms","start":"2026-09-05T06:29:51.930668Z","end":"2026-09-05T06:29:52.171867Z","steps":["trace[1758276629] 'process raft request' (duration: 240.526041ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:29:52.343637Z","caller":"traceutil/trace.go:171","msg":"trace[808191095] transaction","detail":"{read_only:false; response_revision:9281; number_of_response:1; }","duration":"155.131648ms","start":"2026-09-05T06:29:52.188478Z","end":"2026-09-05T06:29:52.343609Z","steps":["trace[808191095] 'process raft request' (duration: 132.424318ms)","trace[808191095] 'compare' (duration: 22.282561ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:29:54.281221Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000025c1.wal"} {"level":"warn","ts":"2026-09-05T06:30:05.500994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.367676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-09-05T06:30:05.501066Z","caller":"traceutil/trace.go:171","msg":"trace[917638836] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9419; }","duration":"106.469597ms","start":"2026-09-05T06:30:05.394579Z","end":"2026-09-05T06:30:05.501048Z","steps":["trace[917638836] 'range keys from in-memory index tree' (duration: 106.253033ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:30:28.552437Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-05T06:30:28.558975Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-05T06:30:28.559102Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-05T06:31:16.957778Z","caller":"traceutil/trace.go:171","msg":"trace[1010342109] linearizableReadLoop","detail":"{readStateIndex:10459; appliedIndex:10458; }","duration":"149.902626ms","start":"2026-09-05T06:31:16.807845Z","end":"2026-09-05T06:31:16.957748Z","steps":["trace[1010342109] 'read index received' (duration: 149.722582ms)","trace[1010342109] 'applied index is now lower than readState.Index' (duration: 178.664µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:31:16.957862Z","caller":"traceutil/trace.go:171","msg":"trace[123089832] transaction","detail":"{read_only:false; response_revision:10103; number_of_response:1; }","duration":"185.253298ms","start":"2026-09-05T06:31:16.772577Z","end":"2026-09-05T06:31:16.95783Z","steps":["trace[123089832] 'process raft request' (duration: 184.992752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:31:16.958027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.166931ms","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-09-05T06:31:16.95807Z","caller":"traceutil/trace.go:171","msg":"trace[2103543263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10103; }","duration":"150.275344ms","start":"2026-09-05T06:31:16.807785Z","end":"2026-09-05T06:31:16.95806Z","steps":["trace[2103543263] 'agreement among raft nodes before linearized reading' (duration: 150.11135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:31:16.958126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.625049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-05T06:31:16.95829Z","caller":"traceutil/trace.go:171","msg":"trace[1289831116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10103; }","duration":"142.788452ms","start":"2026-09-05T06:31:16.815436Z","end":"2026-09-05T06:31:16.958224Z","steps":["trace[1289831116] 'agreement among raft nodes before linearized reading' (duration: 142.523777ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:32:01.822863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7930} {"level":"info","ts":"2026-09-05T06:32:01.918619Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7930,"took":"92.927836ms","hash":1143238874,"current-db-size-bytes":45178880,"current-db-size":"45 MB","current-db-size-in-use-bytes":25567232,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-05T06:32:01.918693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1143238874,"revision":7930,"compact-revision":4845} {"level":"info","ts":"2026-09-05T06:37:01.832467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10430} {"level":"info","ts":"2026-09-05T06:37:01.916013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10430,"took":"80.983816ms","hash":1913911314,"current-db-size-bytes":45178880,"current-db-size":"45 MB","current-db-size-in-use-bytes":28114944,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-05T06:37:01.916066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1913911314,"revision":10430,"compact-revision":7930} {"level":"warn","ts":"2026-09-05T06:41:36.520057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.175392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-05T06:41:36.520151Z","caller":"traceutil/trace.go:171","msg":"trace[713112158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14598; }","duration":"116.319705ms","start":"2026-09-05T06:41:36.40381Z","end":"2026-09-05T06:41:36.52013Z","steps":["trace[713112158] 'range keys from in-memory index tree' (duration: 115.959517ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:41:36.706033Z","caller":"traceutil/trace.go:171","msg":"trace[1175932992] transaction","detail":"{read_only:false; response_revision:14599; number_of_response:1; }","duration":"181.900586ms","start":"2026-09-05T06:41:36.524085Z","end":"2026-09-05T06:41:36.705986Z","steps":["trace[1175932992] 'process raft request' (duration: 181.748943ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:41:36.712857Z","caller":"traceutil/trace.go:171","msg":"trace[517851090] transaction","detail":"{read_only:false; response_revision:14600; number_of_response:1; }","duration":"188.565169ms","start":"2026-09-05T06:41:36.524266Z","end":"2026-09-05T06:41:36.712831Z","steps":["trace[517851090] 'process raft request' (duration: 188.283343ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:41:59.470813Z","caller":"traceutil/trace.go:171","msg":"trace[1131651027] linearizableReadLoop","detail":"{readStateIndex:15432; appliedIndex:15431; }","duration":"252.047996ms","start":"2026-09-05T06:41:59.218735Z","end":"2026-09-05T06:41:59.470783Z","steps":["trace[1131651027] 'read index received' (duration: 251.951054ms)","trace[1131651027] 'applied index is now lower than readState.Index' (duration: 95.162µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:41:59.470896Z","caller":"traceutil/trace.go:171","msg":"trace[236911347] transaction","detail":"{read_only:false; response_revision:14914; number_of_response:1; }","duration":"253.951077ms","start":"2026-09-05T06:41:59.216907Z","end":"2026-09-05T06:41:59.470858Z","steps":["trace[236911347] 'process raft request' (duration: 253.708411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:41:59.471714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.960435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:630406"} {"level":"info","ts":"2026-09-05T06:41:59.471774Z","caller":"traceutil/trace.go:171","msg":"trace[1797650028] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:14914; }","duration":"253.057686ms","start":"2026-09-05T06:41:59.218703Z","end":"2026-09-05T06:41:59.471761Z","steps":["trace[1797650028] 'agreement among raft nodes before linearized reading' (duration: 252.26096ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:41:59.483015Z","caller":"traceutil/trace.go:171","msg":"trace[108556982] transaction","detail":"{read_only:false; response_revision:14915; number_of_response:1; }","duration":"148.006933ms","start":"2026-09-05T06:41:59.334952Z","end":"2026-09-05T06:41:59.482959Z","steps":["trace[108556982] 'process raft request' (duration: 147.85168ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:42:01.839986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12784} {"level":"info","ts":"2026-09-05T06:42:01.928744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12784,"took":"86.155951ms","hash":1087932035,"current-db-size-bytes":45178880,"current-db-size":"45 MB","current-db-size-in-use-bytes":28631040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-05T06:42:01.928819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087932035,"revision":12784,"compact-revision":10430} {"level":"info","ts":"2026-09-05T06:47:01.846289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14928} {"level":"info","ts":"2026-09-05T06:47:01.946039Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14928,"took":"95.534484ms","hash":4045667925,"current-db-size-bytes":45178880,"current-db-size":"45 MB","current-db-size-in-use-bytes":30375936,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-05T06:47:01.946085Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4045667925,"revision":14928,"compact-revision":12784} {"level":"warn","ts":"2026-09-05T06:49:19.051185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.360993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-h5n84\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-09-05T06:49:19.051285Z","caller":"traceutil/trace.go:171","msg":"trace[507841985] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-h5n84; range_end:; response_count:1; response_revision:18336; }","duration":"225.512676ms","start":"2026-09-05T06:49:18.825748Z","end":"2026-09-05T06:49:19.051261Z","steps":["trace[507841985] 'range keys from in-memory index tree' (duration: 225.181138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:49:19.051432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.014051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6vq5w\" ","response":"range_response_count:1 size:20759"} {"level":"info","ts":"2026-09-05T06:49:19.051444Z","caller":"traceutil/trace.go:171","msg":"trace[1603177370] transaction","detail":"{read_only:false; response_revision:18337; number_of_response:1; }","duration":"128.710714ms","start":"2026-09-05T06:49:18.922701Z","end":"2026-09-05T06:49:19.051411Z","steps":["trace[1603177370] 'process raft request' (duration: 81.996644ms)","trace[1603177370] 'compare' (duration: 46.572297ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:49:19.051522Z","caller":"traceutil/trace.go:171","msg":"trace[528732059] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6vq5w; range_end:; response_count:1; response_revision:18336; }","duration":"129.162694ms","start":"2026-09-05T06:49:18.922344Z","end":"2026-09-05T06:49:19.051507Z","steps":["trace[528732059] 'range keys from in-memory index tree' (duration: 128.850027ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:49:19.503145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.181961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-05T06:49:19.503237Z","caller":"traceutil/trace.go:171","msg":"trace[591656917] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:18338; }","duration":"319.306753ms","start":"2026-09-05T06:49:19.183909Z","end":"2026-09-05T06:49:19.503215Z","steps":["trace[591656917] 'count revisions from in-memory index tree' (duration: 319.059038ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:49:19.503281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.949354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-05T06:49:19.503287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:49:19.183873Z","time spent":"319.403466ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-09-05T06:49:19.503325Z","caller":"traceutil/trace.go:171","msg":"trace[1867660931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18338; }","duration":"318.030165ms","start":"2026-09-05T06:49:19.185279Z","end":"2026-09-05T06:49:19.503309Z","steps":["trace[1867660931] 'range keys from in-memory index tree' (duration: 317.862862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:49:19.503364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:49:19.185256Z","time spent":"318.100347ms","remote":"127.0.0.1:35216","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-09-05T06:49:19.503355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.993079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-05T06:49:19.503458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.865945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-t9xvj\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-09-05T06:49:19.503469Z","caller":"traceutil/trace.go:171","msg":"trace[834374760] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18338; }","duration":"264.122912ms","start":"2026-09-05T06:49:19.239305Z","end":"2026-09-05T06:49:19.503428Z","steps":["trace[834374760] 'range keys from in-memory index tree' (duration: 263.833886ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:49:19.503503Z","caller":"traceutil/trace.go:171","msg":"trace[1001809375] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-t9xvj; range_end:; response_count:1; response_revision:18338; }","duration":"241.905747ms","start":"2026-09-05T06:49:19.261585Z","end":"2026-09-05T06:49:19.50349Z","steps":["trace[1001809375] 'range keys from in-memory index tree' (duration: 241.710982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:49:19.503217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.198732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-05T06:49:19.503566Z","caller":"traceutil/trace.go:171","msg":"trace[501188880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18338; }","duration":"313.58464ms","start":"2026-09-05T06:49:19.189965Z","end":"2026-09-05T06:49:19.503549Z","steps":["trace[501188880] 'range keys from in-memory index tree' (duration: 313.021928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:49:19.503626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:49:19.189954Z","time spent":"313.659033ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-05T06:49:19.503901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.399376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T06:49:19.503981Z","caller":"traceutil/trace.go:171","msg":"trace[847019097] transaction","detail":"{read_only:false; response_revision:18339; number_of_response:1; }","duration":"187.965892ms","start":"2026-09-05T06:49:19.316001Z","end":"2026-09-05T06:49:19.503967Z","steps":["trace[847019097] 'process raft request' (duration: 32.422713ms)","trace[847019097] 'compare' (duration: 154.868204ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:50:58.474455Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-05T06:50:58.479437Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-05T06:50:58.479542Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-05T06:52:01.855152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17396} {"level":"info","ts":"2026-09-05T06:52:01.954608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17396,"took":"96.14932ms","hash":3102429635,"current-db-size-bytes":47722496,"current-db-size":"48 MB","current-db-size-in-use-bytes":34840576,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-05T06:52:01.954978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3102429635,"revision":17396,"compact-revision":14928} {"level":"warn","ts":"2026-09-05T06:54:55.434347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.486769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-09-05T06:54:55.434488Z","caller":"traceutil/trace.go:171","msg":"trace[895154828] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:20923; }","duration":"188.695484ms","start":"2026-09-05T06:54:55.245764Z","end":"2026-09-05T06:54:55.434459Z","steps":["trace[895154828] 'range keys from in-memory index tree' (duration: 188.237934ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:54:55.434509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.519068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-05T06:54:55.43459Z","caller":"traceutil/trace.go:171","msg":"trace[187475235] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:20923; }","duration":"178.655592ms","start":"2026-09-05T06:54:55.255916Z","end":"2026-09-05T06:54:55.434572Z","steps":["trace[187475235] 'range keys from in-memory index tree' (duration: 178.316193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:54:55.434347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.246788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-05T06:54:55.434727Z","caller":"traceutil/trace.go:171","msg":"trace[1972023492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20923; }","duration":"191.675007ms","start":"2026-09-05T06:54:55.243026Z","end":"2026-09-05T06:54:55.4347Z","steps":["trace[1972023492] 'range keys from in-memory index tree' (duration: 190.999493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:55:03.443353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.479613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T06:55:03.443482Z","caller":"traceutil/trace.go:171","msg":"trace[972627568] transaction","detail":"{read_only:false; response_revision:20979; number_of_response:1; }","duration":"201.352443ms","start":"2026-09-05T06:55:03.242108Z","end":"2026-09-05T06:55:03.44346Z","steps":["trace[972627568] 'process raft request' (duration: 50.661725ms)","trace[972627568] 'compare' (duration: 150.079105ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:55:03.786511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.896873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T06:55:03.786678Z","caller":"traceutil/trace.go:171","msg":"trace[1174627456] linearizableReadLoop","detail":"{readStateIndex:21699; appliedIndex:21698; }","duration":"259.259442ms","start":"2026-09-05T06:55:03.527347Z","end":"2026-09-05T06:55:03.786606Z","steps":["trace[1174627456] 'read index received' (duration: 75.075393ms)","trace[1174627456] 'applied index is now lower than readState.Index' (duration: 184.183019ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:55:03.786766Z","caller":"traceutil/trace.go:171","msg":"trace[1133283382] transaction","detail":"{read_only:false; response_revision:20980; number_of_response:1; }","duration":"319.436418ms","start":"2026-09-05T06:55:03.467292Z","end":"2026-09-05T06:55:03.786728Z","steps":["trace[1133283382] 'process raft request' (duration: 135.231139ms)","trace[1133283382] 'compare' (duration: 183.683089ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:55:03.786976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:55:03.467268Z","time spent":"319.627022ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-05T06:55:03.787013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.6557ms","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-09-05T06:55:03.787136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.172027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-05T06:55:03.787117Z","caller":"traceutil/trace.go:171","msg":"trace[1174020184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20980; }","duration":"259.798703ms","start":"2026-09-05T06:55:03.527301Z","end":"2026-09-05T06:55:03.7871Z","steps":["trace[1174020184] 'agreement among raft nodes before linearized reading' (duration: 259.520317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:55:03.787179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.230209ms","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-09-05T06:55:03.78721Z","caller":"traceutil/trace.go:171","msg":"trace[434906438] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20980; }","duration":"256.292449ms","start":"2026-09-05T06:55:03.53089Z","end":"2026-09-05T06:55:03.787183Z","steps":["trace[434906438] 'agreement among raft nodes before linearized reading' (duration: 256.128225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:55:03.787183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.142932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T06:55:03.787264Z","caller":"traceutil/trace.go:171","msg":"trace[1502939248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20980; }","duration":"258.397613ms","start":"2026-09-05T06:55:03.528846Z","end":"2026-09-05T06:55:03.787243Z","steps":["trace[1502939248] 'agreement among raft nodes before linearized reading' (duration: 258.113977ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:55:03.787299Z","caller":"traceutil/trace.go:171","msg":"trace[1574174357] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:20980; }","duration":"122.296155ms","start":"2026-09-05T06:55:03.664977Z","end":"2026-09-05T06:55:03.787274Z","steps":["trace[1574174357] 'agreement among raft nodes before linearized reading' (duration: 122.030309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:55:04.027692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.81435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T06:55:04.027932Z","caller":"traceutil/trace.go:171","msg":"trace[1108360678] transaction","detail":"{read_only:false; response_revision:20981; number_of_response:1; }","duration":"235.600979ms","start":"2026-09-05T06:55:03.792303Z","end":"2026-09-05T06:55:04.027904Z","steps":["trace[1108360678] 'process raft request' (duration: 109.476893ms)","trace[1108360678] 'compare' (duration: 125.677837ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:55:04.028014Z","caller":"traceutil/trace.go:171","msg":"trace[1947053916] transaction","detail":"{read_only:false; response_revision:20983; number_of_response:1; }","duration":"235.190721ms","start":"2026-09-05T06:55:03.7928Z","end":"2026-09-05T06:55:04.027991Z","steps":["trace[1947053916] 'process raft request' (duration: 235.102269ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:55:04.028214Z","caller":"traceutil/trace.go:171","msg":"trace[200162793] transaction","detail":"{read_only:false; response_revision:20982; number_of_response:1; }","duration":"235.437586ms","start":"2026-09-05T06:55:03.792766Z","end":"2026-09-05T06:55:04.028203Z","steps":["trace[200162793] 'process raft request' (duration: 235.041988ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:55:04.353809Z","caller":"traceutil/trace.go:171","msg":"trace[1202926629] linearizableReadLoop","detail":"{readStateIndex:21703; appliedIndex:21702; }","duration":"184.197669ms","start":"2026-09-05T06:55:04.169577Z","end":"2026-09-05T06:55:04.353774Z","steps":["trace[1202926629] 'read index received' (duration: 183.816991ms)","trace[1202926629] 'applied index is now lower than readState.Index' (duration: 379.018µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:55:04.353999Z","caller":"traceutil/trace.go:171","msg":"trace[97836128] transaction","detail":"{read_only:false; response_revision:20984; number_of_response:1; }","duration":"186.868365ms","start":"2026-09-05T06:55:04.167117Z","end":"2026-09-05T06:55:04.353985Z","steps":["trace[97836128] 'process raft request' (duration: 186.431316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:55:04.355344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.745523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-05T06:55:04.355439Z","caller":"traceutil/trace.go:171","msg":"trace[671612733] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20984; }","duration":"185.898946ms","start":"2026-09-05T06:55:04.169517Z","end":"2026-09-05T06:55:04.355416Z","steps":["trace[671612733] 'agreement among raft nodes before linearized reading' (duration: 184.627618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:55:04.63402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.57419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T06:55:04.634164Z","caller":"traceutil/trace.go:171","msg":"trace[105092891] linearizableReadLoop","detail":"{readStateIndex:21704; appliedIndex:21703; }","duration":"194.976067ms","start":"2026-09-05T06:55:04.439162Z","end":"2026-09-05T06:55:04.634138Z","steps":["trace[105092891] 'read index received' (duration: 31.038528ms)","trace[105092891] 'applied index is now lower than readState.Index' (duration: 163.935479ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:55:04.634286Z","caller":"traceutil/trace.go:171","msg":"trace[1218243533] transaction","detail":"{read_only:false; response_revision:20985; number_of_response:1; }","duration":"236.07289ms","start":"2026-09-05T06:55:04.398199Z","end":"2026-09-05T06:55:04.634272Z","steps":["trace[1218243533] 'process raft request' (duration: 72.06621ms)","trace[1218243533] 'compare' (duration: 163.225933ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:55:04.634636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.448147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-05T06:55:04.634665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.876667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-05T06:55:04.634723Z","caller":"traceutil/trace.go:171","msg":"trace[296621786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20985; }","duration":"195.546779ms","start":"2026-09-05T06:55:04.439156Z","end":"2026-09-05T06:55:04.634702Z","steps":["trace[296621786] 'agreement among raft nodes before linearized reading' (duration: 195.310624ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:55:04.63474Z","caller":"traceutil/trace.go:171","msg":"trace[372963069] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:20985; }","duration":"172.00131ms","start":"2026-09-05T06:55:04.462719Z","end":"2026-09-05T06:55:04.63472Z","steps":["trace[372963069] 'agreement among raft nodes before linearized reading' (duration: 171.756635ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:55:04.786553Z","caller":"traceutil/trace.go:171","msg":"trace[495884040] transaction","detail":"{read_only:false; response_revision:20986; number_of_response:1; }","duration":"147.50872ms","start":"2026-09-05T06:55:04.639015Z","end":"2026-09-05T06:55:04.786524Z","steps":["trace[495884040] 'process raft request' (duration: 99.254395ms)","trace[495884040] 'compare' (duration: 48.02135ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:55:04.7869Z","caller":"traceutil/trace.go:171","msg":"trace[1680122034] transaction","detail":"{read_only:false; response_revision:20987; number_of_response:1; }","duration":"147.809347ms","start":"2026-09-05T06:55:04.63903Z","end":"2026-09-05T06:55:04.786839Z","steps":["trace[1680122034] 'process raft request' (duration: 147.442029ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:55:04.787107Z","caller":"traceutil/trace.go:171","msg":"trace[2070489824] linearizableReadLoop","detail":"{readStateIndex:21706; appliedIndex:21704; }","duration":"147.537671ms","start":"2026-09-05T06:55:04.639544Z","end":"2026-09-05T06:55:04.787081Z","steps":["trace[2070489824] 'read index received' (duration: 98.698224ms)","trace[2070489824] 'applied index is now lower than readState.Index' (duration: 48.837757ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:55:04.787194Z","caller":"traceutil/trace.go:171","msg":"trace[237704015] transaction","detail":"{read_only:false; response_revision:20988; number_of_response:1; }","duration":"147.550882ms","start":"2026-09-05T06:55:04.639625Z","end":"2026-09-05T06:55:04.787176Z","steps":["trace[237704015] 'process raft request' (duration: 147.114873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:55:04.787334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.765437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19059"} {"level":"info","ts":"2026-09-05T06:55:04.787385Z","caller":"traceutil/trace.go:171","msg":"trace[1750737670] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20988; }","duration":"147.853988ms","start":"2026-09-05T06:55:04.639515Z","end":"2026-09-05T06:55:04.787369Z","steps":["trace[1750737670] 'agreement among raft nodes before linearized reading' (duration: 147.657054ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T06:57:01.863227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19763} {"level":"info","ts":"2026-09-05T06:57:01.988998Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19763,"took":"122.20232ms","hash":4026167099,"current-db-size-bytes":49410048,"current-db-size":"49 MB","current-db-size-in-use-bytes":29646848,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-05T06:57:01.989068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4026167099,"revision":19763,"compact-revision":17396} {"level":"info","ts":"2026-09-05T06:59:07.024262Z","caller":"traceutil/trace.go:171","msg":"trace[1178191068] linearizableReadLoop","detail":"{readStateIndex:24261; appliedIndex:24260; }","duration":"243.998973ms","start":"2026-09-05T06:59:06.780239Z","end":"2026-09-05T06:59:07.024238Z","steps":["trace[1178191068] 'read index received' (duration: 199.68295ms)","trace[1178191068] 'applied index is now lower than readState.Index' (duration: 44.314533ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T06:59:07.024377Z","caller":"traceutil/trace.go:171","msg":"trace[950808717] transaction","detail":"{read_only:false; response_revision:23466; number_of_response:1; }","duration":"319.390888ms","start":"2026-09-05T06:59:06.70489Z","end":"2026-09-05T06:59:07.024281Z","steps":["trace[950808717] 'process raft request' (duration: 275.070565ms)","trace[950808717] 'compare' (duration: 44.16673ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T06:59:07.024533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.27886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:70568"} {"level":"info","ts":"2026-09-05T06:59:07.024634Z","caller":"traceutil/trace.go:171","msg":"trace[1567144988] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclustertemplates.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23466; }","duration":"244.429182ms","start":"2026-09-05T06:59:06.780181Z","end":"2026-09-05T06:59:07.02461Z","steps":["trace[1567144988] 'agreement among raft nodes before linearized reading' (duration: 244.148866ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T06:59:07.024683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T06:59:06.704868Z","time spent":"319.661564ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:02:01.872212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21950} {"level":"info","ts":"2026-09-05T07:02:01.99582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21950,"took":"118.813717ms","hash":1905339275,"current-db-size-bytes":53051392,"current-db-size":"53 MB","current-db-size-in-use-bytes":44847104,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-05T07:02:01.995888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1905339275,"revision":21950,"compact-revision":19763} {"level":"info","ts":"2026-09-05T07:02:32.630579Z","caller":"traceutil/trace.go:171","msg":"trace[888629031] linearizableReadLoop","detail":"{readStateIndex:26470; appliedIndex:26469; }","duration":"107.996207ms","start":"2026-09-05T07:02:32.522529Z","end":"2026-09-05T07:02:32.630525Z","steps":["trace[888629031] 'read index received' (duration: 107.096237ms)","trace[888629031] 'applied index is now lower than readState.Index' (duration: 785.727µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:32.630744Z","caller":"traceutil/trace.go:171","msg":"trace[452318979] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"108.673801ms","start":"2026-09-05T07:02:32.522039Z","end":"2026-09-05T07:02:32.630713Z","steps":["trace[452318979] 'process raft request' (duration: 107.660058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:32.631006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.459926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-05T07:02:32.63113Z","caller":"traceutil/trace.go:171","msg":"trace[1554497202] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25622; }","duration":"108.611489ms","start":"2026-09-05T07:02:32.522469Z","end":"2026-09-05T07:02:32.63108Z","steps":["trace[1554497202] 'agreement among raft nodes before linearized reading' (duration: 108.325813ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:32.830242Z","caller":"traceutil/trace.go:171","msg":"trace[903769791] linearizableReadLoop","detail":"{readStateIndex:26471; appliedIndex:26470; }","duration":"199.551439ms","start":"2026-09-05T07:02:32.630654Z","end":"2026-09-05T07:02:32.830205Z","steps":["trace[903769791] 'read index received' (duration: 138.680774ms)","trace[903769791] 'applied index is now lower than readState.Index' (duration: 60.869225ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:32.830324Z","caller":"traceutil/trace.go:171","msg":"trace[1047488659] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"307.498815ms","start":"2026-09-05T07:02:32.522762Z","end":"2026-09-05T07:02:32.83026Z","steps":["trace[1047488659] 'process raft request' (duration: 246.571057ms)","trace[1047488659] 'compare' (duration: 60.727613ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:02:32.830426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.46757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:02:32.830506Z","caller":"traceutil/trace.go:171","msg":"trace[1581507052] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25623; }","duration":"208.576003ms","start":"2026-09-05T07:02:32.621883Z","end":"2026-09-05T07:02:32.830459Z","steps":["trace[1581507052] 'agreement among raft nodes before linearized reading' (duration: 208.4374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:32.830509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:02:32.522664Z","time spent":"307.7446ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:02:32.845979Z","caller":"traceutil/trace.go:171","msg":"trace[2064181866] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"189.945613ms","start":"2026-09-05T07:02:32.656Z","end":"2026-09-05T07:02:32.845946Z","steps":["trace[2064181866] 'process raft request' (duration: 189.899982ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:32.846018Z","caller":"traceutil/trace.go:171","msg":"trace[854600198] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"205.693792ms","start":"2026-09-05T07:02:32.640282Z","end":"2026-09-05T07:02:32.845975Z","steps":["trace[854600198] 'process raft request' (duration: 205.427726ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:32.846042Z","caller":"traceutil/trace.go:171","msg":"trace[1588049169] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"192.738424ms","start":"2026-09-05T07:02:32.653268Z","end":"2026-09-05T07:02:32.846007Z","steps":["trace[1588049169] 'process raft request' (duration: 192.58284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:33.129896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.847162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:02:33.130013Z","caller":"traceutil/trace.go:171","msg":"trace[586246007] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25626; }","duration":"163.036206ms","start":"2026-09-05T07:02:32.966946Z","end":"2026-09-05T07:02:33.129982Z","steps":["trace[586246007] 'count revisions from in-memory index tree' (duration: 162.732499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:38.65695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.961936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-09-05T07:02:38.657031Z","caller":"traceutil/trace.go:171","msg":"trace[878709615] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25666; }","duration":"129.101159ms","start":"2026-09-05T07:02:38.527911Z","end":"2026-09-05T07:02:38.657013Z","steps":["trace[878709615] 'range keys from in-memory index tree' (duration: 128.785642ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:38.657498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.613713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-05T07:02:38.65753Z","caller":"traceutil/trace.go:171","msg":"trace[526996571] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25666; }","duration":"263.784846ms","start":"2026-09-05T07:02:38.393736Z","end":"2026-09-05T07:02:38.657521Z","steps":["trace[526996571] 'count revisions from in-memory index tree' (duration: 263.538091ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:38.829815Z","caller":"traceutil/trace.go:171","msg":"trace[1788554011] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"161.873211ms","start":"2026-09-05T07:02:38.667916Z","end":"2026-09-05T07:02:38.829789Z","steps":["trace[1788554011] 'process raft request' (duration: 161.699987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.119946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.25856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:02:39.120253Z","caller":"traceutil/trace.go:171","msg":"trace[368965091] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"262.951049ms","start":"2026-09-05T07:02:38.857283Z","end":"2026-09-05T07:02:39.120234Z","steps":["trace[368965091] 'process raft request' (duration: 155.295271ms)","trace[368965091] 'compare' (duration: 107.027654ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:39.135475Z","caller":"traceutil/trace.go:171","msg":"trace[548867690] linearizableReadLoop","detail":"{readStateIndex:26519; appliedIndex:26516; }","duration":"259.631669ms","start":"2026-09-05T07:02:38.875818Z","end":"2026-09-05T07:02:39.135449Z","steps":["trace[548867690] 'read index received' (duration: 136.848596ms)","trace[548867690] 'applied index is now lower than readState.Index' (duration: 122.782143ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:39.13569Z","caller":"traceutil/trace.go:171","msg":"trace[289803956] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"270.097423ms","start":"2026-09-05T07:02:38.865585Z","end":"2026-09-05T07:02:39.135682Z","steps":["trace[289803956] 'process raft request' (duration: 269.723985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.135936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.104059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-05T07:02:39.135969Z","caller":"traceutil/trace.go:171","msg":"trace[60644658] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25670; }","duration":"260.14778ms","start":"2026-09-05T07:02:38.875812Z","end":"2026-09-05T07:02:39.135959Z","steps":["trace[60644658] 'agreement among raft nodes before linearized reading' (duration: 260.044537ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:39.135958Z","caller":"traceutil/trace.go:171","msg":"trace[820422426] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"268.492798ms","start":"2026-09-05T07:02:38.867443Z","end":"2026-09-05T07:02:39.135936Z","steps":["trace[820422426] 'process raft request' (duration: 267.975607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.135993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.839014ms","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:8"} {"level":"info","ts":"2026-09-05T07:02:39.136092Z","caller":"traceutil/trace.go:171","msg":"trace[248822302] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25670; }","duration":"198.965267ms","start":"2026-09-05T07:02:38.937098Z","end":"2026-09-05T07:02:39.136063Z","steps":["trace[248822302] 'agreement among raft nodes before linearized reading' (duration: 198.765172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.367051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.658135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:02:39.367116Z","caller":"traceutil/trace.go:171","msg":"trace[121657331] transaction","detail":"{read_only:false; response_revision:25671; number_of_response:1; }","duration":"226.765762ms","start":"2026-09-05T07:02:39.140339Z","end":"2026-09-05T07:02:39.367105Z","steps":["trace[121657331] 'process raft request' (duration: 113.997834ms)","trace[121657331] 'compare' (duration: 112.584584ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:02:39.618402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.872477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:02:39.6186Z","caller":"traceutil/trace.go:171","msg":"trace[2033158163] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"457.267846ms","start":"2026-09-05T07:02:39.161317Z","end":"2026-09-05T07:02:39.618585Z","steps":["trace[2033158163] 'process raft request' (duration: 318.161183ms)","trace[2033158163] 'compare' (duration: 138.434078ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:02:39.618664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:02:39.161291Z","time spent":"457.331586ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:02:39.619373Z","caller":"traceutil/trace.go:171","msg":"trace[982683815] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"446.610018ms","start":"2026-09-05T07:02:39.172738Z","end":"2026-09-05T07:02:39.619348Z","steps":["trace[982683815] 'process raft request' (duration: 445.8163ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:39.619503Z","caller":"traceutil/trace.go:171","msg":"trace[1927164665] linearizableReadLoop","detail":"{readStateIndex:26523; appliedIndex:26519; }","duration":"380.356037ms","start":"2026-09-05T07:02:39.2391Z","end":"2026-09-05T07:02:39.619456Z","steps":["trace[1927164665] 'read index received' (duration: 15.344599ms)","trace[1927164665] 'applied index is now lower than readState.Index' (duration: 365.007388ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:39.619566Z","caller":"traceutil/trace.go:171","msg":"trace[1850432209] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"446.761561ms","start":"2026-09-05T07:02:39.172782Z","end":"2026-09-05T07:02:39.619544Z","steps":["trace[1850432209] 'process raft request' (duration: 446.499455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.619635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:02:39.172714Z","time spent":"446.72741ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-05T07:02:39.619657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:02:39.172757Z","time spent":"446.855053ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-05T07:02:39.619672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.581271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:02:39.619703Z","caller":"traceutil/trace.go:171","msg":"trace[1411305691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25674; }","duration":"380.613592ms","start":"2026-09-05T07:02:39.239081Z","end":"2026-09-05T07:02:39.619695Z","steps":["trace[1411305691] 'agreement among raft nodes before linearized reading' (duration: 380.556591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.619727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:02:39.23906Z","time spent":"380.662984ms","remote":"127.0.0.1:35216","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-09-05T07:02:39.619875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.917336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-05T07:02:39.619924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.109539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-05T07:02:39.619946Z","caller":"traceutil/trace.go:171","msg":"trace[1984675482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25674; }","duration":"296.043689ms","start":"2026-09-05T07:02:39.323883Z","end":"2026-09-05T07:02:39.619927Z","steps":["trace[1984675482] 'agreement among raft nodes before linearized reading' (duration: 295.794564ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.619977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.731154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-05T07:02:39.619988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.749347ms","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-09-05T07:02:39.619999Z","caller":"traceutil/trace.go:171","msg":"trace[1087501964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25674; }","duration":"262.794185ms","start":"2026-09-05T07:02:39.357199Z","end":"2026-09-05T07:02:39.619993Z","steps":["trace[1087501964] 'agreement among raft nodes before linearized reading' (duration: 262.690713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.620021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.974318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:02:39.620034Z","caller":"traceutil/trace.go:171","msg":"trace[160425900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25674; }","duration":"257.819289ms","start":"2026-09-05T07:02:39.362201Z","end":"2026-09-05T07:02:39.62002Z","steps":["trace[160425900] 'agreement among raft nodes before linearized reading' (duration: 257.686906ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:39.619962Z","caller":"traceutil/trace.go:171","msg":"trace[925904851] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25674; }","duration":"226.17747ms","start":"2026-09-05T07:02:39.393774Z","end":"2026-09-05T07:02:39.619952Z","steps":["trace[925904851] 'agreement among raft nodes before linearized reading' (duration: 226.111299ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:39.620058Z","caller":"traceutil/trace.go:171","msg":"trace[1507373824] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25674; }","duration":"144.067749ms","start":"2026-09-05T07:02:39.475979Z","end":"2026-09-05T07:02:39.620047Z","steps":["trace[1507373824] 'agreement among raft nodes before linearized reading' (duration: 144.013558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.886701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.426974ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:02:39.886845Z","caller":"traceutil/trace.go:171","msg":"trace[2083846669] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25674; }","duration":"100.585657ms","start":"2026-09-05T07:02:39.786237Z","end":"2026-09-05T07:02:39.886823Z","steps":["trace[2083846669] 'range keys from in-memory index tree' (duration: 100.381543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:39.88685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.232069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:02:39.887053Z","caller":"traceutil/trace.go:171","msg":"trace[1731031217] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"262.390627ms","start":"2026-09-05T07:02:39.624648Z","end":"2026-09-05T07:02:39.887039Z","steps":["trace[1731031217] 'process raft request' (duration: 262.272365ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:39.887058Z","caller":"traceutil/trace.go:171","msg":"trace[951208111] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"263.185814ms","start":"2026-09-05T07:02:39.62383Z","end":"2026-09-05T07:02:39.887016Z","steps":["trace[951208111] 'process raft request' (duration: 121.701251ms)","trace[951208111] 'compare' (duration: 140.960642ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:39.887465Z","caller":"traceutil/trace.go:171","msg":"trace[1323703378] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"262.691063ms","start":"2026-09-05T07:02:39.62475Z","end":"2026-09-05T07:02:39.887441Z","steps":["trace[1323703378] 'process raft request' (duration: 262.254994ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:39.887431Z","caller":"traceutil/trace.go:171","msg":"trace[1257145284] linearizableReadLoop","detail":"{readStateIndex:26526; appliedIndex:26523; }","duration":"154.556934ms","start":"2026-09-05T07:02:39.732832Z","end":"2026-09-05T07:02:39.887389Z","steps":["trace[1257145284] 'read index received' (duration: 12.708153ms)","trace[1257145284] 'applied index is now lower than readState.Index' (duration: 141.844531ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:02:39.887587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.738738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:02:39.887656Z","caller":"traceutil/trace.go:171","msg":"trace[126243052] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25677; }","duration":"154.862711ms","start":"2026-09-05T07:02:39.732775Z","end":"2026-09-05T07:02:39.887637Z","steps":["trace[126243052] 'agreement among raft nodes before linearized reading' (duration: 154.721658ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:40.117759Z","caller":"traceutil/trace.go:171","msg":"trace[1750815359] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"204.013764ms","start":"2026-09-05T07:02:39.913724Z","end":"2026-09-05T07:02:40.117737Z","steps":["trace[1750815359] 'process raft request' (duration: 117.543941ms)","trace[1750815359] 'compare' (duration: 86.31237ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:40.117953Z","caller":"traceutil/trace.go:171","msg":"trace[164539110] linearizableReadLoop","detail":"{readStateIndex:26528; appliedIndex:26526; }","duration":"142.23033ms","start":"2026-09-05T07:02:39.975694Z","end":"2026-09-05T07:02:40.117924Z","steps":["trace[164539110] 'read index received' (duration: 55.593533ms)","trace[164539110] 'applied index is now lower than readState.Index' (duration: 86.634547ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:40.118053Z","caller":"traceutil/trace.go:171","msg":"trace[1197589869] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"186.833266ms","start":"2026-09-05T07:02:39.93119Z","end":"2026-09-05T07:02:40.118023Z","steps":["trace[1197589869] 'process raft request' (duration: 186.501008ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:40.118242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.530496ms","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-09-05T07:02:40.118312Z","caller":"traceutil/trace.go:171","msg":"trace[586745773] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25679; }","duration":"142.613468ms","start":"2026-09-05T07:02:39.975679Z","end":"2026-09-05T07:02:40.118293Z","steps":["trace[586745773] 'agreement among raft nodes before linearized reading' (duration: 142.337502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:40.610207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.965055ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-05T07:02:40.610331Z","caller":"traceutil/trace.go:171","msg":"trace[2093415299] linearizableReadLoop","detail":"{readStateIndex:26529; appliedIndex:26528; }","duration":"117.674924ms","start":"2026-09-05T07:02:40.492633Z","end":"2026-09-05T07:02:40.610308Z","steps":["trace[2093415299] 'read index received' (duration: 29.021µs)","trace[2093415299] 'applied index is now lower than readState.Index' (duration: 117.643703ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:02:40.610581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.224425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-05T07:02:40.610621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.97548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-05T07:02:40.610668Z","caller":"traceutil/trace.go:171","msg":"trace[625171361] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25679; }","duration":"111.374528ms","start":"2026-09-05T07:02:40.499273Z","end":"2026-09-05T07:02:40.610648Z","steps":["trace[625171361] 'agreement among raft nodes before linearized reading' (duration: 111.223265ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:40.610673Z","caller":"traceutil/trace.go:171","msg":"trace[8040061] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25679; }","duration":"118.038511ms","start":"2026-09-05T07:02:40.49262Z","end":"2026-09-05T07:02:40.610658Z","steps":["trace[8040061] 'agreement among raft nodes before linearized reading' (duration: 117.829717ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:40.957764Z","caller":"traceutil/trace.go:171","msg":"trace[387640104] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"118.646104ms","start":"2026-09-05T07:02:40.839091Z","end":"2026-09-05T07:02:40.957737Z","steps":["trace[387640104] 'process raft request' (duration: 118.4585ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:51.817545Z","caller":"traceutil/trace.go:171","msg":"trace[362952222] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"147.84861ms","start":"2026-09-05T07:02:51.669662Z","end":"2026-09-05T07:02:51.817511Z","steps":["trace[362952222] 'process raft request' (duration: 147.576765ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:51.817668Z","caller":"traceutil/trace.go:171","msg":"trace[869306754] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"143.819854ms","start":"2026-09-05T07:02:51.673821Z","end":"2026-09-05T07:02:51.817641Z","steps":["trace[869306754] 'process raft request' (duration: 143.565009ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:53.629577Z","caller":"traceutil/trace.go:171","msg":"trace[1233438820] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"122.250422ms","start":"2026-09-05T07:02:53.507301Z","end":"2026-09-05T07:02:53.629551Z","steps":["trace[1233438820] 'process raft request' (duration: 122.048847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:57.086122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.664588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:02:57.086418Z","caller":"traceutil/trace.go:171","msg":"trace[1957028820] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25850; }","duration":"237.985474ms","start":"2026-09-05T07:02:56.848373Z","end":"2026-09-05T07:02:57.086359Z","steps":["trace[1957028820] 'count revisions from in-memory index tree' (duration: 237.574256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:57.087476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.904282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-09-05T07:02:57.087558Z","caller":"traceutil/trace.go:171","msg":"trace[1711968785] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:25850; }","duration":"217.033295ms","start":"2026-09-05T07:02:56.870506Z","end":"2026-09-05T07:02:57.087539Z","steps":["trace[1711968785] 'range keys from in-memory index tree' (duration: 216.065924ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:57.308789Z","caller":"traceutil/trace.go:171","msg":"trace[1717903745] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"101.281622ms","start":"2026-09-05T07:02:57.20747Z","end":"2026-09-05T07:02:57.308752Z","steps":["trace[1717903745] 'process raft request' (duration: 100.872494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:58.030777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.584955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:02:58.031155Z","caller":"traceutil/trace.go:171","msg":"trace[2122195256] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"193.716474ms","start":"2026-09-05T07:02:57.837414Z","end":"2026-09-05T07:02:58.03113Z","steps":["trace[2122195256] 'process raft request' (duration: 193.645423ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:58.031232Z","caller":"traceutil/trace.go:171","msg":"trace[1900762563] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"242.058201ms","start":"2026-09-05T07:02:57.78914Z","end":"2026-09-05T07:02:58.031198Z","steps":["trace[1900762563] 'process raft request' (duration: 241.775515ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:58.031312Z","caller":"traceutil/trace.go:171","msg":"trace[414617471] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"279.2868ms","start":"2026-09-05T07:02:57.751973Z","end":"2026-09-05T07:02:58.03126Z","steps":["trace[414617471] 'process raft request' (duration: 124.103962ms)","trace[414617471] 'compare' (duration: 143.395046ms)","trace[414617471] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:537; } (duration: 10.442794ms)"],"step_count":3} {"level":"info","ts":"2026-09-05T07:02:58.031407Z","caller":"traceutil/trace.go:171","msg":"trace[1175223569] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"196.886052ms","start":"2026-09-05T07:02:57.83451Z","end":"2026-09-05T07:02:58.031396Z","steps":["trace[1175223569] 'process raft request' (duration: 196.498334ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:58.192063Z","caller":"traceutil/trace.go:171","msg":"trace[1666257229] linearizableReadLoop","detail":"{readStateIndex:26715; appliedIndex:26710; }","duration":"207.893259ms","start":"2026-09-05T07:02:57.984142Z","end":"2026-09-05T07:02:58.192036Z","steps":["trace[1666257229] 'read index received' (duration: 45.68384ms)","trace[1666257229] 'applied index is now lower than readState.Index' (duration: 162.207539ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:58.192155Z","caller":"traceutil/trace.go:171","msg":"trace[968854210] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"297.874138ms","start":"2026-09-05T07:02:57.894248Z","end":"2026-09-05T07:02:58.192122Z","steps":["trace[968854210] 'process raft request' (duration: 268.536509ms)","trace[968854210] 'compare' (duration: 28.973761ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:02:58.192423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.261757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-05T07:02:58.192475Z","caller":"traceutil/trace.go:171","msg":"trace[1678667164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25861; }","duration":"208.39076ms","start":"2026-09-05T07:02:57.98407Z","end":"2026-09-05T07:02:58.19246Z","steps":["trace[1678667164] 'agreement among raft nodes before linearized reading' (duration: 208.124733ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:58.192657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.830365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-05T07:02:58.192728Z","caller":"traceutil/trace.go:171","msg":"trace[1601766694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25861; }","duration":"205.911707ms","start":"2026-09-05T07:02:57.986776Z","end":"2026-09-05T07:02:58.192688Z","steps":["trace[1601766694] 'agreement among raft nodes before linearized reading' (duration: 205.500987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:58.19261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.608653ms","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-09-05T07:02:58.19299Z","caller":"traceutil/trace.go:171","msg":"trace[1957227355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25861; }","duration":"201.172215ms","start":"2026-09-05T07:02:57.991801Z","end":"2026-09-05T07:02:58.192974Z","steps":["trace[1957227355] 'agreement among raft nodes before linearized reading' (duration: 200.499389ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:58.371298Z","caller":"traceutil/trace.go:171","msg":"trace[1104004909] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"175.260067ms","start":"2026-09-05T07:02:58.196007Z","end":"2026-09-05T07:02:58.371267Z","steps":["trace[1104004909] 'process raft request' (duration: 127.075764ms)","trace[1104004909] 'compare' (duration: 47.954029ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:58.37154Z","caller":"traceutil/trace.go:171","msg":"trace[1895309343] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"174.630865ms","start":"2026-09-05T07:02:58.196896Z","end":"2026-09-05T07:02:58.371527Z","steps":["trace[1895309343] 'process raft request' (duration: 174.325358ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:58.371725Z","caller":"traceutil/trace.go:171","msg":"trace[1860705906] transaction","detail":"{read_only:false; response_revision:25864; number_of_response:1; }","duration":"174.710917ms","start":"2026-09-05T07:02:58.197006Z","end":"2026-09-05T07:02:58.371717Z","steps":["trace[1860705906] 'process raft request' (duration: 174.480491ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:58.371931Z","caller":"traceutil/trace.go:171","msg":"trace[840711286] transaction","detail":"{read_only:false; response_revision:25866; number_of_response:1; }","duration":"158.790445ms","start":"2026-09-05T07:02:58.213114Z","end":"2026-09-05T07:02:58.371904Z","steps":["trace[840711286] 'process raft request' (duration: 158.666662ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:58.371962Z","caller":"traceutil/trace.go:171","msg":"trace[1605166977] transaction","detail":"{read_only:false; response_revision:25865; number_of_response:1; }","duration":"159.622513ms","start":"2026-09-05T07:02:58.212301Z","end":"2026-09-05T07:02:58.371923Z","steps":["trace[1605166977] 'process raft request' (duration: 159.384328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:58.637362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.416318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-05T07:02:58.637825Z","caller":"traceutil/trace.go:171","msg":"trace[352830534] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25867; }","duration":"165.868327ms","start":"2026-09-05T07:02:58.471867Z","end":"2026-09-05T07:02:58.637736Z","steps":["trace[352830534] 'count revisions from in-memory index tree' (duration: 165.346736ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:59.540876Z","caller":"traceutil/trace.go:171","msg":"trace[1036398348] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26723; }","duration":"269.451428ms","start":"2026-09-05T07:02:59.271397Z","end":"2026-09-05T07:02:59.540848Z","steps":["trace[1036398348] 'read index received' (duration: 269.376066ms)","trace[1036398348] 'applied index is now lower than readState.Index' (duration: 73.972µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:02:59.540906Z","caller":"traceutil/trace.go:171","msg":"trace[1666556806] transaction","detail":"{read_only:false; response_revision:25870; number_of_response:1; }","duration":"276.580242ms","start":"2026-09-05T07:02:59.264294Z","end":"2026-09-05T07:02:59.540874Z","steps":["trace[1666556806] 'process raft request' (duration: 276.377397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:59.541278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.860007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-05T07:02:59.541317Z","caller":"traceutil/trace.go:171","msg":"trace[487603281] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25870; }","duration":"269.9728ms","start":"2026-09-05T07:02:59.271334Z","end":"2026-09-05T07:02:59.541307Z","steps":["trace[487603281] 'agreement among raft nodes before linearized reading' (duration: 269.637533ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:59.561031Z","caller":"traceutil/trace.go:171","msg":"trace[1748285284] transaction","detail":"{read_only:false; response_revision:25871; number_of_response:1; }","duration":"277.094732ms","start":"2026-09-05T07:02:59.28391Z","end":"2026-09-05T07:02:59.561005Z","steps":["trace[1748285284] 'process raft request' (duration: 276.893508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:59.561286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.038466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-05T07:02:59.561343Z","caller":"traceutil/trace.go:171","msg":"trace[1720568350] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25872; }","duration":"281.098338ms","start":"2026-09-05T07:02:59.280228Z","end":"2026-09-05T07:02:59.561326Z","steps":["trace[1720568350] 'agreement among raft nodes before linearized reading' (duration: 281.006726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:59.561599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.372125ms","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-09-05T07:02:59.561639Z","caller":"traceutil/trace.go:171","msg":"trace[1202037842] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25872; }","duration":"254.413596ms","start":"2026-09-05T07:02:59.307215Z","end":"2026-09-05T07:02:59.561628Z","steps":["trace[1202037842] 'agreement among raft nodes before linearized reading' (duration: 254.338334ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:59.561517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.556388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-05T07:02:59.561861Z","caller":"traceutil/trace.go:171","msg":"trace[450688578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25872; }","duration":"226.919036ms","start":"2026-09-05T07:02:59.334909Z","end":"2026-09-05T07:02:59.561828Z","steps":["trace[450688578] 'agreement among raft nodes before linearized reading' (duration: 226.375815ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:59.561881Z","caller":"traceutil/trace.go:171","msg":"trace[261004378] transaction","detail":"{read_only:false; response_revision:25872; number_of_response:1; }","duration":"247.207431ms","start":"2026-09-05T07:02:59.314653Z","end":"2026-09-05T07:02:59.561861Z","steps":["trace[261004378] 'process raft request' (duration: 246.306002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:59.562093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.974097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b082cd0e-1b9d-4021-937f-0ef8445d4b6a\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-09-05T07:02:59.561921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.0502ms","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-09-05T07:02:59.562201Z","caller":"traceutil/trace.go:171","msg":"trace[1992467855] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b082cd0e-1b9d-4021-937f-0ef8445d4b6a; range_end:; response_count:1; response_revision:25872; }","duration":"186.10349ms","start":"2026-09-05T07:02:59.376052Z","end":"2026-09-05T07:02:59.562155Z","steps":["trace[1992467855] 'agreement among raft nodes before linearized reading' (duration: 185.864375ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:02:59.562208Z","caller":"traceutil/trace.go:171","msg":"trace[41318288] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25872; }","duration":"269.302115ms","start":"2026-09-05T07:02:59.292859Z","end":"2026-09-05T07:02:59.562161Z","steps":["trace[41318288] 'agreement among raft nodes before linearized reading' (duration: 268.911767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:02:59.846008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.859387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:02:59.846256Z","caller":"traceutil/trace.go:171","msg":"trace[228645014] transaction","detail":"{read_only:false; response_revision:25874; number_of_response:1; }","duration":"278.222176ms","start":"2026-09-05T07:02:59.568013Z","end":"2026-09-05T07:02:59.846235Z","steps":["trace[228645014] 'process raft request' (duration: 147.029492ms)","trace[228645014] 'compare' (duration: 130.329296ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:00.119638Z","caller":"traceutil/trace.go:171","msg":"trace[1155171820] transaction","detail":"{read_only:false; response_revision:25875; number_of_response:1; }","duration":"262.621761ms","start":"2026-09-05T07:02:59.856927Z","end":"2026-09-05T07:03:00.119549Z","steps":["trace[1155171820] 'process raft request' (duration: 262.335615ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.317053Z","caller":"traceutil/trace.go:171","msg":"trace[962479406] transaction","detail":"{read_only:false; response_revision:25876; number_of_response:1; }","duration":"279.384652ms","start":"2026-09-05T07:03:00.037643Z","end":"2026-09-05T07:03:00.317028Z","steps":["trace[962479406] 'process raft request' (duration: 279.179367ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.317316Z","caller":"traceutil/trace.go:171","msg":"trace[801151214] transaction","detail":"{read_only:false; response_revision:25877; number_of_response:1; }","duration":"278.675575ms","start":"2026-09-05T07:03:00.03863Z","end":"2026-09-05T07:03:00.317306Z","steps":["trace[801151214] 'process raft request' (duration: 278.353199ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.317632Z","caller":"traceutil/trace.go:171","msg":"trace[2109730933] linearizableReadLoop","detail":"{readStateIndex:26730; appliedIndex:26728; }","duration":"279.083835ms","start":"2026-09-05T07:03:00.038515Z","end":"2026-09-05T07:03:00.317598Z","steps":["trace[2109730933] 'read index received' (duration: 80.819213ms)","trace[2109730933] 'applied index is now lower than readState.Index' (duration: 198.262612ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:00.317774Z","caller":"traceutil/trace.go:171","msg":"trace[1801027942] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"278.507542ms","start":"2026-09-05T07:03:00.039253Z","end":"2026-09-05T07:03:00.31776Z","steps":["trace[1801027942] 'process raft request' (duration: 278.011362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.317922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.411079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-09-05T07:03:00.317966Z","caller":"traceutil/trace.go:171","msg":"trace[1114250952] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25878; }","duration":"180.550202ms","start":"2026-09-05T07:03:00.137404Z","end":"2026-09-05T07:03:00.317955Z","steps":["trace[1114250952] 'agreement among raft nodes before linearized reading' (duration: 180.400109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.318001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.400724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:00.318042Z","caller":"traceutil/trace.go:171","msg":"trace[2037785195] 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:25878; }","duration":"129.483847ms","start":"2026-09-05T07:03:00.188546Z","end":"2026-09-05T07:03:00.31803Z","steps":["trace[2037785195] 'agreement among raft nodes before linearized reading' (duration: 129.382335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.318141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.06278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:00.318196Z","caller":"traceutil/trace.go:171","msg":"trace[156912342] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25878; }","duration":"151.170822ms","start":"2026-09-05T07:03:00.167015Z","end":"2026-09-05T07:03:00.318185Z","steps":["trace[156912342] 'agreement among raft nodes before linearized reading' (duration: 151.07845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.318259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.725728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-09-05T07:03:00.318335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.45057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:00.318511Z","caller":"traceutil/trace.go:171","msg":"trace[1814826535] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25878; }","duration":"279.84631ms","start":"2026-09-05T07:03:00.038505Z","end":"2026-09-05T07:03:00.318351Z","steps":["trace[1814826535] 'agreement among raft nodes before linearized reading' (duration: 279.559324ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.318548Z","caller":"traceutil/trace.go:171","msg":"trace[759405945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25878; }","duration":"132.712346ms","start":"2026-09-05T07:03:00.1858Z","end":"2026-09-05T07:03:00.318512Z","steps":["trace[759405945] 'agreement among raft nodes before linearized reading' (duration: 132.400119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.591747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.437743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:03:00.591882Z","caller":"traceutil/trace.go:171","msg":"trace[672685132] linearizableReadLoop","detail":"{readStateIndex:26733; appliedIndex:26732; }","duration":"267.931606ms","start":"2026-09-05T07:03:00.323926Z","end":"2026-09-05T07:03:00.591858Z","steps":["trace[672685132] 'read index received' (duration: 105.273148ms)","trace[672685132] 'applied index is now lower than readState.Index' (duration: 162.656378ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:00.591941Z","caller":"traceutil/trace.go:171","msg":"trace[411079398] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"268.440157ms","start":"2026-09-05T07:03:00.323444Z","end":"2026-09-05T07:03:00.591884Z","steps":["trace[411079398] 'process raft request' (duration: 105.768068ms)","trace[411079398] 'compare' (duration: 162.041015ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:00.591994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.059779ms","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-09-05T07:03:00.592028Z","caller":"traceutil/trace.go:171","msg":"trace[1124765730] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25879; }","duration":"268.13989ms","start":"2026-09-05T07:03:00.323874Z","end":"2026-09-05T07:03:00.592014Z","steps":["trace[1124765730] 'agreement among raft nodes before linearized reading' (duration: 268.071939ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.595023Z","caller":"traceutil/trace.go:171","msg":"trace[1549702957] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"270.862069ms","start":"2026-09-05T07:03:00.324119Z","end":"2026-09-05T07:03:00.594981Z","steps":["trace[1549702957] 'process raft request' (duration: 269.9594ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.595308Z","caller":"traceutil/trace.go:171","msg":"trace[1634990423] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"217.492403ms","start":"2026-09-05T07:03:00.377786Z","end":"2026-09-05T07:03:00.595278Z","steps":["trace[1634990423] 'process raft request' (duration: 216.453891ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.595455Z","caller":"traceutil/trace.go:171","msg":"trace[318774938] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"217.607166ms","start":"2026-09-05T07:03:00.377834Z","end":"2026-09-05T07:03:00.595441Z","steps":["trace[318774938] 'process raft request' (duration: 216.448981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.64446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.276002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-05T07:03:00.64458Z","caller":"traceutil/trace.go:171","msg":"trace[660454429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25882; }","duration":"267.439966ms","start":"2026-09-05T07:03:00.377109Z","end":"2026-09-05T07:03:00.644549Z","steps":["trace[660454429] 'agreement among raft nodes before linearized reading' (duration: 267.120819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.645571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.240674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-05T07:03:00.645566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.470519ms","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-09-05T07:03:00.645626Z","caller":"traceutil/trace.go:171","msg":"trace[451034792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25882; }","duration":"270.334866ms","start":"2026-09-05T07:03:00.375276Z","end":"2026-09-05T07:03:00.645611Z","steps":["trace[451034792] 'agreement among raft nodes before linearized reading' (duration: 270.205184ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.64566Z","caller":"traceutil/trace.go:171","msg":"trace[559713283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25882; }","duration":"269.593871ms","start":"2026-09-05T07:03:00.376035Z","end":"2026-09-05T07:03:00.645629Z","steps":["trace[559713283] 'agreement among raft nodes before linearized reading' (duration: 269.389096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.645761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.072145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-05T07:03:00.645899Z","caller":"traceutil/trace.go:171","msg":"trace[1160221356] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25882; }","duration":"308.362803ms","start":"2026-09-05T07:03:00.337498Z","end":"2026-09-05T07:03:00.645861Z","steps":["trace[1160221356] 'agreement among raft nodes before linearized reading' (duration: 306.80102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:00.645999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:00.337437Z","time spent":"308.512245ms","remote":"127.0.0.1:35336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":143,"response size":32,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-09-05T07:03:00.755086Z","caller":"traceutil/trace.go:171","msg":"trace[16137653] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"102.945388ms","start":"2026-09-05T07:03:00.652111Z","end":"2026-09-05T07:03:00.755057Z","steps":["trace[16137653] 'process raft request' (duration: 102.746913ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.897234Z","caller":"traceutil/trace.go:171","msg":"trace[654851608] linearizableReadLoop","detail":"{readStateIndex:26740; appliedIndex:26737; }","duration":"244.023713ms","start":"2026-09-05T07:03:00.653193Z","end":"2026-09-05T07:03:00.897217Z","steps":["trace[654851608] 'read index received' (duration: 101.745522ms)","trace[654851608] 'applied index is now lower than readState.Index' (duration: 142.276891ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:00.897312Z","caller":"traceutil/trace.go:171","msg":"trace[529344022] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"244.630225ms","start":"2026-09-05T07:03:00.652649Z","end":"2026-09-05T07:03:00.897279Z","steps":["trace[529344022] 'process raft request' (duration: 163.753621ms)","trace[529344022] 'compare' (duration: 80.535947ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:00.897363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.146546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:00.897406Z","caller":"traceutil/trace.go:171","msg":"trace[628295590] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25885; }","duration":"244.239198ms","start":"2026-09-05T07:03:00.653152Z","end":"2026-09-05T07:03:00.897391Z","steps":["trace[628295590] 'agreement among raft nodes before linearized reading' (duration: 244.134086ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:00.897616Z","caller":"traceutil/trace.go:171","msg":"trace[584734782] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"244.530803ms","start":"2026-09-05T07:03:00.653072Z","end":"2026-09-05T07:03:00.897603Z","steps":["trace[584734782] 'process raft request' (duration: 244.046063ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:06.913024Z","caller":"traceutil/trace.go:171","msg":"trace[1076117149] linearizableReadLoop","detail":"{readStateIndex:26805; appliedIndex:26804; }","duration":"130.069099ms","start":"2026-09-05T07:03:06.782917Z","end":"2026-09-05T07:03:06.912986Z","steps":["trace[1076117149] 'read index received' (duration: 118.988762ms)","trace[1076117149] 'applied index is now lower than readState.Index' (duration: 11.079187ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:06.91308Z","caller":"traceutil/trace.go:171","msg":"trace[1538424986] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"241.225324ms","start":"2026-09-05T07:03:06.671828Z","end":"2026-09-05T07:03:06.913054Z","steps":["trace[1538424986] 'process raft request' (duration: 230.098955ms)","trace[1538424986] 'compare' (duration: 10.565426ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:06.91338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.443138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-05T07:03:06.913508Z","caller":"traceutil/trace.go:171","msg":"trace[1805534830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25948; }","duration":"130.643252ms","start":"2026-09-05T07:03:06.782842Z","end":"2026-09-05T07:03:06.913486Z","steps":["trace[1805534830] 'agreement among raft nodes before linearized reading' (duration: 130.267284ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:07.206462Z","caller":"traceutil/trace.go:171","msg":"trace[865463390] linearizableReadLoop","detail":"{readStateIndex:26806; appliedIndex:26805; }","duration":"278.603704ms","start":"2026-09-05T07:03:06.927828Z","end":"2026-09-05T07:03:07.206431Z","steps":["trace[865463390] 'read index received' (duration: 239.2438ms)","trace[865463390] 'applied index is now lower than readState.Index' (duration: 39.358454ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:07.206731Z","caller":"traceutil/trace.go:171","msg":"trace[159192101] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"287.054685ms","start":"2026-09-05T07:03:06.919635Z","end":"2026-09-05T07:03:07.206689Z","steps":["trace[159192101] 'process raft request' (duration: 247.467726ms)","trace[159192101] 'compare' (duration: 39.16443ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:07.206762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.083573ms","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-09-05T07:03:07.206895Z","caller":"traceutil/trace.go:171","msg":"trace[1027619865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25949; }","duration":"278.251266ms","start":"2026-09-05T07:03:06.928627Z","end":"2026-09-05T07:03:07.206879Z","steps":["trace[1027619865] 'agreement among raft nodes before linearized reading' (duration: 278.025041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:07.206871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.991362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-09-05T07:03:07.206919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.539872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:07.206983Z","caller":"traceutil/trace.go:171","msg":"trace[1339622717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25949; }","duration":"279.188407ms","start":"2026-09-05T07:03:06.92777Z","end":"2026-09-05T07:03:07.206958Z","steps":["trace[1339622717] 'agreement among raft nodes before linearized reading' (duration: 278.841349ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:07.207011Z","caller":"traceutil/trace.go:171","msg":"trace[154680670] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25949; }","duration":"187.718705ms","start":"2026-09-05T07:03:07.019269Z","end":"2026-09-05T07:03:07.206988Z","steps":["trace[154680670] 'agreement among raft nodes before linearized reading' (duration: 187.47162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:07.20704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.743515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-09-05T07:03:07.207195Z","caller":"traceutil/trace.go:171","msg":"trace[2056558347] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25949; }","duration":"269.922458ms","start":"2026-09-05T07:03:06.937227Z","end":"2026-09-05T07:03:07.20715Z","steps":["trace[2056558347] 'agreement among raft nodes before linearized reading' (duration: 269.516499ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:07.20704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.152422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-09-05T07:03:07.207484Z","caller":"traceutil/trace.go:171","msg":"trace[504775423] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25949; }","duration":"173.625754ms","start":"2026-09-05T07:03:07.033758Z","end":"2026-09-05T07:03:07.207383Z","steps":["trace[504775423] 'agreement among raft nodes before linearized reading' (duration: 172.916798ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:07.314197Z","caller":"traceutil/trace.go:171","msg":"trace[1675268680] transaction","detail":"{read_only:false; response_revision:25950; number_of_response:1; }","duration":"100.546147ms","start":"2026-09-05T07:03:07.213597Z","end":"2026-09-05T07:03:07.314143Z","steps":["trace[1675268680] 'process raft request' (duration: 97.703066ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:07.316151Z","caller":"traceutil/trace.go:171","msg":"trace[834517668] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"100.749591ms","start":"2026-09-05T07:03:07.215374Z","end":"2026-09-05T07:03:07.316124Z","steps":["trace[834517668] 'process raft request' (duration: 100.389333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:19.614733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.00899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-05T07:03:19.614806Z","caller":"traceutil/trace.go:171","msg":"trace[834595711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26050; }","duration":"235.119922ms","start":"2026-09-05T07:03:19.379669Z","end":"2026-09-05T07:03:19.614789Z","steps":["trace[834595711] 'range keys from in-memory index tree' (duration: 234.866767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:19.614824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.561814ms","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-09-05T07:03:19.614865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.711821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:19.614936Z","caller":"traceutil/trace.go:171","msg":"trace[1327808102] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:26050; }","duration":"184.800213ms","start":"2026-09-05T07:03:19.43012Z","end":"2026-09-05T07:03:19.614921Z","steps":["trace[1327808102] 'count revisions from in-memory index tree' (duration: 184.64972ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:19.614947Z","caller":"traceutil/trace.go:171","msg":"trace[381773089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26050; }","duration":"174.758958ms","start":"2026-09-05T07:03:19.440158Z","end":"2026-09-05T07:03:19.614917Z","steps":["trace[381773089] 'range keys from in-memory index tree' (duration: 174.337929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:19.614962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.466136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:19.615019Z","caller":"traceutil/trace.go:171","msg":"trace[1811923824] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26050; }","duration":"135.579608ms","start":"2026-09-05T07:03:19.479423Z","end":"2026-09-05T07:03:19.615002Z","steps":["trace[1811923824] 'count revisions from in-memory index tree' (duration: 135.415955ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:19.615026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.0443ms","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-09-05T07:03:19.615079Z","caller":"traceutil/trace.go:171","msg":"trace[1132735119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26050; }","duration":"235.176623ms","start":"2026-09-05T07:03:19.379887Z","end":"2026-09-05T07:03:19.615063Z","steps":["trace[1132735119] 'range keys from in-memory index tree' (duration: 234.747694ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:19.760133Z","caller":"traceutil/trace.go:171","msg":"trace[650473351] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"141.452164ms","start":"2026-09-05T07:03:19.618659Z","end":"2026-09-05T07:03:19.760111Z","steps":["trace[650473351] 'process raft request' (duration: 141.32773ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:19.900673Z","caller":"traceutil/trace.go:171","msg":"trace[396678985] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"280.187368ms","start":"2026-09-05T07:03:19.620453Z","end":"2026-09-05T07:03:19.90064Z","steps":["trace[396678985] 'process raft request' (duration: 279.604975ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:19.900765Z","caller":"traceutil/trace.go:171","msg":"trace[636506261] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"280.153237ms","start":"2026-09-05T07:03:19.620532Z","end":"2026-09-05T07:03:19.900685Z","steps":["trace[636506261] 'process raft request' (duration: 279.992684ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:19.900862Z","caller":"traceutil/trace.go:171","msg":"trace[102871070] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"258.589016ms","start":"2026-09-05T07:03:19.642264Z","end":"2026-09-05T07:03:19.900853Z","steps":["trace[102871070] 'process raft request' (duration: 258.330901ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:19.901009Z","caller":"traceutil/trace.go:171","msg":"trace[1459408381] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"256.591223ms","start":"2026-09-05T07:03:19.644391Z","end":"2026-09-05T07:03:19.900982Z","steps":["trace[1459408381] 'process raft request' (duration: 256.247555ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:20.241606Z","caller":"traceutil/trace.go:171","msg":"trace[1263712924] transaction","detail":"{read_only:false; response_revision:26056; number_of_response:1; }","duration":"274.928445ms","start":"2026-09-05T07:03:19.966618Z","end":"2026-09-05T07:03:20.241547Z","steps":["trace[1263712924] 'process raft request' (duration: 204.566497ms)","trace[1263712924] 'compare' (duration: 70.089282ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:20.874161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10595738952055061096,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-05T07:03:21.039147Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006926.wal"} {"level":"info","ts":"2026-09-05T07:03:21.039776Z","caller":"traceutil/trace.go:171","msg":"trace[2009081864] transaction","detail":"{read_only:false; response_revision:26058; number_of_response:1; }","duration":"673.501673ms","start":"2026-09-05T07:03:20.366254Z","end":"2026-09-05T07:03:21.039755Z","steps":["trace[2009081864] 'process raft request' (duration: 673.186276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:21.039913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.366224Z","time spent":"673.618785ms","remote":"127.0.0.1:40402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419277,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:03:21.041823Z","caller":"traceutil/trace.go:171","msg":"trace[1662126766] transaction","detail":"{read_only:false; response_revision:26059; number_of_response:1; }","duration":"644.987642ms","start":"2026-09-05T07:03:20.396809Z","end":"2026-09-05T07:03:21.041797Z","steps":["trace[1662126766] 'process raft request' (duration: 644.511442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:21.041986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.396791Z","time spent":"645.105374ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:03:21.042349Z","caller":"traceutil/trace.go:171","msg":"trace[2029958172] transaction","detail":"{read_only:false; response_revision:26060; number_of_response:1; }","duration":"645.428831ms","start":"2026-09-05T07:03:20.396904Z","end":"2026-09-05T07:03:21.042333Z","steps":["trace[2029958172] 'process raft request' (duration: 644.534282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:21.042426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.396872Z","time spent":"645.509852ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:03:21.042781Z","caller":"traceutil/trace.go:171","msg":"trace[873300840] transaction","detail":"{read_only:false; response_revision:26061; number_of_response:1; }","duration":"643.56813ms","start":"2026-09-05T07:03:20.399199Z","end":"2026-09-05T07:03:21.042767Z","steps":["trace[873300840] 'process raft request' (duration: 642.319604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:21.042856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.399167Z","time spent":"643.653412ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:03:21.045273Z","caller":"traceutil/trace.go:171","msg":"trace[1761871784] transaction","detail":"{read_only:false; response_revision:26062; number_of_response:1; }","duration":"354.867319ms","start":"2026-09-05T07:03:20.690386Z","end":"2026-09-05T07:03:21.045253Z","steps":["trace[1761871784] 'process raft request' (duration: 354.640624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:21.045357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.690356Z","time spent":"354.95877ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:03:21.045401Z","caller":"traceutil/trace.go:171","msg":"trace[1283644676] transaction","detail":"{read_only:false; response_revision:26063; number_of_response:1; }","duration":"326.814588ms","start":"2026-09-05T07:03:20.718566Z","end":"2026-09-05T07:03:21.04538Z","steps":["trace[1283644676] 'process raft request' (duration: 326.647594ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:21.045494Z","caller":"traceutil/trace.go:171","msg":"trace[1212838048] linearizableReadLoop","detail":"{readStateIndex:26923; appliedIndex:26916; }","duration":"672.072202ms","start":"2026-09-05T07:03:20.373203Z","end":"2026-09-05T07:03:21.045275Z","steps":["trace[1212838048] 'read index received' (duration: 666.277308ms)","trace[1212838048] 'applied index is now lower than readState.Index' (duration: 5.793994ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:21.045516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.718552Z","time spent":"326.888869ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-05T07:03:21.045532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.332387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:21.045592Z","caller":"traceutil/trace.go:171","msg":"trace[470487314] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26063; }","duration":"672.45035ms","start":"2026-09-05T07:03:20.373125Z","end":"2026-09-05T07:03:21.045576Z","steps":["trace[470487314] 'agreement among raft nodes before linearized reading' (duration: 672.346738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:21.045624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.373108Z","time spent":"672.507201ms","remote":"127.0.0.1:44014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-09-05T07:03:21.045653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.079892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:21.045696Z","caller":"traceutil/trace.go:171","msg":"trace[896387352] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:26063; }","duration":"395.180634ms","start":"2026-09-05T07:03:20.650501Z","end":"2026-09-05T07:03:21.045682Z","steps":["trace[896387352] 'agreement among raft nodes before linearized reading' (duration: 395.089752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:21.045733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:03:20.650479Z","time spent":"395.240355ms","remote":"127.0.0.1:44122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-09-05T07:03:26.912482Z","caller":"traceutil/trace.go:171","msg":"trace[1692035790] transaction","detail":"{read_only:false; response_revision:26111; number_of_response:1; }","duration":"143.327533ms","start":"2026-09-05T07:03:26.769128Z","end":"2026-09-05T07:03:26.912456Z","steps":["trace[1692035790] 'process raft request' (duration: 143.18131ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.047796Z","caller":"traceutil/trace.go:171","msg":"trace[2004423007] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"123.255713ms","start":"2026-09-05T07:03:26.924514Z","end":"2026-09-05T07:03:27.04777Z","steps":["trace[2004423007] 'process raft request' (duration: 57.053163ms)","trace[2004423007] 'compare' (duration: 65.982025ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:27.204246Z","caller":"traceutil/trace.go:171","msg":"trace[1840112847] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"139.87031ms","start":"2026-09-05T07:03:27.064331Z","end":"2026-09-05T07:03:27.204202Z","steps":["trace[1840112847] 'process raft request' (duration: 139.385899ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.253962Z","caller":"traceutil/trace.go:171","msg":"trace[73453548] linearizableReadLoop","detail":"{readStateIndex:26978; appliedIndex:26973; }","duration":"177.526578ms","start":"2026-09-05T07:03:27.076386Z","end":"2026-09-05T07:03:27.253913Z","steps":["trace[73453548] 'read index received' (duration: 127.427073ms)","trace[73453548] 'applied index is now lower than readState.Index' (duration: 50.098225ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:27.254029Z","caller":"traceutil/trace.go:171","msg":"trace[354437937] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"188.034193ms","start":"2026-09-05T07:03:27.065985Z","end":"2026-09-05T07:03:27.254019Z","steps":["trace[354437937] 'process raft request' (duration: 187.638874ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.253974Z","caller":"traceutil/trace.go:171","msg":"trace[589534757] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"178.447728ms","start":"2026-09-05T07:03:27.0755Z","end":"2026-09-05T07:03:27.253948Z","steps":["trace[589534757] 'process raft request' (duration: 178.289954ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.254127Z","caller":"traceutil/trace.go:171","msg":"trace[687906370] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"178.490308ms","start":"2026-09-05T07:03:27.075564Z","end":"2026-09-05T07:03:27.254054Z","steps":["trace[687906370] 'process raft request' (duration: 178.263453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:27.254293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.886283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-05T07:03:27.254294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.869815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-05T07:03:27.254311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.95473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:27.254335Z","caller":"traceutil/trace.go:171","msg":"trace[1347191016] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26117; }","duration":"176.999636ms","start":"2026-09-05T07:03:27.077322Z","end":"2026-09-05T07:03:27.254321Z","steps":["trace[1347191016] 'agreement among raft nodes before linearized reading' (duration: 176.908104ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.254349Z","caller":"traceutil/trace.go:171","msg":"trace[870938866] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26117; }","duration":"174.014462ms","start":"2026-09-05T07:03:27.080322Z","end":"2026-09-05T07:03:27.254337Z","steps":["trace[870938866] 'agreement among raft nodes before linearized reading' (duration: 173.948161ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.254349Z","caller":"traceutil/trace.go:171","msg":"trace[1684032138] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26117; }","duration":"177.968467ms","start":"2026-09-05T07:03:27.076364Z","end":"2026-09-05T07:03:27.254332Z","steps":["trace[1684032138] 'agreement among raft nodes before linearized reading' (duration: 177.691801ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.254343Z","caller":"traceutil/trace.go:171","msg":"trace[1514958521] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"178.60602ms","start":"2026-09-05T07:03:27.075695Z","end":"2026-09-05T07:03:27.254301Z","steps":["trace[1514958521] 'process raft request' (duration: 178.168081ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:27.394063Z","caller":"traceutil/trace.go:171","msg":"trace[629801645] linearizableReadLoop","detail":"{readStateIndex:26979; appliedIndex:26978; }","duration":"133.187667ms","start":"2026-09-05T07:03:27.260847Z","end":"2026-09-05T07:03:27.394035Z","steps":["trace[629801645] 'read index received' (duration: 132.90431ms)","trace[629801645] 'applied index is now lower than readState.Index' (duration: 282.637µs)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:27.394088Z","caller":"traceutil/trace.go:171","msg":"trace[297883681] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"135.272651ms","start":"2026-09-05T07:03:27.258793Z","end":"2026-09-05T07:03:27.394066Z","steps":["trace[297883681] 'process raft request' (duration: 135.074057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:27.394353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.278828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-09-05T07:03:27.394394Z","caller":"traceutil/trace.go:171","msg":"trace[1646951323] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26118; }","duration":"132.356419ms","start":"2026-09-05T07:03:27.262027Z","end":"2026-09-05T07:03:27.394384Z","steps":["trace[1646951323] 'agreement among raft nodes before linearized reading' (duration: 132.189565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:27.394421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.551804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18d25abe84c1a99e\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-09-05T07:03:27.394467Z","caller":"traceutil/trace.go:171","msg":"trace[765207941] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18d25abe84c1a99e; range_end:; response_count:1; response_revision:26118; }","duration":"133.625646ms","start":"2026-09-05T07:03:27.260827Z","end":"2026-09-05T07:03:27.394453Z","steps":["trace[765207941] 'agreement among raft nodes before linearized reading' (duration: 133.373861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:36.123004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.1976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:03:36.123314Z","caller":"traceutil/trace.go:171","msg":"trace[281643975] linearizableReadLoop","detail":"{readStateIndex:27049; appliedIndex:27047; }","duration":"138.4643ms","start":"2026-09-05T07:03:35.98483Z","end":"2026-09-05T07:03:36.123295Z","steps":["trace[281643975] 'read index received' (duration: 1.805019ms)","trace[281643975] 'applied index is now lower than readState.Index' (duration: 136.658141ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:36.123338Z","caller":"traceutil/trace.go:171","msg":"trace[1915623617] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"151.733954ms","start":"2026-09-05T07:03:35.971568Z","end":"2026-09-05T07:03:36.123302Z","steps":["trace[1915623617] 'process raft request' (duration: 151.54562ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:36.123441Z","caller":"traceutil/trace.go:171","msg":"trace[693160713] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"154.891733ms","start":"2026-09-05T07:03:35.968519Z","end":"2026-09-05T07:03:36.123411Z","steps":["trace[693160713] 'process raft request' (duration: 18.16692ms)","trace[693160713] 'compare' (duration: 135.969536ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:36.123633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.725886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-05T07:03:36.12367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.482867ms","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-09-05T07:03:36.123682Z","caller":"traceutil/trace.go:171","msg":"trace[724667974] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26187; }","duration":"111.824268ms","start":"2026-09-05T07:03:36.011843Z","end":"2026-09-05T07:03:36.123667Z","steps":["trace[724667974] 'agreement among raft nodes before linearized reading' (duration: 111.691865ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:36.123705Z","caller":"traceutil/trace.go:171","msg":"trace[938963855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26187; }","duration":"136.541989ms","start":"2026-09-05T07:03:35.987153Z","end":"2026-09-05T07:03:36.123695Z","steps":["trace[938963855] 'agreement among raft nodes before linearized reading' (duration: 136.369255ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:36.123711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.874049ms","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-09-05T07:03:36.123766Z","caller":"traceutil/trace.go:171","msg":"trace[1387320551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26187; }","duration":"138.977011ms","start":"2026-09-05T07:03:35.984777Z","end":"2026-09-05T07:03:36.123754Z","steps":["trace[1387320551] 'agreement among raft nodes before linearized reading' (duration: 138.606713ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:36.298904Z","caller":"traceutil/trace.go:171","msg":"trace[1058740259] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"170.040995ms","start":"2026-09-05T07:03:36.128825Z","end":"2026-09-05T07:03:36.298866Z","steps":["trace[1058740259] 'process raft request' (duration: 128.058015ms)","trace[1058740259] 'compare' (duration: 41.708364ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:36.299256Z","caller":"traceutil/trace.go:171","msg":"trace[1196284983] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"170.106327ms","start":"2026-09-05T07:03:36.129125Z","end":"2026-09-05T07:03:36.299231Z","steps":["trace[1196284983] 'process raft request' (duration: 169.684628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:36.299438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.603897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:03:36.299015Z","caller":"traceutil/trace.go:171","msg":"trace[36998583] linearizableReadLoop","detail":"{readStateIndex:27052; appliedIndex:27050; }","duration":"113.176347ms","start":"2026-09-05T07:03:36.185812Z","end":"2026-09-05T07:03:36.298988Z","steps":["trace[36998583] 'read index received' (duration: 71.006083ms)","trace[36998583] 'applied index is now lower than readState.Index' (duration: 42.168094ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:36.299538Z","caller":"traceutil/trace.go:171","msg":"trace[1684126481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26189; }","duration":"113.75567ms","start":"2026-09-05T07:03:36.185757Z","end":"2026-09-05T07:03:36.299512Z","steps":["trace[1684126481] 'agreement among raft nodes before linearized reading' (duration: 113.562695ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:37.610294Z","caller":"traceutil/trace.go:171","msg":"trace[1775626374] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"179.168443ms","start":"2026-09-05T07:03:37.431099Z","end":"2026-09-05T07:03:37.610268Z","steps":["trace[1775626374] 'process raft request' (duration: 179.070851ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:37.610341Z","caller":"traceutil/trace.go:171","msg":"trace[277247747] transaction","detail":"{read_only:false; response_revision:26192; number_of_response:1; }","duration":"179.748104ms","start":"2026-09-05T07:03:37.430557Z","end":"2026-09-05T07:03:37.610305Z","steps":["trace[277247747] 'process raft request' (duration: 179.446378ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:37.61048Z","caller":"traceutil/trace.go:171","msg":"trace[1468891875] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"179.110981ms","start":"2026-09-05T07:03:37.43136Z","end":"2026-09-05T07:03:37.610471Z","steps":["trace[1468891875] 'process raft request' (duration: 178.870455ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:37.610488Z","caller":"traceutil/trace.go:171","msg":"trace[1584279267] linearizableReadLoop","detail":"{readStateIndex:27058; appliedIndex:27053; }","duration":"167.648005ms","start":"2026-09-05T07:03:37.442827Z","end":"2026-09-05T07:03:37.610475Z","steps":["trace[1584279267] 'read index received' (duration: 27.811756ms)","trace[1584279267] 'applied index is now lower than readState.Index' (duration: 139.835519ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:37.610551Z","caller":"traceutil/trace.go:171","msg":"trace[1715908154] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"178.60406ms","start":"2026-09-05T07:03:37.431934Z","end":"2026-09-05T07:03:37.610538Z","steps":["trace[1715908154] 'process raft request' (duration: 178.457826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:37.61065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.053301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-05T07:03:37.610688Z","caller":"traceutil/trace.go:171","msg":"trace[2036299210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26195; }","duration":"131.135802ms","start":"2026-09-05T07:03:37.47954Z","end":"2026-09-05T07:03:37.610676Z","steps":["trace[2036299210] 'agreement among raft nodes before linearized reading' (duration: 131.01415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:37.610649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.812389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-05T07:03:37.610758Z","caller":"traceutil/trace.go:171","msg":"trace[531860246] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26195; }","duration":"167.979762ms","start":"2026-09-05T07:03:37.442758Z","end":"2026-09-05T07:03:37.610738Z","steps":["trace[531860246] 'agreement among raft nodes before linearized reading' (duration: 167.790038ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:40.27254Z","caller":"traceutil/trace.go:171","msg":"trace[234210138] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"116.502629ms","start":"2026-09-05T07:03:40.156012Z","end":"2026-09-05T07:03:40.272515Z","steps":["trace[234210138] 'process raft request' (duration: 69.416249ms)","trace[234210138] 'compare' (duration: 46.918737ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:45.553105Z","caller":"traceutil/trace.go:171","msg":"trace[1966967198] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27111; }","duration":"224.519415ms","start":"2026-09-05T07:03:45.328562Z","end":"2026-09-05T07:03:45.553081Z","steps":["trace[1966967198] 'read index received' (duration: 224.345051ms)","trace[1966967198] 'applied index is now lower than readState.Index' (duration: 173.094µs)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:45.553349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.77095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-09-05T07:03:45.553388Z","caller":"traceutil/trace.go:171","msg":"trace[1479136712] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:26247; }","duration":"224.872672ms","start":"2026-09-05T07:03:45.328505Z","end":"2026-09-05T07:03:45.553377Z","steps":["trace[1479136712] 'agreement among raft nodes before linearized reading' (duration: 224.669557ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:03:50.164792Z","caller":"traceutil/trace.go:171","msg":"trace[181921726] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"125.111223ms","start":"2026-09-05T07:03:50.039639Z","end":"2026-09-05T07:03:50.16475Z","steps":["trace[181921726] 'process raft request' (duration: 124.821437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:03:51.939982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.889165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-05T07:03:51.940143Z","caller":"traceutil/trace.go:171","msg":"trace[2045070114] linearizableReadLoop","detail":"{readStateIndex:27163; appliedIndex:27162; }","duration":"158.379997ms","start":"2026-09-05T07:03:51.781721Z","end":"2026-09-05T07:03:51.940101Z","steps":["trace[2045070114] 'read index received' (duration: 56.179195ms)","trace[2045070114] 'applied index is now lower than readState.Index' (duration: 102.199392ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:03:51.940288Z","caller":"traceutil/trace.go:171","msg":"trace[1913948355] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"221.227184ms","start":"2026-09-05T07:03:51.719013Z","end":"2026-09-05T07:03:51.94024Z","steps":["trace[1913948355] 'process raft request' (duration: 118.956901ms)","trace[1913948355] 'compare' (duration: 101.356003ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:03:51.940366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.650183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-05T07:03:51.940406Z","caller":"traceutil/trace.go:171","msg":"trace[1123146033] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26297; }","duration":"158.787935ms","start":"2026-09-05T07:03:51.781603Z","end":"2026-09-05T07:03:51.94039Z","steps":["trace[1123146033] 'agreement among raft nodes before linearized reading' (duration: 158.607632ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:19.945709Z","caller":"traceutil/trace.go:171","msg":"trace[663275297] transaction","detail":"{read_only:false; response_revision:26651; number_of_response:1; }","duration":"114.69034ms","start":"2026-09-05T07:04:19.830989Z","end":"2026-09-05T07:04:19.94568Z","steps":["trace[663275297] 'process raft request' (duration: 114.477385ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.071125Z","caller":"traceutil/trace.go:171","msg":"trace[893293362] transaction","detail":"{read_only:false; response_revision:26652; number_of_response:1; }","duration":"237.507133ms","start":"2026-09-05T07:04:19.833593Z","end":"2026-09-05T07:04:20.0711Z","steps":["trace[893293362] 'process raft request' (duration: 237.274988ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.071569Z","caller":"traceutil/trace.go:171","msg":"trace[1908470390] transaction","detail":"{read_only:false; response_revision:26653; number_of_response:1; }","duration":"232.268541ms","start":"2026-09-05T07:04:19.839258Z","end":"2026-09-05T07:04:20.071526Z","steps":["trace[1908470390] 'process raft request' (duration: 231.790341ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.071717Z","caller":"traceutil/trace.go:171","msg":"trace[4389929] transaction","detail":"{read_only:false; response_revision:26654; number_of_response:1; }","duration":"230.033313ms","start":"2026-09-05T07:04:19.841673Z","end":"2026-09-05T07:04:20.071707Z","steps":["trace[4389929] 'process raft request' (duration: 229.800228ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.071743Z","caller":"traceutil/trace.go:171","msg":"trace[80412030] linearizableReadLoop","detail":"{readStateIndex:27529; appliedIndex:27526; }","duration":"230.935743ms","start":"2026-09-05T07:04:19.840779Z","end":"2026-09-05T07:04:20.071715Z","steps":["trace[80412030] 'read index received' (duration: 104.781938ms)","trace[80412030] 'applied index is now lower than readState.Index' (duration: 126.151805ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:04:20.071822Z","caller":"traceutil/trace.go:171","msg":"trace[255820265] transaction","detail":"{read_only:false; response_revision:26655; number_of_response:1; }","duration":"224.974414ms","start":"2026-09-05T07:04:19.846837Z","end":"2026-09-05T07:04:20.071812Z","steps":["trace[255820265] 'process raft request' (duration: 224.79841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.071958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.156885ms","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-09-05T07:04:20.072003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.239546ms","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-09-05T07:04:20.072044Z","caller":"traceutil/trace.go:171","msg":"trace[174206463] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26655; }","duration":"216.266217ms","start":"2026-09-05T07:04:19.855756Z","end":"2026-09-05T07:04:20.072022Z","steps":["trace[174206463] 'agreement among raft nodes before linearized reading' (duration: 216.123864ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.072056Z","caller":"traceutil/trace.go:171","msg":"trace[326350944] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26655; }","duration":"216.329829ms","start":"2026-09-05T07:04:19.855713Z","end":"2026-09-05T07:04:20.072043Z","steps":["trace[326350944] 'agreement among raft nodes before linearized reading' (duration: 216.226767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.072024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.199168ms","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-09-05T07:04:20.072156Z","caller":"traceutil/trace.go:171","msg":"trace[729489270] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26655; }","duration":"231.374972ms","start":"2026-09-05T07:04:19.840751Z","end":"2026-09-05T07:04:20.072126Z","steps":["trace[729489270] 'agreement among raft nodes before linearized reading' (duration: 231.065825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.07223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.939058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419294"} {"level":"info","ts":"2026-09-05T07:04:20.072293Z","caller":"traceutil/trace.go:171","msg":"trace[40055975] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26655; }","duration":"228.05608ms","start":"2026-09-05T07:04:19.844221Z","end":"2026-09-05T07:04:20.072277Z","steps":["trace[40055975] 'agreement among raft nodes before linearized reading' (duration: 227.656981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.07234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.106754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:04:20.072392Z","caller":"traceutil/trace.go:171","msg":"trace[1838388560] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:26655; }","duration":"216.197916ms","start":"2026-09-05T07:04:19.85618Z","end":"2026-09-05T07:04:20.072378Z","steps":["trace[1838388560] 'agreement among raft nodes before linearized reading' (duration: 216.101714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.072396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.966502ms","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-09-05T07:04:20.072499Z","caller":"traceutil/trace.go:171","msg":"trace[117131492] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26655; }","duration":"217.089934ms","start":"2026-09-05T07:04:19.855398Z","end":"2026-09-05T07:04:20.072488Z","steps":["trace[117131492] 'agreement among raft nodes before linearized reading' (duration: 216.90988ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.227937Z","caller":"traceutil/trace.go:171","msg":"trace[1963763627] linearizableReadLoop","detail":"{readStateIndex:27532; appliedIndex:27531; }","duration":"112.958613ms","start":"2026-09-05T07:04:20.114952Z","end":"2026-09-05T07:04:20.22791Z","steps":["trace[1963763627] 'read index received' (duration: 86.202969ms)","trace[1963763627] 'applied index is now lower than readState.Index' (duration: 26.754494ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:04:20.228043Z","caller":"traceutil/trace.go:171","msg":"trace[470378944] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"117.159973ms","start":"2026-09-05T07:04:20.110846Z","end":"2026-09-05T07:04:20.228006Z","steps":["trace[470378944] 'process raft request' (duration: 90.278866ms)","trace[470378944] 'compare' (duration: 26.650852ms)"],"step_count":2} {"level":"warn","ts":"2026-09-05T07:04:20.228227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.242559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-05T07:04:20.228273Z","caller":"traceutil/trace.go:171","msg":"trace[515734623] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26656; }","duration":"113.354271ms","start":"2026-09-05T07:04:20.114905Z","end":"2026-09-05T07:04:20.228259Z","steps":["trace[515734623] 'agreement among raft nodes before linearized reading' (duration: 113.151307ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.463891Z","caller":"traceutil/trace.go:171","msg":"trace[474338338] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"230.067474ms","start":"2026-09-05T07:04:20.233777Z","end":"2026-09-05T07:04:20.463844Z","steps":["trace[474338338] 'process raft request' (duration: 200.201813ms)","trace[474338338] 'compare' (duration: 29.709788ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:04:20.476477Z","caller":"traceutil/trace.go:171","msg":"trace[1387535566] linearizableReadLoop","detail":"{readStateIndex:27534; appliedIndex:27532; }","duration":"103.827796ms","start":"2026-09-05T07:04:20.372623Z","end":"2026-09-05T07:04:20.47645Z","steps":["trace[1387535566] 'read index received' (duration: 61.375026ms)","trace[1387535566] 'applied index is now lower than readState.Index' (duration: 42.45104ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:04:20.476564Z","caller":"traceutil/trace.go:171","msg":"trace[148860699] transaction","detail":"{read_only:false; response_revision:26658; number_of_response:1; }","duration":"129.16462ms","start":"2026-09-05T07:04:20.34737Z","end":"2026-09-05T07:04:20.476535Z","steps":["trace[148860699] 'process raft request' (duration: 128.877174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.476894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.260346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-05T07:04:20.476976Z","caller":"traceutil/trace.go:171","msg":"trace[614479326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26659; }","duration":"104.409249ms","start":"2026-09-05T07:04:20.372548Z","end":"2026-09-05T07:04:20.476957Z","steps":["trace[614479326] 'agreement among raft nodes before linearized reading' (duration: 104.165693ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.725767Z","caller":"traceutil/trace.go:171","msg":"trace[376327031] transaction","detail":"{read_only:false; response_revision:26660; number_of_response:1; }","duration":"284.609803ms","start":"2026-09-05T07:04:20.441128Z","end":"2026-09-05T07:04:20.725738Z","steps":["trace[376327031] 'process raft request' (duration: 275.434436ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.878438Z","caller":"traceutil/trace.go:171","msg":"trace[270019251] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"395.524882ms","start":"2026-09-05T07:04:20.482873Z","end":"2026-09-05T07:04:20.878397Z","steps":["trace[270019251] 'process raft request' (duration: 386.13409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.878609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:04:20.482843Z","time spent":"395.679005ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-05T07:04:20.888186Z","caller":"traceutil/trace.go:171","msg":"trace[1301044492] linearizableReadLoop","detail":"{readStateIndex:27537; appliedIndex:27535; }","duration":"402.147433ms","start":"2026-09-05T07:04:20.486005Z","end":"2026-09-05T07:04:20.888153Z","steps":["trace[1301044492] 'read index received' (duration: 230.601174ms)","trace[1301044492] 'applied index is now lower than readState.Index' (duration: 171.544749ms)"],"step_count":2} {"level":"info","ts":"2026-09-05T07:04:20.888403Z","caller":"traceutil/trace.go:171","msg":"trace[1961946121] transaction","detail":"{read_only:false; response_revision:26662; number_of_response:1; }","duration":"247.771472ms","start":"2026-09-05T07:04:20.640607Z","end":"2026-09-05T07:04:20.888379Z","steps":["trace[1961946121] 'process raft request' (duration: 246.912774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.888518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.993177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-05T07:04:20.888567Z","caller":"traceutil/trace.go:171","msg":"trace[1795485978] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26662; }","duration":"116.1297ms","start":"2026-09-05T07:04:20.772417Z","end":"2026-09-05T07:04:20.888547Z","steps":["trace[1795485978] 'agreement among raft nodes before linearized reading' (duration: 116.004737ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.888571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.690231ms","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-09-05T07:04:20.888571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.700485ms","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-09-05T07:04:20.888637Z","caller":"traceutil/trace.go:171","msg":"trace[1402975398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26662; }","duration":"309.827643ms","start":"2026-09-05T07:04:20.578792Z","end":"2026-09-05T07:04:20.88862Z","steps":["trace[1402975398] 'agreement among raft nodes before linearized reading' (duration: 309.616599ms)"],"step_count":1} {"level":"info","ts":"2026-09-05T07:04:20.88864Z","caller":"traceutil/trace.go:171","msg":"trace[1035755503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26662; }","duration":"270.846348ms","start":"2026-09-05T07:04:20.61778Z","end":"2026-09-05T07:04:20.888626Z","steps":["trace[1035755503] 'agreement among raft nodes before linearized reading' (duration: 270.645833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.888672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:04:20.578766Z","time spent":"309.899995ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-05T07:04:20.888716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.679362ms","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-09-05T07:04:20.888787Z","caller":"traceutil/trace.go:171","msg":"trace[85481724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26662; }","duration":"303.785195ms","start":"2026-09-05T07:04:20.584984Z","end":"2026-09-05T07:04:20.888769Z","steps":["trace[85481724] 'agreement among raft nodes before linearized reading' (duration: 303.546359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.888832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:04:20.584974Z","time spent":"303.846527ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-05T07:04:20.8888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.765606ms","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:9"} {"level":"info","ts":"2026-09-05T07:04:20.888929Z","caller":"traceutil/trace.go:171","msg":"trace[1628122942] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:26662; }","duration":"402.952181ms","start":"2026-09-05T07:04:20.485944Z","end":"2026-09-05T07:04:20.888896Z","steps":["trace[1628122942] 'agreement among raft nodes before linearized reading' (duration: 402.554522ms)"],"step_count":1} {"level":"warn","ts":"2026-09-05T07:04:20.888985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-05T07:04:20.485929Z","time spent":"403.043373ms","remote":"127.0.0.1:35278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":141,"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-09-05T07:07:01.880813Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25274} {"level":"info","ts":"2026-09-05T07:07:02.052242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25274,"took":"167.169175ms","hash":2696407665,"current-db-size-bytes":55582720,"current-db-size":"56 MB","current-db-size-in-use-bytes":39038976,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-09-05T07:07:02.052314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2696407665,"revision":25274,"compact-revision":21950} {"level":"info","ts":"2026-09-05T07:08:32.45073Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-05T07:08:32.456835Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-05T07:08:32.456947Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-05T07:12:01.893526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28269} {"level":"info","ts":"2026-09-05T07:12:02.044721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28269,"took":"146.224636ms","hash":1425669505,"current-db-size-bytes":55582720,"current-db-size":"56 MB","current-db-size-in-use-bytes":32677888,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-05T07:12:02.044813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1425669505,"revision":28269,"compact-revision":25274} {"level":"info","ts":"2026-09-05T07:17:01.90232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30629} {"level":"info","ts":"2026-09-05T07:17:02.040871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30629,"took":"134.704078ms","hash":312433404,"current-db-size-bytes":55582720,"current-db-size":"56 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-05T07:17:02.040939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":312433404,"revision":30629,"compact-revision":28269} {"level":"info","ts":"2026-09-05T07:22:01.912584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32887} {"level":"info","ts":"2026-09-05T07:22:02.070694Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32887,"took":"153.370702ms","hash":2102460152,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":30269440,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-05T07:22:02.070756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2102460152,"revision":32887,"compact-revision":30629} {"level":"info","ts":"2026-09-05T07:27:01.922299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35114} {"level":"info","ts":"2026-09-05T07:27:02.073244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35114,"took":"144.554884ms","hash":814469979,"current-db-size-bytes":56623104,"current-db-size":"57 MB","current-db-size-in-use-bytes":32231424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-05T07:27:02.073337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":814469979,"revision":35114,"compact-revision":32887} {"level":"info","ts":"2026-09-05T07:30:02.25066Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-05T07:30:02.257164Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-05T07:30:02.257342Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-05T07:32:01.930264Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37410} {"level":"info","ts":"2026-09-05T07:32:02.092239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37410,"took":"157.283727ms","hash":2289183988,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":31797248,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-05T07:32:02.092317Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2289183988,"revision":37410,"compact-revision":35114} {"level":"info","ts":"2026-09-05T07:37:01.938776Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39679} {"level":"info","ts":"2026-09-05T07:37:02.076584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39679,"took":"133.595867ms","hash":3591506699,"current-db-size-bytes":57524224,"current-db-size":"58 MB","current-db-size-in-use-bytes":30806016,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-05T07:37:02.076656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3591506699,"revision":39679,"compact-revision":37410}