{"level":"warn","ts":"2026-10-15T18:58:46.83663Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T18:58:46.836756Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.148: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.148:2380","--initial-cluster=instance=https://162.253.55.148:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.148:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.148:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T18:58:46.836847Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T18:58:46.836864Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-10-15T18:58:46.836893Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T18:58:46.83948Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"]} {"level":"info","ts":"2026-10-15T18:58:46.839617Z","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.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.148:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T18:58:46.845886Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.975712ms"} {"level":"info","ts":"2026-10-15T18:58:46.853052Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a6342599e225e01b","cluster-id":"8ad3da7aa894cc05"} {"level":"info","ts":"2026-10-15T18:58:46.853172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T18:58:46.853235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 0"} {"level":"info","ts":"2026-10-15T18:58:46.853251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a6342599e225e01b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T18:58:46.853263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 1"} {"level":"info","ts":"2026-10-15T18:58:46.853366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"warn","ts":"2026-10-15T18:58:46.860432Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T18:58:46.862586Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T18:58:46.864276Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-15T18:58:46.867707Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a6342599e225e01b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T18:58:46.867941Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a6342599e225e01b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T18:58:46.86808Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T18:58:46.86828Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T18:58:46.868309Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-15T18:58:46.869519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"info","ts":"2026-10-15T18:58:46.871304Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","added-peer-id":"a6342599e225e01b","added-peer-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-10-15T18:58:46.873961Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-15T18:58:46.87422Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-10-15T18:58:46.874313Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T18:58:46.874344Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-10-15T18:58:46.874264Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a6342599e225e01b","initial-advertise-peer-urls":["https://162.253.55.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T18:58:47.353908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T18:58:47.353991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T18:58:47.354032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgPreVoteResp from a6342599e225e01b at term 1"} {"level":"info","ts":"2026-10-15T18:58:47.354053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became candidate at term 2"} {"level":"info","ts":"2026-10-15T18:58:47.354064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgVoteResp from a6342599e225e01b at term 2"} {"level":"info","ts":"2026-10-15T18:58:47.354081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became leader at term 2"} {"level":"info","ts":"2026-10-15T18:58:47.3541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a6342599e225e01b elected leader a6342599e225e01b at term 2"} {"level":"info","ts":"2026-10-15T18:58:47.362769Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:47.364371Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:58:47.364416Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T18:58:47.364366Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a6342599e225e01b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.148:2379]}","request-path":"/0/members/a6342599e225e01b/attributes","cluster-id":"8ad3da7aa894cc05","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T18:58:47.364794Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T18:58:47.36484Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T18:58:47.365124Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:47.365414Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:47.365469Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T18:58:47.367901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.148:2379"} {"level":"info","ts":"2026-10-15T18:58:47.369045Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T18:59:39.943378Z","caller":"traceutil/trace.go:171","msg":"trace[671498623] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"223.888297ms","start":"2026-10-15T18:59:39.719459Z","end":"2026-10-15T18:59:39.943348Z","steps":["trace[671498623] 'process raft request' (duration: 223.266084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:40.378527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.833701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nxlcs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T18:59:40.378662Z","caller":"traceutil/trace.go:171","msg":"trace[1477565456] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nxlcs; range_end:; response_count:1; response_revision:610; }","duration":"382.029845ms","start":"2026-10-15T18:59:39.996609Z","end":"2026-10-15T18:59:40.378639Z","steps":["trace[1477565456] 'range keys from in-memory index tree' (duration: 381.584885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:40.378705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:59:39.996539Z","time spent":"382.154958ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-nxlcs\" "} {"level":"warn","ts":"2026-10-15T18:59:40.379373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.654392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:59:40.37952Z","caller":"traceutil/trace.go:171","msg":"trace[670252850] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"240.603956ms","start":"2026-10-15T18:59:40.138895Z","end":"2026-10-15T18:59:40.379499Z","steps":["trace[670252850] 'read index received' (duration: 133.101535ms)","trace[670252850] 'applied index is now lower than readState.Index' (duration: 107.500951ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:59:40.379578Z","caller":"traceutil/trace.go:171","msg":"trace[1703145624] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"348.038306ms","start":"2026-10-15T18:59:40.031474Z","end":"2026-10-15T18:59:40.379513Z","steps":["trace[1703145624] 'process raft request' (duration: 240.449633ms)","trace[1703145624] 'compare' (duration: 106.461978ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:59:40.379759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.852922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:59:40.379824Z","caller":"traceutil/trace.go:171","msg":"trace[1824062932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"240.938413ms","start":"2026-10-15T18:59:40.138866Z","end":"2026-10-15T18:59:40.379804Z","steps":["trace[1824062932] 'agreement among raft nodes before linearized reading' (duration: 240.78796ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:40.379835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:59:40.031453Z","time spent":"348.272191ms","remote":"127.0.0.1:59956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T18:59:41.1564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.534137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T18:59:41.157483Z","caller":"traceutil/trace.go:171","msg":"trace[788476382] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"753.949807ms","start":"2026-10-15T18:59:40.403463Z","end":"2026-10-15T18:59:41.157412Z","steps":["trace[788476382] 'process raft request' (duration: 427.481478ms)","trace[788476382] 'compare' (duration: 324.303511ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T18:59:41.157736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:59:40.403431Z","time spent":"754.230584ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T18:59:41.165575Z","caller":"traceutil/trace.go:171","msg":"trace[2039622012] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"201.712908ms","start":"2026-10-15T18:59:40.963771Z","end":"2026-10-15T18:59:41.165484Z","steps":["trace[2039622012] 'read index received' (duration: 182.894973ms)","trace[2039622012] 'applied index is now lower than readState.Index' (duration: 18.815195ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:59:41.1656Z","caller":"traceutil/trace.go:171","msg":"trace[1074942625] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"337.030483ms","start":"2026-10-15T18:59:40.828522Z","end":"2026-10-15T18:59:41.165552Z","steps":["trace[1074942625] 'process raft request' (duration: 331.717026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:41.165752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.947643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-15T18:59:41.165857Z","caller":"traceutil/trace.go:171","msg":"trace[1683545777] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:1; response_revision:615; }","duration":"202.115117ms","start":"2026-10-15T18:59:40.963719Z","end":"2026-10-15T18:59:41.165834Z","steps":["trace[1683545777] 'agreement among raft nodes before linearized reading' (duration: 201.903832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:41.166017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T18:59:40.828485Z","time spent":"337.199357ms","remote":"127.0.0.1:59644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T18:59:41.383119Z","caller":"traceutil/trace.go:171","msg":"trace[476406673] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"144.6423ms","start":"2026-10-15T18:59:41.238443Z","end":"2026-10-15T18:59:41.383085Z","steps":["trace[476406673] 'process raft request' (duration: 141.678185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:57.242249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.968115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:59:57.242333Z","caller":"traceutil/trace.go:171","msg":"trace[589291965] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:775; }","duration":"144.150289ms","start":"2026-10-15T18:59:57.098167Z","end":"2026-10-15T18:59:57.242317Z","steps":["trace[589291965] 'count revisions from in-memory index tree' (duration: 143.831522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:57.242404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.018696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k5xbd\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-15T18:59:57.245295Z","caller":"traceutil/trace.go:171","msg":"trace[387149418] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-k5xbd; range_end:; response_count:1; response_revision:775; }","duration":"146.92063ms","start":"2026-10-15T18:59:57.098311Z","end":"2026-10-15T18:59:57.245232Z","steps":["trace[387149418] 'range keys from in-memory index tree' (duration: 143.815331ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T18:59:57.425751Z","caller":"traceutil/trace.go:171","msg":"trace[560491461] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"135.842346ms","start":"2026-10-15T18:59:57.289891Z","end":"2026-10-15T18:59:57.425733Z","steps":["trace[560491461] 'read index received' (duration: 135.616901ms)","trace[560491461] 'applied index is now lower than readState.Index' (duration: 224.565µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T18:59:57.425835Z","caller":"traceutil/trace.go:171","msg":"trace[348620293] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"172.696508ms","start":"2026-10-15T18:59:57.253104Z","end":"2026-10-15T18:59:57.4258Z","steps":["trace[348620293] 'process raft request' (duration: 172.468963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:57.425902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.996719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T18:59:57.42596Z","caller":"traceutil/trace.go:171","msg":"trace[2105410611] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:776; }","duration":"136.100501ms","start":"2026-10-15T18:59:57.289849Z","end":"2026-10-15T18:59:57.425949Z","steps":["trace[2105410611] 'agreement among raft nodes before linearized reading' (duration: 135.988079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:57.425999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.019928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:59:57.426092Z","caller":"traceutil/trace.go:171","msg":"trace[289806428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:776; }","duration":"135.12453ms","start":"2026-10-15T18:59:57.29093Z","end":"2026-10-15T18:59:57.426055Z","steps":["trace[289806428] 'agreement among raft nodes before linearized reading' (duration: 134.989757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T18:59:57.426748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.742824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T18:59:57.426824Z","caller":"traceutil/trace.go:171","msg":"trace[1272058083] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:776; }","duration":"135.840715ms","start":"2026-10-15T18:59:57.290966Z","end":"2026-10-15T18:59:57.426807Z","steps":["trace[1272058083] 'agreement among raft nodes before linearized reading' (duration: 135.740183ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:00:52.664548Z","caller":"traceutil/trace.go:171","msg":"trace[1321946052] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"160.753225ms","start":"2026-10-15T19:00:52.503777Z","end":"2026-10-15T19:00:52.66453Z","steps":["trace[1321946052] 'process raft request' (duration: 160.653213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:17.246965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.842246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T19:01:17.247057Z","caller":"traceutil/trace.go:171","msg":"trace[1921300433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"197.987939ms","start":"2026-10-15T19:01:17.049049Z","end":"2026-10-15T19:01:17.247037Z","steps":["trace[1921300433] 'range keys from in-memory index tree' (duration: 197.674702ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:17.247137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.687651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T19:01:17.24732Z","caller":"traceutil/trace.go:171","msg":"trace[1748523585] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"107.944287ms","start":"2026-10-15T19:01:17.13935Z","end":"2026-10-15T19:01:17.247294Z","steps":["trace[1748523585] 'range keys from in-memory index tree' (duration: 107.467467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:17.247176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.060939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:17.247529Z","caller":"traceutil/trace.go:171","msg":"trace[551314685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1853; }","duration":"108.445968ms","start":"2026-10-15T19:01:17.139038Z","end":"2026-10-15T19:01:17.247484Z","steps":["trace[551314685] 'range keys from in-memory index tree' (duration: 107.975028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:17.545595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.490007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T19:01:17.54572Z","caller":"traceutil/trace.go:171","msg":"trace[105481893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1854; }","duration":"159.685121ms","start":"2026-10-15T19:01:17.386008Z","end":"2026-10-15T19:01:17.545693Z","steps":["trace[105481893] 'range keys from in-memory index tree' (duration: 159.097079ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:17.73219Z","caller":"traceutil/trace.go:171","msg":"trace[704255300] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"181.314109ms","start":"2026-10-15T19:01:17.550835Z","end":"2026-10-15T19:01:17.73215Z","steps":["trace[704255300] 'process raft request' (duration: 181.068304ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:17.878331Z","caller":"traceutil/trace.go:171","msg":"trace[1065969005] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"321.324237ms","start":"2026-10-15T19:01:17.55698Z","end":"2026-10-15T19:01:17.878304Z","steps":["trace[1065969005] 'process raft request' (duration: 321.03304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:17.878534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:17.556924Z","time spent":"321.5004ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:01:17.878559Z","caller":"traceutil/trace.go:171","msg":"trace[1140529351] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"209.284638ms","start":"2026-10-15T19:01:17.669224Z","end":"2026-10-15T19:01:17.878509Z","steps":["trace[1140529351] 'process raft request' (duration: 209.020832ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:18.217293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.663055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-10-15T19:01:18.217421Z","caller":"traceutil/trace.go:171","msg":"trace[1608516591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1857; }","duration":"104.840899ms","start":"2026-10-15T19:01:18.112557Z","end":"2026-10-15T19:01:18.217398Z","steps":["trace[1608516591] 'range keys from in-memory index tree' (duration: 104.43166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:18.217482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.759896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T19:01:18.21753Z","caller":"traceutil/trace.go:171","msg":"trace[2144750463] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1857; }","duration":"284.876679ms","start":"2026-10-15T19:01:17.932639Z","end":"2026-10-15T19:01:18.217516Z","steps":["trace[2144750463] 'range keys from in-memory index tree' (duration: 284.586523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:18.217577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.944527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T19:01:18.21769Z","caller":"traceutil/trace.go:171","msg":"trace[937534036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1857; }","duration":"203.098201ms","start":"2026-10-15T19:01:18.014559Z","end":"2026-10-15T19:01:18.217658Z","steps":["trace[937534036] 'range keys from in-memory index tree' (duration: 202.718512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:18.217577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.436034ms","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-10-15T19:01:18.217836Z","caller":"traceutil/trace.go:171","msg":"trace[1963849227] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"286.763821ms","start":"2026-10-15T19:01:17.931053Z","end":"2026-10-15T19:01:18.217817Z","steps":["trace[1963849227] 'range keys from in-memory index tree' (duration: 286.100206ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:32.713499Z","caller":"traceutil/trace.go:171","msg":"trace[1241280183] transaction","detail":"{read_only:false; response_revision:1983; number_of_response:1; }","duration":"194.705417ms","start":"2026-10-15T19:01:32.518768Z","end":"2026-10-15T19:01:32.713474Z","steps":["trace[1241280183] 'process raft request' (duration: 194.525623ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:32.717182Z","caller":"traceutil/trace.go:171","msg":"trace[1487279808] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"170.92079ms","start":"2026-10-15T19:01:32.546237Z","end":"2026-10-15T19:01:32.717158Z","steps":["trace[1487279808] 'process raft request' (duration: 170.754016ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:02.663826Z","caller":"traceutil/trace.go:171","msg":"trace[1340704228] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2237; }","duration":"113.118237ms","start":"2026-10-15T19:02:02.550679Z","end":"2026-10-15T19:02:02.663797Z","steps":["trace[1340704228] 'read index received' (duration: 19.006656ms)","trace[1340704228] 'applied index is now lower than readState.Index' (duration: 94.110681ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:02:02.66392Z","caller":"traceutil/trace.go:171","msg":"trace[400003569] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"117.159609ms","start":"2026-10-15T19:02:02.546724Z","end":"2026-10-15T19:02:02.663884Z","steps":["trace[400003569] 'process raft request' (duration: 71.064133ms)","trace[400003569] 'compare' (duration: 45.903492ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:02:02.664017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.339582ms","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:5"} {"level":"info","ts":"2026-10-15T19:02:02.664104Z","caller":"traceutil/trace.go:171","msg":"trace[1857486692] 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:2158; }","duration":"113.484624ms","start":"2026-10-15T19:02:02.550604Z","end":"2026-10-15T19:02:02.664089Z","steps":["trace[1857486692] 'agreement among raft nodes before linearized reading' (duration: 113.334521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:02.664249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.820258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T19:02:02.66431Z","caller":"traceutil/trace.go:171","msg":"trace[1691210331] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2158; }","duration":"100.933699ms","start":"2026-10-15T19:02:02.563359Z","end":"2026-10-15T19:02:02.664293Z","steps":["trace[1691210331] 'agreement among raft nodes before linearized reading' (duration: 100.615043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:08.409434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.336048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:02:08.40953Z","caller":"traceutil/trace.go:171","msg":"trace[792519440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"271.489831ms","start":"2026-10-15T19:02:08.138017Z","end":"2026-10-15T19:02:08.409507Z","steps":["trace[792519440] 'range keys from in-memory index tree' (duration: 271.162835ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:08.770982Z","caller":"traceutil/trace.go:171","msg":"trace[38108387] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"199.92592ms","start":"2026-10-15T19:02:08.571026Z","end":"2026-10-15T19:02:08.770952Z","steps":["trace[38108387] 'read index received' (duration: 199.755536ms)","trace[38108387] 'applied index is now lower than readState.Index' (duration: 169.174µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:02:08.771095Z","caller":"traceutil/trace.go:171","msg":"trace[2119738382] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"206.796119ms","start":"2026-10-15T19:02:08.564244Z","end":"2026-10-15T19:02:08.77104Z","steps":["trace[2119738382] 'process raft request' (duration: 206.439962ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:08.771257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.201115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-15T19:02:08.771314Z","caller":"traceutil/trace.go:171","msg":"trace[676242630] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2187; }","duration":"200.296077ms","start":"2026-10-15T19:02:08.570996Z","end":"2026-10-15T19:02:08.771292Z","steps":["trace[676242630] 'agreement among raft nodes before linearized reading' (duration: 200.101273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:08.995977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.149658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:02:08.996217Z","caller":"traceutil/trace.go:171","msg":"trace[1506887023] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"225.129361ms","start":"2026-10-15T19:02:08.771062Z","end":"2026-10-15T19:02:08.996192Z","steps":["trace[1506887023] 'read index received' (duration: 42.623275ms)","trace[1506887023] 'applied index is now lower than readState.Index' (duration: 182.504166ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:02:08.996326Z","caller":"traceutil/trace.go:171","msg":"trace[1549786703] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"311.919894ms","start":"2026-10-15T19:02:08.684396Z","end":"2026-10-15T19:02:08.996316Z","steps":["trace[1549786703] 'process raft request' (duration: 129.313716ms)","trace[1549786703] 'compare' (duration: 181.984205ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:02:08.996449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:08.684367Z","time spent":"311.982325ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:02:08.996568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.217027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-15T19:02:08.996628Z","caller":"traceutil/trace.go:171","msg":"trace[787335082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2189; }","duration":"404.310289ms","start":"2026-10-15T19:02:08.592295Z","end":"2026-10-15T19:02:08.996606Z","steps":["trace[787335082] 'agreement among raft nodes before linearized reading' (duration: 404.123206ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:08.99664Z","caller":"traceutil/trace.go:171","msg":"trace[909107501] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"219.477936ms","start":"2026-10-15T19:02:08.777154Z","end":"2026-10-15T19:02:08.996632Z","steps":["trace[909107501] 'process raft request' (duration: 218.950765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:08.996673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:08.592286Z","time spent":"404.378941ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T19:02:08.996768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.051865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-15T19:02:08.996786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.442737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T19:02:08.99686Z","caller":"traceutil/trace.go:171","msg":"trace[867698201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2189; }","duration":"407.211098ms","start":"2026-10-15T19:02:08.589618Z","end":"2026-10-15T19:02:08.996829Z","steps":["trace[867698201] 'agreement among raft nodes before linearized reading' (duration: 406.892872ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:08.996877Z","caller":"traceutil/trace.go:171","msg":"trace[1510428778] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"291.59251ms","start":"2026-10-15T19:02:08.705254Z","end":"2026-10-15T19:02:08.996847Z","steps":["trace[1510428778] 'agreement among raft nodes before linearized reading' (duration: 291.281714ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:08.996907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:08.589584Z","time spent":"407.31333ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-15T19:03:38.292556Z","caller":"traceutil/trace.go:171","msg":"trace[1844031787] transaction","detail":"{read_only:false; response_revision:2865; number_of_response:1; }","duration":"190.906422ms","start":"2026-10-15T19:03:38.101622Z","end":"2026-10-15T19:03:38.292528Z","steps":["trace[1844031787] 'process raft request' (duration: 190.751189ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:38.292961Z","caller":"traceutil/trace.go:171","msg":"trace[2064405086] linearizableReadLoop","detail":"{readStateIndex:2975; appliedIndex:2975; }","duration":"155.348787ms","start":"2026-10-15T19:03:38.13757Z","end":"2026-10-15T19:03:38.292919Z","steps":["trace[2064405086] 'read index received' (duration: 155.329866ms)","trace[2064405086] 'applied index is now lower than readState.Index' (duration: 15.581µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:03:38.293114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.526771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:03:38.293175Z","caller":"traceutil/trace.go:171","msg":"trace[696991358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2865; }","duration":"155.606503ms","start":"2026-10-15T19:03:38.137556Z","end":"2026-10-15T19:03:38.293162Z","steps":["trace[696991358] 'agreement among raft nodes before linearized reading' (duration: 155.49223ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:06:22.700242Z","caller":"traceutil/trace.go:171","msg":"trace[2018465389] transaction","detail":"{read_only:false; response_revision:3889; number_of_response:1; }","duration":"116.059453ms","start":"2026-10-15T19:06:22.584157Z","end":"2026-10-15T19:06:22.700216Z","steps":["trace[2018465389] 'process raft request' (duration: 45.19452ms)","trace[2018465389] 'compare' (duration: 70.74724ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:06:55.321735Z","caller":"traceutil/trace.go:171","msg":"trace[907544448] transaction","detail":"{read_only:false; response_revision:4103; number_of_response:1; }","duration":"166.678761ms","start":"2026-10-15T19:06:55.155033Z","end":"2026-10-15T19:06:55.321712Z","steps":["trace[907544448] 'process raft request' (duration: 166.520258ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:13.566173Z","caller":"traceutil/trace.go:171","msg":"trace[1767137756] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"128.126588ms","start":"2026-10-15T19:08:13.438019Z","end":"2026-10-15T19:08:13.566145Z","steps":["trace[1767137756] 'read index received' (duration: 46.06456ms)","trace[1767137756] 'applied index is now lower than readState.Index' (duration: 82.060778ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:13.566234Z","caller":"traceutil/trace.go:171","msg":"trace[570720792] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"138.340652ms","start":"2026-10-15T19:08:13.427846Z","end":"2026-10-15T19:08:13.566186Z","steps":["trace[570720792] 'process raft request' (duration: 56.346695ms)","trace[570720792] 'compare' (duration: 81.804183ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:13.566435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.377813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:08:13.566532Z","caller":"traceutil/trace.go:171","msg":"trace[260869308] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4954; }","duration":"128.534867ms","start":"2026-10-15T19:08:13.437973Z","end":"2026-10-15T19:08:13.566508Z","steps":["trace[260869308] 'agreement among raft nodes before linearized reading' (duration: 128.271131ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:18.382715Z","caller":"traceutil/trace.go:171","msg":"trace[141729210] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"174.74653ms","start":"2026-10-15T19:08:18.207921Z","end":"2026-10-15T19:08:18.382668Z","steps":["trace[141729210] 'process raft request' (duration: 137.079625ms)","trace[141729210] 'compare' (duration: 37.42736ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:20.945889Z","caller":"traceutil/trace.go:171","msg":"trace[1464407094] linearizableReadLoop","detail":"{readStateIndex:5352; appliedIndex:5351; }","duration":"110.989272ms","start":"2026-10-15T19:08:20.834873Z","end":"2026-10-15T19:08:20.945862Z","steps":["trace[1464407094] 'read index received' (duration: 110.842599ms)","trace[1464407094] 'applied index is now lower than readState.Index' (duration: 145.463µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:20.945942Z","caller":"traceutil/trace.go:171","msg":"trace[734564491] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"134.655861ms","start":"2026-10-15T19:08:20.811249Z","end":"2026-10-15T19:08:20.945905Z","steps":["trace[734564491] 'process raft request' (duration: 134.439716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:20.946705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.809251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531300"} {"level":"info","ts":"2026-10-15T19:08:20.94679Z","caller":"traceutil/trace.go:171","msg":"trace[1087609739] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:5156; }","duration":"111.929433ms","start":"2026-10-15T19:08:20.834842Z","end":"2026-10-15T19:08:20.946771Z","steps":["trace[1087609739] 'agreement among raft nodes before linearized reading' (duration: 111.163166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:21.25675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.415473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:08:21.256843Z","caller":"traceutil/trace.go:171","msg":"trace[528082270] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5356; }","duration":"120.244505ms","start":"2026-10-15T19:08:21.136583Z","end":"2026-10-15T19:08:21.256828Z","steps":["trace[528082270] 'read index received' (duration: 18.596977ms)","trace[528082270] 'applied index is now lower than readState.Index' (duration: 101.646478ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:21.25692Z","caller":"traceutil/trace.go:171","msg":"trace[257959374] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"121.714998ms","start":"2026-10-15T19:08:21.135181Z","end":"2026-10-15T19:08:21.256896Z","steps":["trace[257959374] 'process raft request' (duration: 20.019398ms)","trace[257959374] 'compare' (duration: 101.184848ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:21.25695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.370689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T19:08:21.256989Z","caller":"traceutil/trace.go:171","msg":"trace[1102585382] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:5160; }","duration":"120.44761ms","start":"2026-10-15T19:08:21.136529Z","end":"2026-10-15T19:08:21.256977Z","steps":["trace[1102585382] 'agreement among raft nodes before linearized reading' (duration: 120.368229ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:21.257021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.567799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-15T19:08:21.257065Z","caller":"traceutil/trace.go:171","msg":"trace[445912387] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:5160; }","duration":"118.61145ms","start":"2026-10-15T19:08:21.13844Z","end":"2026-10-15T19:08:21.257051Z","steps":["trace[445912387] 'agreement among raft nodes before linearized reading' (duration: 118.477837ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:21.397054Z","caller":"traceutil/trace.go:171","msg":"trace[227859672] linearizableReadLoop","detail":"{readStateIndex:5358; appliedIndex:5357; }","duration":"131.507302ms","start":"2026-10-15T19:08:21.265526Z","end":"2026-10-15T19:08:21.397034Z","steps":["trace[227859672] 'read index received' (duration: 101.358491ms)","trace[227859672] 'applied index is now lower than readState.Index' (duration: 30.147711ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:21.397111Z","caller":"traceutil/trace.go:171","msg":"trace[332733641] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"131.924391ms","start":"2026-10-15T19:08:21.265154Z","end":"2026-10-15T19:08:21.397078Z","steps":["trace[332733641] 'process raft request' (duration: 101.75925ms)","trace[332733641] 'compare' (duration: 30.025528ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:21.397227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.680336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:08:21.397264Z","caller":"traceutil/trace.go:171","msg":"trace[2040996161] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5161; }","duration":"131.727827ms","start":"2026-10-15T19:08:21.265522Z","end":"2026-10-15T19:08:21.39725Z","steps":["trace[2040996161] 'agreement among raft nodes before linearized reading' (duration: 131.656145ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:21.584205Z","caller":"traceutil/trace.go:171","msg":"trace[255275634] linearizableReadLoop","detail":"{readStateIndex:5364; appliedIndex:5363; }","duration":"117.349502ms","start":"2026-10-15T19:08:21.466829Z","end":"2026-10-15T19:08:21.584179Z","steps":["trace[255275634] 'read index received' (duration: 44.140078ms)","trace[255275634] 'applied index is now lower than readState.Index' (duration: 73.208504ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:21.584301Z","caller":"traceutil/trace.go:171","msg":"trace[709329216] transaction","detail":"{read_only:false; response_revision:5167; number_of_response:1; }","duration":"117.904294ms","start":"2026-10-15T19:08:21.466363Z","end":"2026-10-15T19:08:21.584268Z","steps":["trace[709329216] 'process raft request' (duration: 44.643748ms)","trace[709329216] 'compare' (duration: 73.046561ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:21.584398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.543026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-10-15T19:08:21.584452Z","caller":"traceutil/trace.go:171","msg":"trace[607595285] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:5167; }","duration":"117.625588ms","start":"2026-10-15T19:08:21.466799Z","end":"2026-10-15T19:08:21.584425Z","steps":["trace[607595285] 'agreement among raft nodes before linearized reading' (duration: 117.463925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:25.602209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.883299ms","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-10-15T19:08:25.602269Z","caller":"traceutil/trace.go:171","msg":"trace[253620325] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5292; }","duration":"172.994442ms","start":"2026-10-15T19:08:25.429261Z","end":"2026-10-15T19:08:25.602256Z","steps":["trace[253620325] 'range keys from in-memory index tree' (duration: 172.729126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:25.602435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.3696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db9b626a-b69f-45d3-ab0c-168b58d56162\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T19:08:25.602492Z","caller":"traceutil/trace.go:171","msg":"trace[1276101131] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db9b626a-b69f-45d3-ab0c-168b58d56162; range_end:; response_count:1; response_revision:5292; }","duration":"126.441912ms","start":"2026-10-15T19:08:25.476034Z","end":"2026-10-15T19:08:25.602476Z","steps":["trace[1276101131] 'range keys from in-memory index tree' (duration: 126.262008ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:25.897792Z","caller":"traceutil/trace.go:171","msg":"trace[915974981] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5491; }","duration":"233.778794ms","start":"2026-10-15T19:08:25.663993Z","end":"2026-10-15T19:08:25.897772Z","steps":["trace[915974981] 'read index received' (duration: 233.61447ms)","trace[915974981] 'applied index is now lower than readState.Index' (duration: 163.444µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:25.897904Z","caller":"traceutil/trace.go:171","msg":"trace[1658977166] transaction","detail":"{read_only:false; response_revision:5293; number_of_response:1; }","duration":"291.58015ms","start":"2026-10-15T19:08:25.606259Z","end":"2026-10-15T19:08:25.897839Z","steps":["trace[1658977166] 'process raft request' (duration: 291.362776ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:25.898067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.035209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db9b626a-b69f-45d3-ab0c-168b58d56162\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T19:08:25.898195Z","caller":"traceutil/trace.go:171","msg":"trace[2043886293] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db9b626a-b69f-45d3-ab0c-168b58d56162; range_end:; response_count:1; response_revision:5293; }","duration":"234.210443ms","start":"2026-10-15T19:08:25.663956Z","end":"2026-10-15T19:08:25.898166Z","steps":["trace[2043886293] 'agreement among raft nodes before linearized reading' (duration: 233.910227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:25.934559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.452988ms","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-10-15T19:08:25.934634Z","caller":"traceutil/trace.go:171","msg":"trace[179553746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5294; }","duration":"119.57528ms","start":"2026-10-15T19:08:25.815039Z","end":"2026-10-15T19:08:25.934614Z","steps":["trace[179553746] 'agreement among raft nodes before linearized reading' (duration: 119.358746ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:26.189418Z","caller":"traceutil/trace.go:171","msg":"trace[278896953] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"106.580556ms","start":"2026-10-15T19:08:26.082813Z","end":"2026-10-15T19:08:26.189394Z","steps":["trace[278896953] 'process raft request' (duration: 106.410252ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.910342Z","caller":"traceutil/trace.go:171","msg":"trace[1351651918] transaction","detail":"{read_only:false; response_revision:5558; number_of_response:1; }","duration":"238.380265ms","start":"2026-10-15T19:08:40.671933Z","end":"2026-10-15T19:08:40.910313Z","steps":["trace[1351651918] 'process raft request' (duration: 238.202431ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.945414Z","caller":"traceutil/trace.go:171","msg":"trace[1513809249] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"246.472733ms","start":"2026-10-15T19:08:40.698922Z","end":"2026-10-15T19:08:40.945395Z","steps":["trace[1513809249] 'process raft request' (duration: 246.35439ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.945563Z","caller":"traceutil/trace.go:171","msg":"trace[144313257] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"223.759494ms","start":"2026-10-15T19:08:40.721799Z","end":"2026-10-15T19:08:40.945558Z","steps":["trace[144313257] 'process raft request' (duration: 223.57294ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.945821Z","caller":"traceutil/trace.go:171","msg":"trace[1625739884] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"222.64997ms","start":"2026-10-15T19:08:40.723166Z","end":"2026-10-15T19:08:40.945816Z","steps":["trace[1625739884] 'process raft request' (duration: 222.370374ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.946004Z","caller":"traceutil/trace.go:171","msg":"trace[400132556] linearizableReadLoop","detail":"{readStateIndex:5769; appliedIndex:5765; }","duration":"143.326562ms","start":"2026-10-15T19:08:40.802671Z","end":"2026-10-15T19:08:40.945997Z","steps":["trace[400132556] 'read index received' (duration: 107.505777ms)","trace[400132556] 'applied index is now lower than readState.Index' (duration: 35.820285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:08:40.946125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.442104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-v6rp9\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-10-15T19:08:40.946145Z","caller":"traceutil/trace.go:171","msg":"trace[18860565] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-v6rp9; range_end:; response_count:1; response_revision:5562; }","duration":"143.509585ms","start":"2026-10-15T19:08:40.802629Z","end":"2026-10-15T19:08:40.946138Z","steps":["trace[18860565] 'agreement among raft nodes before linearized reading' (duration: 143.390992ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:40.94628Z","caller":"traceutil/trace.go:171","msg":"trace[274014193] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"117.591577ms","start":"2026-10-15T19:08:40.828683Z","end":"2026-10-15T19:08:40.946275Z","steps":["trace[274014193] 'process raft request' (duration: 117.063785ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:47.444453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2912} {"level":"info","ts":"2026-10-15T19:08:47.533798Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2912,"took":"87.445686ms","hash":1363951806,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:08:47.533872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1363951806,"revision":2912,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:08:48.642384Z","caller":"traceutil/trace.go:171","msg":"trace[1605242397] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"181.034808ms","start":"2026-10-15T19:08:48.461327Z","end":"2026-10-15T19:08:48.642362Z","steps":["trace[1605242397] 'process raft request' (duration: 180.844294ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:49.108559Z","caller":"traceutil/trace.go:171","msg":"trace[346836010] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"204.796568ms","start":"2026-10-15T19:08:48.903739Z","end":"2026-10-15T19:08:49.108536Z","steps":["trace[346836010] 'process raft request' (duration: 204.650425ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:49.196456Z","caller":"traceutil/trace.go:171","msg":"trace[1752907506] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"243.140738ms","start":"2026-10-15T19:08:48.953284Z","end":"2026-10-15T19:08:49.196425Z","steps":["trace[1752907506] 'process raft request' (duration: 208.038659ms)","trace[1752907506] 'compare' (duration: 34.926876ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:49.196848Z","caller":"traceutil/trace.go:171","msg":"trace[1055237472] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"221.149966ms","start":"2026-10-15T19:08:48.97568Z","end":"2026-10-15T19:08:49.19683Z","steps":["trace[1055237472] 'process raft request' (duration: 220.694406ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:49.197014Z","caller":"traceutil/trace.go:171","msg":"trace[1493322097] transaction","detail":"{read_only:false; response_revision:5727; number_of_response:1; }","duration":"221.206958ms","start":"2026-10-15T19:08:48.975788Z","end":"2026-10-15T19:08:49.196995Z","steps":["trace[1493322097] 'process raft request' (duration: 220.803029ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:57.056484Z","caller":"traceutil/trace.go:171","msg":"trace[1402791210] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"106.986625ms","start":"2026-10-15T19:08:56.949476Z","end":"2026-10-15T19:08:57.056462Z","steps":["trace[1402791210] 'process raft request' (duration: 106.459724ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:09:01.18495Z","caller":"traceutil/trace.go:171","msg":"trace[913328732] transaction","detail":"{read_only:false; response_revision:5884; number_of_response:1; }","duration":"117.688699ms","start":"2026-10-15T19:09:01.067229Z","end":"2026-10-15T19:09:01.184918Z","steps":["trace[913328732] 'process raft request' (duration: 93.605261ms)","trace[913328732] 'compare' (duration: 23.679359ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:09:01.341301Z","caller":"traceutil/trace.go:171","msg":"trace[669508797] transaction","detail":"{read_only:false; response_revision:5890; number_of_response:1; }","duration":"104.898809ms","start":"2026-10-15T19:09:01.236374Z","end":"2026-10-15T19:09:01.341273Z","steps":["trace[669508797] 'process raft request' (duration: 61.929207ms)","trace[669508797] 'compare' (duration: 42.769088ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:09:01.342063Z","caller":"traceutil/trace.go:171","msg":"trace[889992148] transaction","detail":"{read_only:false; response_revision:5891; number_of_response:1; }","duration":"101.138276ms","start":"2026-10-15T19:09:01.240376Z","end":"2026-10-15T19:09:01.341514Z","steps":["trace[889992148] 'process raft request' (duration: 100.84392ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:13:47.457574Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5682} {"level":"info","ts":"2026-10-15T19:13:47.559224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5682,"took":"99.662205ms","hash":1657023583,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":26185728,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T19:13:47.559292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1657023583,"revision":5682,"compact-revision":2912} {"level":"info","ts":"2026-10-15T19:14:47.064283Z","caller":"traceutil/trace.go:171","msg":"trace[814077983] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"359.609998ms","start":"2026-10-15T19:14:46.704643Z","end":"2026-10-15T19:14:47.064253Z","steps":["trace[814077983] 'process raft request' (duration: 341.12981ms)","trace[814077983] 'compare' (duration: 18.005217ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:14:47.06445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:14:46.704615Z","time spent":"359.752611ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:14:47.248067Z","caller":"traceutil/trace.go:171","msg":"trace[55107696] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"120.607435ms","start":"2026-10-15T19:14:47.127426Z","end":"2026-10-15T19:14:47.248034Z","steps":["trace[55107696] 'process raft request' (duration: 82.873375ms)","trace[55107696] 'compare' (duration: 37.418163ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:14:47.249857Z","caller":"traceutil/trace.go:171","msg":"trace[1305847613] linearizableReadLoop","detail":"{readStateIndex:8823; appliedIndex:8821; }","duration":"113.031009ms","start":"2026-10-15T19:14:47.136805Z","end":"2026-10-15T19:14:47.249836Z","steps":["trace[1305847613] 'read index received' (duration: 73.462398ms)","trace[1305847613] 'applied index is now lower than readState.Index' (duration: 39.567871ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:14:47.249956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.136671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:14:47.249926Z","caller":"traceutil/trace.go:171","msg":"trace[956582341] transaction","detail":"{read_only:false; response_revision:8523; number_of_response:1; }","duration":"118.52433ms","start":"2026-10-15T19:14:47.13137Z","end":"2026-10-15T19:14:47.249895Z","steps":["trace[956582341] 'process raft request' (duration: 118.363067ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:47.249976Z","caller":"traceutil/trace.go:171","msg":"trace[737467864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8523; }","duration":"113.204503ms","start":"2026-10-15T19:14:47.136766Z","end":"2026-10-15T19:14:47.24997Z","steps":["trace[737467864] 'agreement among raft nodes before linearized reading' (duration: 113.148962ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:59.003445Z","caller":"traceutil/trace.go:171","msg":"trace[171871852] linearizableReadLoop","detail":"{readStateIndex:8887; appliedIndex:8886; }","duration":"288.198166ms","start":"2026-10-15T19:14:58.715217Z","end":"2026-10-15T19:14:59.003415Z","steps":["trace[171871852] 'read index received' (duration: 288.027182ms)","trace[171871852] 'applied index is now lower than readState.Index' (duration: 169.624µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:14:59.003541Z","caller":"traceutil/trace.go:171","msg":"trace[229080923] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"415.030199ms","start":"2026-10-15T19:14:58.588485Z","end":"2026-10-15T19:14:59.003515Z","steps":["trace[229080923] 'process raft request' (duration: 414.718362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:59.003719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:14:58.588455Z","time spent":"415.141312ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:14:59.003719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.468392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:14:59.003829Z","caller":"traceutil/trace.go:171","msg":"trace[132980458] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8584; }","duration":"288.661396ms","start":"2026-10-15T19:14:58.715139Z","end":"2026-10-15T19:14:59.0038Z","steps":["trace[132980458] 'agreement among raft nodes before linearized reading' (duration: 288.416681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:59.009725Z","caller":"traceutil/trace.go:171","msg":"trace[486809206] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"253.141984ms","start":"2026-10-15T19:14:58.756556Z","end":"2026-10-15T19:14:59.009698Z","steps":["trace[486809206] 'process raft request' (duration: 252.93577ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:14:59.883959Z","caller":"traceutil/trace.go:171","msg":"trace[491774794] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"374.913666ms","start":"2026-10-15T19:14:59.509017Z","end":"2026-10-15T19:14:59.88393Z","steps":["trace[491774794] 'process raft request' (duration: 374.697261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:59.884207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:14:59.508985Z","time spent":"375.054588ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:14:59.895517Z","caller":"traceutil/trace.go:171","msg":"trace[1581008841] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"367.378719ms","start":"2026-10-15T19:14:59.528113Z","end":"2026-10-15T19:14:59.895492Z","steps":["trace[1581008841] 'process raft request' (duration: 367.231456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:14:59.89569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:14:59.528084Z","time spent":"367.517133ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:15:28.450706Z","caller":"traceutil/trace.go:171","msg":"trace[1772063572] linearizableReadLoop","detail":"{readStateIndex:9104; appliedIndex:9103; }","duration":"313.041823ms","start":"2026-10-15T19:15:28.137631Z","end":"2026-10-15T19:15:28.450673Z","steps":["trace[1772063572] 'read index received' (duration: 312.832188ms)","trace[1772063572] 'applied index is now lower than readState.Index' (duration: 208.665µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:15:28.450795Z","caller":"traceutil/trace.go:171","msg":"trace[1808983644] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"402.893802ms","start":"2026-10-15T19:15:28.047876Z","end":"2026-10-15T19:15:28.45077Z","steps":["trace[1808983644] 'process raft request' (duration: 402.640026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:28.450881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.236238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:15:28.450911Z","caller":"traceutil/trace.go:171","msg":"trace[324699466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8793; }","duration":"313.312159ms","start":"2026-10-15T19:15:28.137591Z","end":"2026-10-15T19:15:28.450903Z","steps":["trace[324699466] 'agreement among raft nodes before linearized reading' (duration: 313.237957ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:28.450935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:28.047853Z","time spent":"402.996444ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:15:28.45094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:28.13757Z","time spent":"313.363071ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:15:28.451021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.311297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:15:28.451149Z","caller":"traceutil/trace.go:171","msg":"trace[1006920926] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8793; }","duration":"218.518902ms","start":"2026-10-15T19:15:28.232599Z","end":"2026-10-15T19:15:28.451118Z","steps":["trace[1006920926] 'agreement among raft nodes before linearized reading' (duration: 218.298237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:28.451247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.002142ms","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-10-15T19:15:28.451366Z","caller":"traceutil/trace.go:171","msg":"trace[1893224501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8793; }","duration":"105.302939ms","start":"2026-10-15T19:15:28.346034Z","end":"2026-10-15T19:15:28.451337Z","steps":["trace[1893224501] 'agreement among raft nodes before linearized reading' (duration: 104.9353ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:28.664953Z","caller":"traceutil/trace.go:171","msg":"trace[2137108305] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"208.503272ms","start":"2026-10-15T19:15:28.456423Z","end":"2026-10-15T19:15:28.664926Z","steps":["trace[2137108305] 'process raft request' (duration: 208.302887ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:28.666021Z","caller":"traceutil/trace.go:171","msg":"trace[2013449427] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"209.242148ms","start":"2026-10-15T19:15:28.456746Z","end":"2026-10-15T19:15:28.665988Z","steps":["trace[2013449427] 'process raft request' (duration: 208.933841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:28.666369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.505093ms","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-10-15T19:15:28.666367Z","caller":"traceutil/trace.go:171","msg":"trace[607531314] linearizableReadLoop","detail":"{readStateIndex:9106; appliedIndex:9104; }","duration":"179.057193ms","start":"2026-10-15T19:15:28.486837Z","end":"2026-10-15T19:15:28.665894Z","steps":["trace[607531314] 'read index received' (duration: 177.866057ms)","trace[607531314] 'applied index is now lower than readState.Index' (duration: 1.189426ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:15:28.66647Z","caller":"traceutil/trace.go:171","msg":"trace[559495828] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8795; }","duration":"179.640386ms","start":"2026-10-15T19:15:28.486807Z","end":"2026-10-15T19:15:28.666447Z","steps":["trace[559495828] 'agreement among raft nodes before linearized reading' (duration: 179.305349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:28.666991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.90217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ldg79\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T19:15:28.667048Z","caller":"traceutil/trace.go:171","msg":"trace[2021250741] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ldg79; range_end:; response_count:1; response_revision:8795; }","duration":"135.004003ms","start":"2026-10-15T19:15:28.53203Z","end":"2026-10-15T19:15:28.667034Z","steps":["trace[2021250741] 'agreement among raft nodes before linearized reading' (duration: 134.569794ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:30.941835Z","caller":"traceutil/trace.go:171","msg":"trace[392423511] linearizableReadLoop","detail":"{readStateIndex:9116; appliedIndex:9114; }","duration":"266.325215ms","start":"2026-10-15T19:15:30.675486Z","end":"2026-10-15T19:15:30.941812Z","steps":["trace[392423511] 'read index received' (duration: 70.520463ms)","trace[392423511] 'applied index is now lower than readState.Index' (duration: 195.803212ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:15:30.941925Z","caller":"traceutil/trace.go:171","msg":"trace[1963249612] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"267.971761ms","start":"2026-10-15T19:15:30.673905Z","end":"2026-10-15T19:15:30.941877Z","steps":["trace[1963249612] 'process raft request' (duration: 267.753317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:30.942109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.57644ms","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-10-15T19:15:30.942162Z","caller":"traceutil/trace.go:171","msg":"trace[1276723287] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8805; }","duration":"266.676592ms","start":"2026-10-15T19:15:30.675463Z","end":"2026-10-15T19:15:30.942139Z","steps":["trace[1276723287] 'agreement among raft nodes before linearized reading' (duration: 266.493028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:30.942308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.613493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ldg79\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-10-15T19:15:30.942393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.577346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:15:30.942401Z","caller":"traceutil/trace.go:171","msg":"trace[751996290] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ldg79; range_end:; response_count:1; response_revision:8805; }","duration":"262.736405ms","start":"2026-10-15T19:15:30.679641Z","end":"2026-10-15T19:15:30.942377Z","steps":["trace[751996290] 'agreement among raft nodes before linearized reading' (duration: 262.451869ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:30.942438Z","caller":"traceutil/trace.go:171","msg":"trace[301003704] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:8805; }","duration":"180.674228ms","start":"2026-10-15T19:15:30.761751Z","end":"2026-10-15T19:15:30.942425Z","steps":["trace[301003704] 'agreement among raft nodes before linearized reading' (duration: 180.555896ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:37.39034Z","caller":"traceutil/trace.go:171","msg":"trace[1418445213] linearizableReadLoop","detail":"{readStateIndex:9152; appliedIndex:9151; }","duration":"391.06208ms","start":"2026-10-15T19:15:36.999245Z","end":"2026-10-15T19:15:37.390307Z","steps":["trace[1418445213] 'read index received' (duration: 390.919717ms)","trace[1418445213] 'applied index is now lower than readState.Index' (duration: 140.733µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:15:37.390397Z","caller":"traceutil/trace.go:171","msg":"trace[2013992601] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"396.300665ms","start":"2026-10-15T19:15:36.994045Z","end":"2026-10-15T19:15:37.390346Z","steps":["trace[2013992601] 'process raft request' (duration: 396.014909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:37.39057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:36.994016Z","time spent":"396.460899ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:15:37.390691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.423248ms","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-10-15T19:15:37.390791Z","caller":"traceutil/trace.go:171","msg":"trace[321461689] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8839; }","duration":"391.598682ms","start":"2026-10-15T19:15:36.999167Z","end":"2026-10-15T19:15:37.390766Z","steps":["trace[321461689] 'agreement among raft nodes before linearized reading' (duration: 391.310106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:37.390843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:36.999153Z","time spent":"391.679944ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:15:37.482565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.002481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ldg79\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T19:15:37.482667Z","caller":"traceutil/trace.go:171","msg":"trace[2032823910] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ldg79; range_end:; response_count:1; response_revision:8842; }","duration":"476.135684ms","start":"2026-10-15T19:15:37.006506Z","end":"2026-10-15T19:15:37.482642Z","steps":["trace[2032823910] 'agreement among raft nodes before linearized reading' (duration: 475.862838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:37.482721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:37.006496Z","time spent":"476.213695ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-ldg79\" "} {"level":"info","ts":"2026-10-15T19:15:37.482759Z","caller":"traceutil/trace.go:171","msg":"trace[1016139881] transaction","detail":"{read_only:false; response_revision:8841; number_of_response:1; }","duration":"245.901565ms","start":"2026-10-15T19:15:37.236809Z","end":"2026-10-15T19:15:37.48271Z","steps":["trace[1016139881] 'process raft request' (duration: 245.436105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:37.483004Z","caller":"traceutil/trace.go:171","msg":"trace[833891250] transaction","detail":"{read_only:false; response_revision:8842; number_of_response:1; }","duration":"178.965261ms","start":"2026-10-15T19:15:37.304021Z","end":"2026-10-15T19:15:37.482987Z","steps":["trace[833891250] 'process raft request' (duration: 178.285806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:37.48325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.409157ms","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-10-15T19:15:37.483302Z","caller":"traceutil/trace.go:171","msg":"trace[1842453343] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8842; }","duration":"350.506519ms","start":"2026-10-15T19:15:37.132783Z","end":"2026-10-15T19:15:37.483289Z","steps":["trace[1842453343] 'agreement among raft nodes before linearized reading' (duration: 350.342995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:37.483352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:37.132758Z","time spent":"350.57679ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T19:15:37.483423Z","caller":"traceutil/trace.go:171","msg":"trace[617879948] transaction","detail":"{read_only:false; response_revision:8840; number_of_response:1; }","duration":"246.62929ms","start":"2026-10-15T19:15:37.236777Z","end":"2026-10-15T19:15:37.483406Z","steps":["trace[617879948] 'process raft request' (duration: 245.288051ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:37.550409Z","caller":"traceutil/trace.go:171","msg":"trace[997981951] transaction","detail":"{read_only:false; response_revision:8843; number_of_response:1; }","duration":"155.256399ms","start":"2026-10-15T19:15:37.395111Z","end":"2026-10-15T19:15:37.550368Z","steps":["trace[997981951] 'process raft request' (duration: 97.776703ms)","trace[997981951] 'compare' (duration: 57.112658ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:15:42.647557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.471576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6dhpj\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-10-15T19:15:42.64763Z","caller":"traceutil/trace.go:171","msg":"trace[1284579889] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6dhpj; range_end:; response_count:1; response_revision:8898; }","duration":"107.584478ms","start":"2026-10-15T19:15:42.54003Z","end":"2026-10-15T19:15:42.647615Z","steps":["trace[1284579889] 'agreement among raft nodes before linearized reading' (duration: 107.392943ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:42.997047Z","caller":"traceutil/trace.go:171","msg":"trace[75270910] transaction","detail":"{read_only:false; response_revision:8904; number_of_response:1; }","duration":"225.317582ms","start":"2026-10-15T19:15:42.771704Z","end":"2026-10-15T19:15:42.997022Z","steps":["trace[75270910] 'process raft request' (duration: 225.189219ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:42.997304Z","caller":"traceutil/trace.go:171","msg":"trace[245456515] linearizableReadLoop","detail":"{readStateIndex:9219; appliedIndex:9219; }","duration":"223.026251ms","start":"2026-10-15T19:15:42.774238Z","end":"2026-10-15T19:15:42.997264Z","steps":["trace[245456515] 'read index received' (duration: 223.010971ms)","trace[245456515] 'applied index is now lower than readState.Index' (duration: 11.95µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:15:42.997638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.370819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-10-15T19:15:42.99768Z","caller":"traceutil/trace.go:171","msg":"trace[1163266598] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8904; }","duration":"223.451651ms","start":"2026-10-15T19:15:42.774215Z","end":"2026-10-15T19:15:42.997667Z","steps":["trace[1163266598] 'agreement among raft nodes before linearized reading' (duration: 223.229296ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:43.111256Z","caller":"traceutil/trace.go:171","msg":"trace[1048616982] linearizableReadLoop","detail":"{readStateIndex:9220; appliedIndex:9219; }","duration":"113.824817ms","start":"2026-10-15T19:15:42.997411Z","end":"2026-10-15T19:15:43.111235Z","steps":["trace[1048616982] 'read index received' (duration: 105.079284ms)","trace[1048616982] 'applied index is now lower than readState.Index' (duration: 8.744783ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:15:43.111485Z","caller":"traceutil/trace.go:171","msg":"trace[71377782] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"316.019249ms","start":"2026-10-15T19:15:42.795454Z","end":"2026-10-15T19:15:43.111473Z","steps":["trace[71377782] 'process raft request' (duration: 307.023151ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.111594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:42.795435Z","time spent":"316.08636ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:15:43.111767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.949544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:15:43.111809Z","caller":"traceutil/trace.go:171","msg":"trace[1562492133] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8905; }","duration":"275.030596ms","start":"2026-10-15T19:15:42.836767Z","end":"2026-10-15T19:15:43.111798Z","steps":["trace[1562492133] 'agreement among raft nodes before linearized reading' (duration: 274.968785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.113413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.950338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225408"} {"level":"info","ts":"2026-10-15T19:15:43.113462Z","caller":"traceutil/trace.go:171","msg":"trace[1049892523] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8905; }","duration":"302.043531ms","start":"2026-10-15T19:15:42.811405Z","end":"2026-10-15T19:15:43.113448Z","steps":["trace[1049892523] 'agreement among raft nodes before linearized reading' (duration: 300.525167ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.113492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:42.81138Z","time spent":"302.103152ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225432,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-10-15T19:15:43.113756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.026809ms","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-10-15T19:15:43.113793Z","caller":"traceutil/trace.go:171","msg":"trace[511274695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8905; }","duration":"306.104841ms","start":"2026-10-15T19:15:42.80768Z","end":"2026-10-15T19:15:43.113785Z","steps":["trace[511274695] 'agreement among raft nodes before linearized reading' (duration: 306.002329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.113818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:42.80766Z","time spent":"306.151852ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-15T19:15:43.379741Z","caller":"traceutil/trace.go:171","msg":"trace[1670896023] linearizableReadLoop","detail":"{readStateIndex:9222; appliedIndex:9220; }","duration":"268.386519ms","start":"2026-10-15T19:15:43.11132Z","end":"2026-10-15T19:15:43.379707Z","steps":["trace[1670896023] 'read index received' (duration: 195.055515ms)","trace[1670896023] 'applied index is now lower than readState.Index' (duration: 73.329524ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:15:43.379801Z","caller":"traceutil/trace.go:171","msg":"trace[1024783361] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"364.828713ms","start":"2026-10-15T19:15:43.014923Z","end":"2026-10-15T19:15:43.379751Z","steps":["trace[1024783361] 'process raft request' (duration: 291.455478ms)","trace[1024783361] 'compare' (duration: 72.877014ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:15:43.380006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:43.0149Z","time spent":"365.001798ms","remote":"127.0.0.1:51524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2553,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:15:43.379968Z","caller":"traceutil/trace.go:171","msg":"trace[319243638] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8906; }","duration":"359.398144ms","start":"2026-10-15T19:15:43.020548Z","end":"2026-10-15T19:15:43.379946Z","steps":["trace[319243638] 'process raft request' (duration: 359.085967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.380336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:43.020517Z","time spent":"359.69374ms","remote":"127.0.0.1:51524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":77,"response count":0,"response size":2603,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:15:43.380348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.844698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-10-15T19:15:43.380449Z","caller":"traceutil/trace.go:171","msg":"trace[817707063] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8906; }","duration":"376.986311ms","start":"2026-10-15T19:15:43.003445Z","end":"2026-10-15T19:15:43.380431Z","steps":["trace[817707063] 'agreement among raft nodes before linearized reading' (duration: 376.600763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.380538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:15:43.003426Z","time spent":"377.095283ms","remote":"127.0.0.1:59968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-10-15T19:15:43.382378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.899851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:15:43.382522Z","caller":"traceutil/trace.go:171","msg":"trace[1199081101] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8907; }","duration":"259.070195ms","start":"2026-10-15T19:15:43.123429Z","end":"2026-10-15T19:15:43.382499Z","steps":["trace[1199081101] 'agreement among raft nodes before linearized reading' (duration: 258.728297ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:43.38266Z","caller":"traceutil/trace.go:171","msg":"trace[2070632251] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"259.460703ms","start":"2026-10-15T19:15:43.123141Z","end":"2026-10-15T19:15:43.382602Z","steps":["trace[2070632251] 'process raft request' (duration: 258.87781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.383015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.779999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-15T19:15:43.383311Z","caller":"traceutil/trace.go:171","msg":"trace[1269112582] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8907; }","duration":"210.137157ms","start":"2026-10-15T19:15:43.173151Z","end":"2026-10-15T19:15:43.383288Z","steps":["trace[1269112582] 'agreement among raft nodes before linearized reading' (duration: 209.702888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:15:43.38321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.878941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:15:43.384542Z","caller":"traceutil/trace.go:171","msg":"trace[718085560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8907; }","duration":"245.25086ms","start":"2026-10-15T19:15:43.139264Z","end":"2026-10-15T19:15:43.384515Z","steps":["trace[718085560] 'agreement among raft nodes before linearized reading' (duration: 243.87453ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:15:55.161156Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246a.wal"} {"level":"info","ts":"2026-10-15T19:17:07.887113Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:17:07.923912Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T19:17:07.924085Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T19:18:47.465975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7974} {"level":"info","ts":"2026-10-15T19:18:47.554686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7974,"took":"86.18689ms","hash":4132760595,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":25763840,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-15T19:18:47.554993Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4132760595,"revision":7974,"compact-revision":5682} {"level":"info","ts":"2026-10-15T19:23:47.472683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10440} {"level":"info","ts":"2026-10-15T19:23:47.565254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10440,"took":"89.845951ms","hash":1730335687,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":27926528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-15T19:23:47.56542Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1730335687,"revision":10440,"compact-revision":7974} {"level":"info","ts":"2026-10-15T19:26:31.347861Z","caller":"traceutil/trace.go:171","msg":"trace[1435304672] linearizableReadLoop","detail":"{readStateIndex:14394; appliedIndex:14393; }","duration":"116.91938ms","start":"2026-10-15T19:26:31.230902Z","end":"2026-10-15T19:26:31.347821Z","steps":["trace[1435304672] 'read index received' (duration: 114.411515ms)","trace[1435304672] 'applied index is now lower than readState.Index' (duration: 2.506275ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:26:31.347896Z","caller":"traceutil/trace.go:171","msg":"trace[395324676] transaction","detail":"{read_only:false; response_revision:13916; number_of_response:1; }","duration":"162.71139ms","start":"2026-10-15T19:26:31.185129Z","end":"2026-10-15T19:26:31.34784Z","steps":["trace[395324676] 'process raft request' (duration: 160.307117ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:26:31.348075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.124415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:26:31.348289Z","caller":"traceutil/trace.go:171","msg":"trace[1916865183] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:13916; }","duration":"117.38183ms","start":"2026-10-15T19:26:31.230877Z","end":"2026-10-15T19:26:31.348259Z","steps":["trace[1916865183] 'agreement among raft nodes before linearized reading' (duration: 117.078033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:26:52.731095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.48689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:26:52.731431Z","caller":"traceutil/trace.go:171","msg":"trace[588954620] transaction","detail":"{read_only:false; response_revision:14030; number_of_response:1; }","duration":"280.427108ms","start":"2026-10-15T19:26:52.450963Z","end":"2026-10-15T19:26:52.73139Z","steps":["trace[588954620] 'process raft request' (duration: 115.54362ms)","trace[588954620] 'compare' (duration: 164.369046ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:26:52.732009Z","caller":"traceutil/trace.go:171","msg":"trace[78525047] transaction","detail":"{read_only:false; response_revision:14031; number_of_response:1; }","duration":"279.756663ms","start":"2026-10-15T19:26:52.4522Z","end":"2026-10-15T19:26:52.731956Z","steps":["trace[78525047] 'process raft request' (duration: 279.081178ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:26:52.732133Z","caller":"traceutil/trace.go:171","msg":"trace[1956313539] linearizableReadLoop","detail":"{readStateIndex:14514; appliedIndex:14511; }","duration":"263.465513ms","start":"2026-10-15T19:26:52.468633Z","end":"2026-10-15T19:26:52.732098Z","steps":["trace[1956313539] 'read index received' (duration: 97.709836ms)","trace[1956313539] 'applied index is now lower than readState.Index' (duration: 165.754277ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:26:52.732181Z","caller":"traceutil/trace.go:171","msg":"trace[1759727138] transaction","detail":"{read_only:false; response_revision:14032; number_of_response:1; }","duration":"279.480477ms","start":"2026-10-15T19:26:52.452652Z","end":"2026-10-15T19:26:52.732132Z","steps":["trace[1759727138] 'process raft request' (duration: 279.262662ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:26:52.732389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.75268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-15T19:26:52.732499Z","caller":"traceutil/trace.go:171","msg":"trace[305045559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14032; }","duration":"263.947995ms","start":"2026-10-15T19:26:52.468529Z","end":"2026-10-15T19:26:52.732477Z","steps":["trace[305045559] 'agreement among raft nodes before linearized reading' (duration: 263.661328ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:00.869958Z","caller":"traceutil/trace.go:171","msg":"trace[228821992] transaction","detail":"{read_only:false; response_revision:14072; number_of_response:1; }","duration":"109.799832ms","start":"2026-10-15T19:27:00.76012Z","end":"2026-10-15T19:27:00.869919Z","steps":["trace[228821992] 'process raft request' (duration: 109.536056ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:00.870007Z","caller":"traceutil/trace.go:171","msg":"trace[642493153] transaction","detail":"{read_only:false; response_revision:14073; number_of_response:1; }","duration":"109.846273ms","start":"2026-10-15T19:27:00.760128Z","end":"2026-10-15T19:27:00.869975Z","steps":["trace[642493153] 'process raft request' (duration: 109.728931ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:09.673234Z","caller":"traceutil/trace.go:171","msg":"trace[1864982183] linearizableReadLoop","detail":"{readStateIndex:14619; appliedIndex:14618; }","duration":"234.164767ms","start":"2026-10-15T19:27:09.439032Z","end":"2026-10-15T19:27:09.673196Z","steps":["trace[1864982183] 'read index received' (duration: 233.798229ms)","trace[1864982183] 'applied index is now lower than readState.Index' (duration: 365.388µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:27:09.673444Z","caller":"traceutil/trace.go:171","msg":"trace[610778242] transaction","detail":"{read_only:false; response_revision:14133; number_of_response:1; }","duration":"333.727243ms","start":"2026-10-15T19:27:09.339661Z","end":"2026-10-15T19:27:09.673388Z","steps":["trace[610778242] 'process raft request' (duration: 333.281963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:09.673634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:27:09.339637Z","time spent":"333.890117ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:27:09.673701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.763827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ldg79\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T19:27:09.673785Z","caller":"traceutil/trace.go:171","msg":"trace[1318850267] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ldg79; range_end:; response_count:1; response_revision:14133; }","duration":"155.89167ms","start":"2026-10-15T19:27:09.517844Z","end":"2026-10-15T19:27:09.673735Z","steps":["trace[1318850267] 'agreement among raft nodes before linearized reading' (duration: 155.660545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:09.673784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.095943ms","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-10-15T19:27:09.673896Z","caller":"traceutil/trace.go:171","msg":"trace[388395045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14133; }","duration":"107.264317ms","start":"2026-10-15T19:27:09.566604Z","end":"2026-10-15T19:27:09.673868Z","steps":["trace[388395045] 'agreement among raft nodes before linearized reading' (duration: 107.014321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:09.674183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.151259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-xc42z\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-10-15T19:27:09.674232Z","caller":"traceutil/trace.go:171","msg":"trace[2137095830] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-xc42z; range_end:; response_count:1; response_revision:14133; }","duration":"235.250951ms","start":"2026-10-15T19:27:09.43897Z","end":"2026-10-15T19:27:09.674221Z","steps":["trace[2137095830] 'agreement among raft nodes before linearized reading' (duration: 234.474264ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:09.808371Z","caller":"traceutil/trace.go:171","msg":"trace[333947574] transaction","detail":"{read_only:false; response_revision:14134; number_of_response:1; }","duration":"129.15523ms","start":"2026-10-15T19:27:09.679175Z","end":"2026-10-15T19:27:09.808331Z","steps":["trace[333947574] 'process raft request' (duration: 39.377539ms)","trace[333947574] 'compare' (duration: 89.639198ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:27:09.856898Z","caller":"traceutil/trace.go:171","msg":"trace[2011852929] transaction","detail":"{read_only:false; response_revision:14135; number_of_response:1; }","duration":"177.676311ms","start":"2026-10-15T19:27:09.679197Z","end":"2026-10-15T19:27:09.856874Z","steps":["trace[2011852929] 'process raft request' (duration: 177.502787ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:09.857202Z","caller":"traceutil/trace.go:171","msg":"trace[653452796] transaction","detail":"{read_only:false; response_revision:14136; number_of_response:1; }","duration":"175.784219ms","start":"2026-10-15T19:27:09.681372Z","end":"2026-10-15T19:27:09.857156Z","steps":["trace[653452796] 'process raft request' (duration: 175.457062ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:09.857305Z","caller":"traceutil/trace.go:171","msg":"trace[586438081] transaction","detail":"{read_only:false; response_revision:14137; number_of_response:1; }","duration":"167.753881ms","start":"2026-10-15T19:27:09.689538Z","end":"2026-10-15T19:27:09.857291Z","steps":["trace[586438081] 'process raft request' (duration: 167.456825ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:09.857243Z","caller":"traceutil/trace.go:171","msg":"trace[446145597] linearizableReadLoop","detail":"{readStateIndex:14621; appliedIndex:14619; }","duration":"175.896082ms","start":"2026-10-15T19:27:09.681328Z","end":"2026-10-15T19:27:09.857224Z","steps":["trace[446145597] 'read index received' (duration: 37.309914ms)","trace[446145597] 'applied index is now lower than readState.Index' (duration: 138.584928ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:27:09.857691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.176935ms","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-10-15T19:27:09.857764Z","caller":"traceutil/trace.go:171","msg":"trace[21923602] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14137; }","duration":"112.469901ms","start":"2026-10-15T19:27:09.745273Z","end":"2026-10-15T19:27:09.857743Z","steps":["trace[21923602] 'agreement among raft nodes before linearized reading' (duration: 112.243716ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:09.858275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.935435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:63 size:594904"} {"level":"info","ts":"2026-10-15T19:27:09.858347Z","caller":"traceutil/trace.go:171","msg":"trace[2091828035] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:63; response_revision:14137; }","duration":"177.032396ms","start":"2026-10-15T19:27:09.681298Z","end":"2026-10-15T19:27:09.85833Z","steps":["trace[2091828035] 'agreement among raft nodes before linearized reading' (duration: 175.979053ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:27:10.826145Z","caller":"traceutil/trace.go:171","msg":"trace[1948929368] transaction","detail":"{read_only:false; response_revision:14142; number_of_response:1; }","duration":"138.588188ms","start":"2026-10-15T19:27:10.68749Z","end":"2026-10-15T19:27:10.826079Z","steps":["trace[1948929368] 'process raft request' (duration: 138.152169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.361574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.980721ms","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-10-15T19:27:34.361666Z","caller":"traceutil/trace.go:171","msg":"trace[1428791422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14449; }","duration":"378.103553ms","start":"2026-10-15T19:27:33.983544Z","end":"2026-10-15T19:27:34.361647Z","steps":["trace[1428791422] 'range keys from in-memory index tree' (duration: 377.851888ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.361703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:27:33.983532Z","time spent":"378.160634ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-15T19:27:34.361722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.607097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ldg79\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-10-15T19:27:34.361874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.163769ms","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:5"} {"level":"info","ts":"2026-10-15T19:27:34.361926Z","caller":"traceutil/trace.go:171","msg":"trace[115284040] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:14449; }","duration":"104.23882ms","start":"2026-10-15T19:27:34.257668Z","end":"2026-10-15T19:27:34.361907Z","steps":["trace[115284040] 'count revisions from in-memory index tree' (duration: 104.097887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.36194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.032483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-10-15T19:27:34.361953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.04058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-15T19:27:34.361971Z","caller":"traceutil/trace.go:171","msg":"trace[1736982609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14449; }","duration":"384.102775ms","start":"2026-10-15T19:27:33.977858Z","end":"2026-10-15T19:27:34.361961Z","steps":["trace[1736982609] 'range keys from in-memory index tree' (duration: 383.907051ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:27:33.977838Z","time spent":"384.155967ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-15T19:27:34.361999Z","caller":"traceutil/trace.go:171","msg":"trace[480144279] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:14449; }","duration":"435.127362ms","start":"2026-10-15T19:27:33.926857Z","end":"2026-10-15T19:27:34.361985Z","steps":["trace[480144279] 'range keys from in-memory index tree' (duration: 434.901056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.361997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.048923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-936bfb09-66ea-4e6a-8478-a610b162442a\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-15T19:27:34.362092Z","caller":"traceutil/trace.go:171","msg":"trace[2106562640] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-936bfb09-66ea-4e6a-8478-a610b162442a; range_end:; response_count:1; response_revision:14449; }","duration":"156.194516ms","start":"2026-10-15T19:27:34.205871Z","end":"2026-10-15T19:27:34.362065Z","steps":["trace[2106562640] 'range keys from in-memory index tree' (duration: 155.863859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.362107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.361333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T19:27:34.362135Z","caller":"traceutil/trace.go:171","msg":"trace[443355732] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14449; }","duration":"414.430705ms","start":"2026-10-15T19:27:33.947695Z","end":"2026-10-15T19:27:34.362126Z","steps":["trace[443355732] 'range keys from in-memory index tree' (duration: 414.193479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.362157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:27:33.947672Z","time spent":"414.479256ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T19:27:34.362117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.193209ms","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:5"} {"level":"warn","ts":"2026-10-15T19:27:34.362033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:27:33.926832Z","time spent":"435.193074ms","remote":"127.0.0.1:59644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3708,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-10-15T19:27:34.362224Z","caller":"traceutil/trace.go:171","msg":"trace[1946607291] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:14449; }","duration":"191.342402ms","start":"2026-10-15T19:27:34.170855Z","end":"2026-10-15T19:27:34.362197Z","steps":["trace[1946607291] 'count revisions from in-memory index tree' (duration: 191.075646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.362281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.306555ms","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-10-15T19:27:34.362305Z","caller":"traceutil/trace.go:171","msg":"trace[1953415725] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14449; }","duration":"362.357656ms","start":"2026-10-15T19:27:33.99994Z","end":"2026-10-15T19:27:34.362298Z","steps":["trace[1953415725] 'range keys from in-memory index tree' (duration: 362.213743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.362345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:27:33.999925Z","time spent":"362.397187ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T19:27:34.36284Z","caller":"traceutil/trace.go:171","msg":"trace[179033433] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ldg79; range_end:; response_count:1; response_revision:14449; }","duration":"264.790663ms","start":"2026-10-15T19:27:34.098033Z","end":"2026-10-15T19:27:34.362824Z","steps":["trace[179033433] 'range keys from in-memory index tree' (duration: 263.444103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:27:34.562584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.0524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-10-15T19:27:34.562694Z","caller":"traceutil/trace.go:171","msg":"trace[1168037774] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:14453; }","duration":"101.256075ms","start":"2026-10-15T19:27:34.461409Z","end":"2026-10-15T19:27:34.562665Z","steps":["trace[1168037774] 'range keys from in-memory index tree' (duration: 100.906777ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:28:47.479953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12752} {"level":"info","ts":"2026-10-15T19:28:47.5644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12752,"took":"81.785206ms","hash":2508523140,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T19:28:47.56451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2508523140,"revision":12752,"compact-revision":10440} {"level":"info","ts":"2026-10-15T19:29:00.9394Z","caller":"traceutil/trace.go:171","msg":"trace[1620296977] transaction","detail":"{read_only:false; response_revision:15265; number_of_response:1; }","duration":"126.347328ms","start":"2026-10-15T19:29:00.813016Z","end":"2026-10-15T19:29:00.939363Z","steps":["trace[1620296977] 'process raft request' (duration: 126.120343ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.300128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.788177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:29:01.300231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.28237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:29:01.300283Z","caller":"traceutil/trace.go:171","msg":"trace[1341271804] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:15266; }","duration":"248.374852ms","start":"2026-10-15T19:29:01.051891Z","end":"2026-10-15T19:29:01.300266Z","steps":["trace[1341271804] 'count revisions from in-memory index tree' (duration: 248.175978ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.300345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.870068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-15T19:29:01.300491Z","caller":"traceutil/trace.go:171","msg":"trace[1524536850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15266; }","duration":"256.057171ms","start":"2026-10-15T19:29:01.044407Z","end":"2026-10-15T19:29:01.300464Z","steps":["trace[1524536850] 'range keys from in-memory index tree' (duration: 255.653963ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.300534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.578764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-10-15T19:29:01.300556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.272297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-10-15T19:29:01.30064Z","caller":"traceutil/trace.go:171","msg":"trace[679480482] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15266; }","duration":"160.709967ms","start":"2026-10-15T19:29:01.139913Z","end":"2026-10-15T19:29:01.300623Z","steps":["trace[679480482] 'range keys from in-memory index tree' (duration: 160.258777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.300715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.646454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-10-15T19:29:01.300759Z","caller":"traceutil/trace.go:171","msg":"trace[1266512473] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:1; response_revision:15266; }","duration":"246.711885ms","start":"2026-10-15T19:29:01.054035Z","end":"2026-10-15T19:29:01.300747Z","steps":["trace[1266512473] 'range keys from in-memory index tree' (duration: 246.537291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.300891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.454581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-10-15T19:29:01.30091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.641191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-15T19:29:01.300953Z","caller":"traceutil/trace.go:171","msg":"trace[2134318699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15266; }","duration":"264.703192ms","start":"2026-10-15T19:29:01.036235Z","end":"2026-10-15T19:29:01.300938Z","steps":["trace[2134318699] 'range keys from in-memory index tree' (duration: 264.516558ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:01.300995Z","caller":"traceutil/trace.go:171","msg":"trace[1008241799] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15266; }","duration":"150.577244ms","start":"2026-10-15T19:29:01.150356Z","end":"2026-10-15T19:29:01.300933Z","steps":["trace[1008241799] 'range keys from in-memory index tree' (duration: 150.306967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.301106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.213014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-10-15T19:29:01.301122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.168007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:29:01.301212Z","caller":"traceutil/trace.go:171","msg":"trace[207241874] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15266; }","duration":"161.28123ms","start":"2026-10-15T19:29:01.139916Z","end":"2026-10-15T19:29:01.301197Z","steps":["trace[207241874] 'range keys from in-memory index tree' (duration: 161.031375ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.300128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.704204ms","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:5"} {"level":"info","ts":"2026-10-15T19:29:01.300232Z","caller":"traceutil/trace.go:171","msg":"trace[1152540632] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15266; }","duration":"149.899829ms","start":"2026-10-15T19:29:01.150307Z","end":"2026-10-15T19:29:01.300206Z","steps":["trace[1152540632] 'range keys from in-memory index tree' (duration: 149.745965ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:01.301167Z","caller":"traceutil/trace.go:171","msg":"trace[794345591] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15266; }","duration":"241.294756ms","start":"2026-10-15T19:29:01.059856Z","end":"2026-10-15T19:29:01.301151Z","steps":["trace[794345591] 'range keys from in-memory index tree' (duration: 241.110092ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.300285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.31098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:29:01.301537Z","caller":"traceutil/trace.go:171","msg":"trace[1872951494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15266; }","duration":"162.581708ms","start":"2026-10-15T19:29:01.138941Z","end":"2026-10-15T19:29:01.301523Z","steps":["trace[1872951494] 'range keys from in-memory index tree' (duration: 161.214248ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:01.301552Z","caller":"traceutil/trace.go:171","msg":"trace[1260392626] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:15266; }","duration":"175.199067ms","start":"2026-10-15T19:29:01.126336Z","end":"2026-10-15T19:29:01.301536Z","steps":["trace[1260392626] 'count revisions from in-memory index tree' (duration: 173.585241ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:01.300655Z","caller":"traceutil/trace.go:171","msg":"trace[1706446796] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15266; }","duration":"150.395849ms","start":"2026-10-15T19:29:01.150244Z","end":"2026-10-15T19:29:01.30064Z","steps":["trace[1706446796] 'range keys from in-memory index tree' (duration: 149.873779ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:01.561787Z","caller":"traceutil/trace.go:171","msg":"trace[623855025] transaction","detail":"{read_only:false; response_revision:15268; number_of_response:1; }","duration":"254.282643ms","start":"2026-10-15T19:29:01.307482Z","end":"2026-10-15T19:29:01.561765Z","steps":["trace[623855025] 'process raft request' (duration: 254.15853ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:01.561898Z","caller":"traceutil/trace.go:171","msg":"trace[769352364] transaction","detail":"{read_only:false; response_revision:15267; number_of_response:1; }","duration":"254.578009ms","start":"2026-10-15T19:29:01.307253Z","end":"2026-10-15T19:29:01.561831Z","steps":["trace[769352364] 'process raft request' (duration: 221.009688ms)","trace[769352364] 'compare' (duration: 33.254544ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:29:01.562202Z","caller":"traceutil/trace.go:171","msg":"trace[777599642] linearizableReadLoop","detail":"{readStateIndex:15794; appliedIndex:15791; }","duration":"205.898294ms","start":"2026-10-15T19:29:01.356288Z","end":"2026-10-15T19:29:01.562186Z","steps":["trace[777599642] 'read index received' (duration: 171.995656ms)","trace[777599642] 'applied index is now lower than readState.Index' (duration: 33.900868ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:29:01.562421Z","caller":"traceutil/trace.go:171","msg":"trace[434991743] transaction","detail":"{read_only:false; response_revision:15269; number_of_response:1; }","duration":"251.327677ms","start":"2026-10-15T19:29:01.311082Z","end":"2026-10-15T19:29:01.56241Z","steps":["trace[434991743] 'process raft request' (duration: 250.647602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.562688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.739816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-15T19:29:01.562727Z","caller":"traceutil/trace.go:171","msg":"trace[883796118] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15273; }","duration":"246.817528ms","start":"2026-10-15T19:29:01.315901Z","end":"2026-10-15T19:29:01.562719Z","steps":["trace[883796118] 'agreement among raft nodes before linearized reading' (duration: 246.652214ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:01.562386Z","caller":"traceutil/trace.go:171","msg":"trace[1496401951] transaction","detail":"{read_only:false; response_revision:15270; number_of_response:1; }","duration":"202.480619ms","start":"2026-10-15T19:29:01.359886Z","end":"2026-10-15T19:29:01.562366Z","steps":["trace[1496401951] 'process raft request' (duration: 201.952597ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.562963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.397707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ldg79\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T19:29:01.563061Z","caller":"traceutil/trace.go:171","msg":"trace[1196074647] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ldg79; range_end:; response_count:1; response_revision:15273; }","duration":"197.53336ms","start":"2026-10-15T19:29:01.365512Z","end":"2026-10-15T19:29:01.563046Z","steps":["trace[1196074647] 'agreement among raft nodes before linearized reading' (duration: 197.328115ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.563139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.848407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:29:01.563178Z","caller":"traceutil/trace.go:171","msg":"trace[204022326] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15273; }","duration":"250.926838ms","start":"2026-10-15T19:29:01.312242Z","end":"2026-10-15T19:29:01.563169Z","steps":["trace[204022326] 'agreement among raft nodes before linearized reading' (duration: 250.875347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.563978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.854135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:29:01.564085Z","caller":"traceutil/trace.go:171","msg":"trace[668652518] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15273; }","duration":"254.959048ms","start":"2026-10-15T19:29:01.309064Z","end":"2026-10-15T19:29:01.564023Z","steps":["trace[668652518] 'agreement among raft nodes before linearized reading' (duration: 254.857465ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.565899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.736207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:29:01.565953Z","caller":"traceutil/trace.go:171","msg":"trace[1827965092] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15273; }","duration":"256.817319ms","start":"2026-10-15T19:29:01.309123Z","end":"2026-10-15T19:29:01.56594Z","steps":["trace[1827965092] 'agreement among raft nodes before linearized reading' (duration: 254.15382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:01.838651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.033653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-5tvc7\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-10-15T19:29:01.838728Z","caller":"traceutil/trace.go:171","msg":"trace[1213800363] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-5tvc7; range_end:; response_count:1; response_revision:15274; }","duration":"117.200117ms","start":"2026-10-15T19:29:01.721511Z","end":"2026-10-15T19:29:01.838711Z","steps":["trace[1213800363] 'range keys from in-memory index tree' (duration: 116.853059ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:51.147776Z","caller":"traceutil/trace.go:171","msg":"trace[480239292] transaction","detail":"{read_only:false; response_revision:16637; number_of_response:1; }","duration":"127.962964ms","start":"2026-10-15T19:31:51.019793Z","end":"2026-10-15T19:31:51.147756Z","steps":["trace[480239292] 'process raft request' (duration: 127.912033ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:51.147838Z","caller":"traceutil/trace.go:171","msg":"trace[1060835870] transaction","detail":"{read_only:false; response_revision:16636; number_of_response:1; }","duration":"136.005832ms","start":"2026-10-15T19:31:51.011814Z","end":"2026-10-15T19:31:51.14782Z","steps":["trace[1060835870] 'process raft request' (duration: 76.513579ms)","trace[1060835870] 'compare' (duration: 59.242797ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:31:51.14801Z","caller":"traceutil/trace.go:171","msg":"trace[1554749752] linearizableReadLoop","detail":"{readStateIndex:17204; appliedIndex:17203; }","duration":"136.046963ms","start":"2026-10-15T19:31:51.011953Z","end":"2026-10-15T19:31:51.148Z","steps":["trace[1554749752] 'read index received' (duration: 4.908318ms)","trace[1554749752] 'applied index is now lower than readState.Index' (duration: 131.137435ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:31:51.148163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.237237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-15T19:31:51.148198Z","caller":"traceutil/trace.go:171","msg":"trace[2082650621] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16637; }","duration":"136.290788ms","start":"2026-10-15T19:31:51.011896Z","end":"2026-10-15T19:31:51.148187Z","steps":["trace[2082650621] 'agreement among raft nodes before linearized reading' (duration: 136.172425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:51.148367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.331879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-pswvs\" ","response":"range_response_count:1 size:18546"} {"level":"info","ts":"2026-10-15T19:31:51.148399Z","caller":"traceutil/trace.go:171","msg":"trace[1036012962] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-pswvs; range_end:; response_count:1; response_revision:16637; }","duration":"136.363129ms","start":"2026-10-15T19:31:51.012027Z","end":"2026-10-15T19:31:51.14839Z","steps":["trace[1036012962] 'agreement among raft nodes before linearized reading' (duration: 136.273917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:51.148508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.947346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-15T19:31:51.148545Z","caller":"traceutil/trace.go:171","msg":"trace[657877164] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:16637; }","duration":"128.983227ms","start":"2026-10-15T19:31:51.019551Z","end":"2026-10-15T19:31:51.148534Z","steps":["trace[657877164] 'agreement among raft nodes before linearized reading' (duration: 128.874194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:51.148577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.481142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-10-15T19:31:51.148613Z","caller":"traceutil/trace.go:171","msg":"trace[802311378] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16637; }","duration":"136.547734ms","start":"2026-10-15T19:31:51.012056Z","end":"2026-10-15T19:31:51.148604Z","steps":["trace[802311378] 'agreement among raft nodes before linearized reading' (duration: 136.462162ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:51.148673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.763778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-neutron\" ","response":"range_response_count:1 size:905"} {"level":"info","ts":"2026-10-15T19:31:51.148748Z","caller":"traceutil/trace.go:171","msg":"trace[99812097] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-neutron; range_end:; response_count:1; response_revision:16637; }","duration":"136.861391ms","start":"2026-10-15T19:31:51.011871Z","end":"2026-10-15T19:31:51.148732Z","steps":["trace[99812097] 'agreement among raft nodes before linearized reading' (duration: 136.687147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:51.148948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.094635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:31:51.148981Z","caller":"traceutil/trace.go:171","msg":"trace[1167246644] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:16637; }","duration":"137.149407ms","start":"2026-10-15T19:31:51.011825Z","end":"2026-10-15T19:31:51.148975Z","steps":["trace[1167246644] 'agreement among raft nodes before linearized reading' (duration: 137.102986ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:33:47.488259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15201} {"level":"info","ts":"2026-10-15T19:33:47.597375Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15201,"took":"105.950698ms","hash":96732700,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":31936512,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:33:47.597453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":96732700,"revision":15201,"compact-revision":12752} {"level":"info","ts":"2026-10-15T19:34:36.345718Z","caller":"traceutil/trace.go:171","msg":"trace[1550115383] transaction","detail":"{read_only:false; response_revision:17774; number_of_response:1; }","duration":"201.561679ms","start":"2026-10-15T19:34:36.14413Z","end":"2026-10-15T19:34:36.345691Z","steps":["trace[1550115383] 'process raft request' (duration: 114.008266ms)","trace[1550115383] 'compare' (duration: 87.463121ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:34:41.768758Z","caller":"traceutil/trace.go:171","msg":"trace[242337150] linearizableReadLoop","detail":"{readStateIndex:18418; appliedIndex:18417; }","duration":"249.364604ms","start":"2026-10-15T19:34:41.519366Z","end":"2026-10-15T19:34:41.768731Z","steps":["trace[242337150] 'read index received' (duration: 232.957442ms)","trace[242337150] 'applied index is now lower than readState.Index' (duration: 16.405982ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:34:41.768973Z","caller":"traceutil/trace.go:171","msg":"trace[1337181562] transaction","detail":"{read_only:false; response_revision:17809; number_of_response:1; }","duration":"268.68251ms","start":"2026-10-15T19:34:41.500238Z","end":"2026-10-15T19:34:41.76892Z","steps":["trace[1337181562] 'process raft request' (duration: 252.141945ms)","trace[1337181562] 'compare' (duration: 16.258349ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:34:41.769408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.920876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T19:34:41.769479Z","caller":"traceutil/trace.go:171","msg":"trace[1222182123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17809; }","duration":"250.133981ms","start":"2026-10-15T19:34:41.519331Z","end":"2026-10-15T19:34:41.769465Z","steps":["trace[1222182123] 'agreement among raft nodes before linearized reading' (duration: 249.780103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:34:41.770712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.041891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T19:34:41.770764Z","caller":"traceutil/trace.go:171","msg":"trace[846326806] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17809; }","duration":"237.120783ms","start":"2026-10-15T19:34:41.53363Z","end":"2026-10-15T19:34:41.770751Z","steps":["trace[846326806] 'agreement among raft nodes before linearized reading' (duration: 236.995671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:34:41.77151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.829425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T19:34:41.771568Z","caller":"traceutil/trace.go:171","msg":"trace[1582275105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17809; }","duration":"230.980858ms","start":"2026-10-15T19:34:41.540572Z","end":"2026-10-15T19:34:41.771552Z","steps":["trace[1582275105] 'agreement among raft nodes before linearized reading' (duration: 230.773484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:34:41.771765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.174774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:34:41.771812Z","caller":"traceutil/trace.go:171","msg":"trace[1564342261] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17809; }","duration":"101.252865ms","start":"2026-10-15T19:34:41.670544Z","end":"2026-10-15T19:34:41.771796Z","steps":["trace[1564342261] 'agreement among raft nodes before linearized reading' (duration: 101.183503ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:34:42.139738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.853847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:34:42.140105Z","caller":"traceutil/trace.go:171","msg":"trace[331982023] transaction","detail":"{read_only:false; response_revision:17811; number_of_response:1; }","duration":"361.053179ms","start":"2026-10-15T19:34:41.779034Z","end":"2026-10-15T19:34:42.140087Z","steps":["trace[331982023] 'process raft request' (duration: 361.001848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:34:42.140173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:34:41.779016Z","time spent":"361.1261ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:34:42.140147Z","caller":"traceutil/trace.go:171","msg":"trace[176293953] transaction","detail":"{read_only:false; response_revision:17810; number_of_response:1; }","duration":"363.154006ms","start":"2026-10-15T19:34:41.776955Z","end":"2026-10-15T19:34:42.140109Z","steps":["trace[176293953] 'process raft request' (duration: 140.846969ms)","trace[176293953] 'compare' (duration: 221.744644ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:34:42.140282Z","caller":"traceutil/trace.go:171","msg":"trace[356291984] linearizableReadLoop","detail":"{readStateIndex:18419; appliedIndex:18418; }","duration":"361.492368ms","start":"2026-10-15T19:34:41.77878Z","end":"2026-10-15T19:34:42.140273Z","steps":["trace[356291984] 'read index received' (duration: 139.1142ms)","trace[356291984] 'applied index is now lower than readState.Index' (duration: 222.377068ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:34:42.140385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:34:41.776933Z","time spent":"363.333439ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:34:42.140474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.699574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T19:34:42.140531Z","caller":"traceutil/trace.go:171","msg":"trace[233524483] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17811; }","duration":"361.788085ms","start":"2026-10-15T19:34:41.778734Z","end":"2026-10-15T19:34:42.140522Z","steps":["trace[233524483] 'agreement among raft nodes before linearized reading' (duration: 361.618791ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:34:42.140564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:34:41.778714Z","time spent":"361.843137ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-15T19:34:42.374367Z","caller":"traceutil/trace.go:171","msg":"trace[1716491411] transaction","detail":"{read_only:false; response_revision:17812; number_of_response:1; }","duration":"208.894611ms","start":"2026-10-15T19:34:42.165444Z","end":"2026-10-15T19:34:42.374339Z","steps":["trace[1716491411] 'process raft request' (duration: 208.525362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:34:57.111761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.696368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:34:57.111922Z","caller":"traceutil/trace.go:171","msg":"trace[263539916] transaction","detail":"{read_only:false; response_revision:18016; number_of_response:1; }","duration":"140.667445ms","start":"2026-10-15T19:34:56.971226Z","end":"2026-10-15T19:34:57.111893Z","steps":["trace[263539916] 'process raft request' (duration: 22.728881ms)","trace[263539916] 'compare' (duration: 117.413012ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:34:57.11461Z","caller":"traceutil/trace.go:171","msg":"trace[1031600818] transaction","detail":"{read_only:false; response_revision:18017; number_of_response:1; }","duration":"140.327487ms","start":"2026-10-15T19:34:56.974259Z","end":"2026-10-15T19:34:57.114586Z","steps":["trace[1031600818] 'process raft request' (duration: 140.166154ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:34:57.114758Z","caller":"traceutil/trace.go:171","msg":"trace[866453994] transaction","detail":"{read_only:false; response_revision:18018; number_of_response:1; }","duration":"133.20388ms","start":"2026-10-15T19:34:56.981515Z","end":"2026-10-15T19:34:57.114719Z","steps":["trace[866453994] 'process raft request' (duration: 133.026456ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:35:38.624361Z","caller":"traceutil/trace.go:171","msg":"trace[1065319682] linearizableReadLoop","detail":"{readStateIndex:19109; appliedIndex:19108; }","duration":"115.9654ms","start":"2026-10-15T19:35:38.50836Z","end":"2026-10-15T19:35:38.624326Z","steps":["trace[1065319682] 'read index received' (duration: 115.843568ms)","trace[1065319682] 'applied index is now lower than readState.Index' (duration: 116.622µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:35:38.624558Z","caller":"traceutil/trace.go:171","msg":"trace[1493432515] transaction","detail":"{read_only:false; response_revision:18480; number_of_response:1; }","duration":"119.892896ms","start":"2026-10-15T19:35:38.504648Z","end":"2026-10-15T19:35:38.62454Z","steps":["trace[1493432515] 'process raft request' (duration: 119.463607ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:35:38.624914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.483821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-15T19:35:38.624975Z","caller":"traceutil/trace.go:171","msg":"trace[634690514] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:18480; }","duration":"116.635824ms","start":"2026-10-15T19:35:38.508323Z","end":"2026-10-15T19:35:38.624959Z","steps":["trace[634690514] 'agreement among raft nodes before linearized reading' (duration: 116.380299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:35:38.625327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.441359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-db-sync\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-10-15T19:35:38.625539Z","caller":"traceutil/trace.go:171","msg":"trace[1130626952] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-db-sync; range_end:; response_count:1; response_revision:18480; }","duration":"101.677714ms","start":"2026-10-15T19:35:38.523784Z","end":"2026-10-15T19:35:38.625462Z","steps":["trace[1130626952] 'agreement among raft nodes before linearized reading' (duration: 101.104262ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:37:43.080348Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:37:43.088898Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T19:37:43.089039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T19:38:47.498266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17422} {"level":"info","ts":"2026-10-15T19:38:47.605043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17422,"took":"102.311575ms","hash":2904791269,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":34529280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T19:38:47.605164Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2904791269,"revision":17422,"compact-revision":15201} {"level":"info","ts":"2026-10-15T19:40:03.581651Z","caller":"traceutil/trace.go:171","msg":"trace[754228116] linearizableReadLoop","detail":"{readStateIndex:21039; appliedIndex:21038; }","duration":"106.17934ms","start":"2026-10-15T19:40:03.475443Z","end":"2026-10-15T19:40:03.581623Z","steps":["trace[754228116] 'read index received' (duration: 105.966735ms)","trace[754228116] 'applied index is now lower than readState.Index' (duration: 211.745µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:40:03.5818Z","caller":"traceutil/trace.go:171","msg":"trace[2096707768] transaction","detail":"{read_only:false; response_revision:20345; number_of_response:1; }","duration":"287.603718ms","start":"2026-10-15T19:40:03.294156Z","end":"2026-10-15T19:40:03.58176Z","steps":["trace[2096707768] 'process raft request' (duration: 287.311972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:40:03.581878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.415825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-15T19:40:03.581927Z","caller":"traceutil/trace.go:171","msg":"trace[2102262929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20345; }","duration":"106.522308ms","start":"2026-10-15T19:40:03.475393Z","end":"2026-10-15T19:40:03.581915Z","steps":["trace[2102262929] 'agreement among raft nodes before linearized reading' (duration: 106.345824ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:03.772143Z","caller":"traceutil/trace.go:171","msg":"trace[1454538622] transaction","detail":"{read_only:false; response_revision:20346; number_of_response:1; }","duration":"184.826433ms","start":"2026-10-15T19:40:03.587285Z","end":"2026-10-15T19:40:03.772111Z","steps":["trace[1454538622] 'process raft request' (duration: 173.884891ms)","trace[1454538622] 'compare' (duration: 10.820579ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:40:04.229474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.994931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T19:40:04.229626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.235266ms","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-10-15T19:40:04.229739Z","caller":"traceutil/trace.go:171","msg":"trace[856462379] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20346; }","duration":"138.3952ms","start":"2026-10-15T19:40:04.091313Z","end":"2026-10-15T19:40:04.229708Z","steps":["trace[856462379] 'range keys from in-memory index tree' (duration: 138.015851ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.229669Z","caller":"traceutil/trace.go:171","msg":"trace[1094297547] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:20346; }","duration":"315.251117ms","start":"2026-10-15T19:40:03.914395Z","end":"2026-10-15T19:40:04.229646Z","steps":["trace[1094297547] 'count revisions from in-memory index tree' (duration: 314.824118ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:40:04.230085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.958191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-15T19:40:04.230053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.238957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-15T19:40:04.23012Z","caller":"traceutil/trace.go:171","msg":"trace[1666325910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20346; }","duration":"143.038462ms","start":"2026-10-15T19:40:04.087071Z","end":"2026-10-15T19:40:04.230109Z","steps":["trace[1666325910] 'range keys from in-memory index tree' (duration: 142.814338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:40:04.230109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:40:03.914378Z","time spent":"315.681236ms","remote":"127.0.0.1:60058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":18,"response size":32,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:40:04.230145Z","caller":"traceutil/trace.go:171","msg":"trace[1398760779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20346; }","duration":"158.372611ms","start":"2026-10-15T19:40:04.07175Z","end":"2026-10-15T19:40:04.230122Z","steps":["trace[1398760779] 'range keys from in-memory index tree' (duration: 158.078254ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.340219Z","caller":"traceutil/trace.go:171","msg":"trace[1688285222] transaction","detail":"{read_only:false; response_revision:20347; number_of_response:1; }","duration":"105.500695ms","start":"2026-10-15T19:40:04.234689Z","end":"2026-10-15T19:40:04.340189Z","steps":["trace[1688285222] 'process raft request' (duration: 105.340251ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.40448Z","caller":"traceutil/trace.go:171","msg":"trace[1672352525] transaction","detail":"{read_only:false; response_revision:20348; number_of_response:1; }","duration":"169.643268ms","start":"2026-10-15T19:40:04.234818Z","end":"2026-10-15T19:40:04.404462Z","steps":["trace[1672352525] 'process raft request' (duration: 169.455484ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.40494Z","caller":"traceutil/trace.go:171","msg":"trace[1599240589] linearizableReadLoop","detail":"{readStateIndex:21043; appliedIndex:21040; }","duration":"143.905691ms","start":"2026-10-15T19:40:04.260999Z","end":"2026-10-15T19:40:04.404905Z","steps":["trace[1599240589] 'read index received' (duration: 79.012061ms)","trace[1599240589] 'applied index is now lower than readState.Index' (duration: 64.891ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:40:04.40498Z","caller":"traceutil/trace.go:171","msg":"trace[968537892] transaction","detail":"{read_only:false; response_revision:20349; number_of_response:1; }","duration":"168.838661ms","start":"2026-10-15T19:40:04.236098Z","end":"2026-10-15T19:40:04.404937Z","steps":["trace[968537892] 'process raft request' (duration: 168.293639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:40:04.405205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.183158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:40:04.405267Z","caller":"traceutil/trace.go:171","msg":"trace[679812070] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:20349; }","duration":"144.30523ms","start":"2026-10-15T19:40:04.260943Z","end":"2026-10-15T19:40:04.405248Z","steps":["trace[679812070] 'agreement among raft nodes before linearized reading' (duration: 144.079155ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.669276Z","caller":"traceutil/trace.go:171","msg":"trace[725380302] transaction","detail":"{read_only:false; response_revision:20351; number_of_response:1; }","duration":"305.782077ms","start":"2026-10-15T19:40:04.363464Z","end":"2026-10-15T19:40:04.669246Z","steps":["trace[725380302] 'process raft request' (duration: 305.641984ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.669298Z","caller":"traceutil/trace.go:171","msg":"trace[838453789] transaction","detail":"{read_only:false; response_revision:20350; number_of_response:1; }","duration":"320.479362ms","start":"2026-10-15T19:40:04.348749Z","end":"2026-10-15T19:40:04.669228Z","steps":["trace[838453789] 'process raft request' (duration: 288.995588ms)","trace[838453789] 'compare' (duration: 31.050784ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:40:04.66946Z","caller":"traceutil/trace.go:171","msg":"trace[2045743533] linearizableReadLoop","detail":"{readStateIndex:21046; appliedIndex:21043; }","duration":"168.762568ms","start":"2026-10-15T19:40:04.500669Z","end":"2026-10-15T19:40:04.669431Z","steps":["trace[2045743533] 'read index received' (duration: 137.039269ms)","trace[2045743533] 'applied index is now lower than readState.Index' (duration: 31.720889ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:40:04.669491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:40:04.363447Z","time spent":"305.965111ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:40:04.66972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.042404ms","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-10-15T19:40:04.669764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:40:04.348723Z","time spent":"320.706276ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:40:04.669787Z","caller":"traceutil/trace.go:171","msg":"trace[830550333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20352; }","duration":"169.147877ms","start":"2026-10-15T19:40:04.500622Z","end":"2026-10-15T19:40:04.66977Z","steps":["trace[830550333] 'agreement among raft nodes before linearized reading' (duration: 168.915392ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.669957Z","caller":"traceutil/trace.go:171","msg":"trace[1206298534] transaction","detail":"{read_only:false; response_revision:20352; number_of_response:1; }","duration":"297.28124ms","start":"2026-10-15T19:40:04.372663Z","end":"2026-10-15T19:40:04.669944Z","steps":["trace[1206298534] 'process raft request' (duration: 296.526314ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:40:04.836714Z","caller":"traceutil/trace.go:171","msg":"trace[587663087] transaction","detail":"{read_only:false; response_revision:20353; number_of_response:1; }","duration":"162.932651ms","start":"2026-10-15T19:40:04.673757Z","end":"2026-10-15T19:40:04.83669Z","steps":["trace[587663087] 'process raft request' (duration: 160.697082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:40:05.062435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.221123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T19:40:05.062564Z","caller":"traceutil/trace.go:171","msg":"trace[510944475] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20353; }","duration":"122.371267ms","start":"2026-10-15T19:40:04.94014Z","end":"2026-10-15T19:40:05.062511Z","steps":["trace[510944475] 'range keys from in-memory index tree' (duration: 122.026748ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:43:47.509316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19884} {"level":"info","ts":"2026-10-15T19:43:47.637794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19884,"took":"124.112511ms","hash":449239401,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:43:47.637875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":449239401,"revision":19884,"compact-revision":17422} {"level":"warn","ts":"2026-10-15T19:47:07.489972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.206468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:47:07.490231Z","caller":"traceutil/trace.go:171","msg":"trace[2113245595] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:24687; }","duration":"117.374092ms","start":"2026-10-15T19:47:07.372679Z","end":"2026-10-15T19:47:07.490053Z","steps":["trace[2113245595] 'count revisions from in-memory index tree' (duration: 117.100916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:13.251894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.90933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:47:13.252083Z","caller":"traceutil/trace.go:171","msg":"trace[637800583] linearizableReadLoop","detail":"{readStateIndex:25544; appliedIndex:25542; }","duration":"364.640073ms","start":"2026-10-15T19:47:12.887424Z","end":"2026-10-15T19:47:13.252065Z","steps":["trace[637800583] 'read index received' (duration: 153.439316ms)","trace[637800583] 'applied index is now lower than readState.Index' (duration: 211.196807ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:47:13.252168Z","caller":"traceutil/trace.go:171","msg":"trace[1078215579] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"388.17898ms","start":"2026-10-15T19:47:12.863979Z","end":"2026-10-15T19:47:13.252158Z","steps":["trace[1078215579] 'process raft request' (duration: 388.029297ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:13.252176Z","caller":"traceutil/trace.go:171","msg":"trace[1551031776] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"388.62425ms","start":"2026-10-15T19:47:12.863498Z","end":"2026-10-15T19:47:13.252123Z","steps":["trace[1551031776] 'process raft request' (duration: 177.367912ms)","trace[1551031776] 'compare' (duration: 210.769578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:47:13.252521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:12.86347Z","time spent":"388.823104ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:47:13.252234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:12.863965Z","time spent":"388.232292ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:47:13.25272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.218975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:47:13.252779Z","caller":"traceutil/trace.go:171","msg":"trace[954566655] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24730; }","duration":"365.388719ms","start":"2026-10-15T19:47:12.887377Z","end":"2026-10-15T19:47:13.252765Z","steps":["trace[954566655] 'agreement among raft nodes before linearized reading' (duration: 364.882138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:13.252831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:12.887357Z","time spent":"365.46062ms","remote":"127.0.0.1:60040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":32,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:47:13.696598Z","caller":"traceutil/trace.go:171","msg":"trace[329377106] linearizableReadLoop","detail":"{readStateIndex:25545; appliedIndex:25544; }","duration":"444.447519ms","start":"2026-10-15T19:47:13.252118Z","end":"2026-10-15T19:47:13.696565Z","steps":["trace[329377106] 'read index received' (duration: 368.631101ms)","trace[329377106] 'applied index is now lower than readState.Index' (duration: 75.814438ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:47:13.696849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.209021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T19:47:13.696917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.010586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-15T19:47:13.697184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.110801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-10-15T19:47:13.69696Z","caller":"traceutil/trace.go:171","msg":"trace[1114602689] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24731; }","duration":"603.342154ms","start":"2026-10-15T19:47:13.093596Z","end":"2026-10-15T19:47:13.696938Z","steps":["trace[1114602689] 'agreement among raft nodes before linearized reading' (duration: 603.125689ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:13.697322Z","caller":"traceutil/trace.go:171","msg":"trace[2026855507] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24731; }","duration":"799.300236ms","start":"2026-10-15T19:47:12.897986Z","end":"2026-10-15T19:47:13.697287Z","steps":["trace[2026855507] 'agreement among raft nodes before linearized reading' (duration: 798.919567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:13.697399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:12.897969Z","time spent":"799.417539ms","remote":"127.0.0.1:59716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2107,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-10-15T19:47:13.697401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:13.093493Z","time spent":"603.880396ms","remote":"127.0.0.1:58270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:47:13.696921Z","caller":"traceutil/trace.go:171","msg":"trace[2061716889] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"637.338012ms","start":"2026-10-15T19:47:13.059514Z","end":"2026-10-15T19:47:13.696852Z","steps":["trace[2061716889] 'process raft request' (duration: 561.250298ms)","trace[2061716889] 'compare' (duration: 75.323927ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:47:13.697572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:13.059479Z","time spent":"637.989077ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:47:13.697806Z","caller":"traceutil/trace.go:171","msg":"trace[11520687] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:24731; }","duration":"762.981696ms","start":"2026-10-15T19:47:12.934797Z","end":"2026-10-15T19:47:13.697779Z","steps":["trace[11520687] 'agreement among raft nodes before linearized reading' (duration: 761.960614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:13.697883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:12.934784Z","time spent":"763.082309ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:47:13.774841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.650453ms","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-10-15T19:47:13.775032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.109507ms","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-10-15T19:47:13.775125Z","caller":"traceutil/trace.go:171","msg":"trace[155414015] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24732; }","duration":"497.24855ms","start":"2026-10-15T19:47:13.277855Z","end":"2026-10-15T19:47:13.775104Z","steps":["trace[155414015] 'agreement among raft nodes before linearized reading' (duration: 497.001244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:13.775169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:13.277833Z","time spent":"497.326492ms","remote":"127.0.0.1:59652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":141,"response size":33,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:47:13.775595Z","caller":"traceutil/trace.go:171","msg":"trace[1939103603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24732; }","duration":"479.796236ms","start":"2026-10-15T19:47:13.295148Z","end":"2026-10-15T19:47:13.774944Z","steps":["trace[1939103603] 'agreement among raft nodes before linearized reading' (duration: 479.491019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:13.775673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:47:13.295092Z","time spent":"480.564743ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T19:47:13.776104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.079378ms","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-10-15T19:47:13.776893Z","caller":"traceutil/trace.go:171","msg":"trace[1542193669] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24732; }","duration":"213.892016ms","start":"2026-10-15T19:47:13.562959Z","end":"2026-10-15T19:47:13.776851Z","steps":["trace[1542193669] 'agreement among raft nodes before linearized reading' (duration: 211.645557ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:13.988775Z","caller":"traceutil/trace.go:171","msg":"trace[1166479123] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"208.499507ms","start":"2026-10-15T19:47:13.780246Z","end":"2026-10-15T19:47:13.988746Z","steps":["trace[1166479123] 'process raft request' (duration: 158.122839ms)","trace[1166479123] 'compare' (duration: 50.156023ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:47:13.989071Z","caller":"traceutil/trace.go:171","msg":"trace[2077486233] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"144.177702ms","start":"2026-10-15T19:47:13.844866Z","end":"2026-10-15T19:47:13.989044Z","steps":["trace[2077486233] 'process raft request' (duration: 143.831695ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:13.989276Z","caller":"traceutil/trace.go:171","msg":"trace[774508443] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25546; }","duration":"131.751339ms","start":"2026-10-15T19:47:13.85749Z","end":"2026-10-15T19:47:13.989241Z","steps":["trace[774508443] 'read index received' (duration: 80.823549ms)","trace[774508443] 'applied index is now lower than readState.Index' (duration: 50.92571ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:47:13.989682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.013388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-15T19:47:13.989734Z","caller":"traceutil/trace.go:171","msg":"trace[844704040] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24735; }","duration":"109.10666ms","start":"2026-10-15T19:47:13.880614Z","end":"2026-10-15T19:47:13.98972Z","steps":["trace[844704040] 'agreement among raft nodes before linearized reading' (duration: 108.905136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:47:13.98985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.308901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-15T19:47:13.989983Z","caller":"traceutil/trace.go:171","msg":"trace[1550824620] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24735; }","duration":"132.530185ms","start":"2026-10-15T19:47:13.857423Z","end":"2026-10-15T19:47:13.989953Z","steps":["trace[1550824620] 'agreement among raft nodes before linearized reading' (duration: 132.056485ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:15.526973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.488571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:48:15.527088Z","caller":"traceutil/trace.go:171","msg":"trace[1281439607] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26119; }","duration":"108.876115ms","start":"2026-10-15T19:48:15.418194Z","end":"2026-10-15T19:48:15.52707Z","steps":["trace[1281439607] 'read index received' (duration: 167.324µs)","trace[1281439607] 'applied index is now lower than readState.Index' (duration: 108.707561ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:15.527193Z","caller":"traceutil/trace.go:171","msg":"trace[116569063] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"325.770677ms","start":"2026-10-15T19:48:15.201382Z","end":"2026-10-15T19:48:15.527153Z","steps":["trace[116569063] 'process raft request' (duration: 110.022451ms)","trace[116569063] 'compare' (duration: 215.060071ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:15.527255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.057139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:48:15.527285Z","caller":"traceutil/trace.go:171","msg":"trace[177286786] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25293; }","duration":"109.09077ms","start":"2026-10-15T19:48:15.418185Z","end":"2026-10-15T19:48:15.527276Z","steps":["trace[177286786] 'agreement among raft nodes before linearized reading' (duration: 108.973277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:15.527376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:15.201311Z","time spent":"325.970482ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:15.958034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.305493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-10-15T19:48:15.958042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.517404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-15T19:48:15.958134Z","caller":"traceutil/trace.go:171","msg":"trace[476590455] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25293; }","duration":"278.455996ms","start":"2026-10-15T19:48:15.679654Z","end":"2026-10-15T19:48:15.95811Z","steps":["trace[476590455] 'range keys from in-memory index tree' (duration: 278.139969ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:15.958144Z","caller":"traceutil/trace.go:171","msg":"trace[1778826321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25293; }","duration":"251.663127ms","start":"2026-10-15T19:48:15.706457Z","end":"2026-10-15T19:48:15.958121Z","steps":["trace[1778826321] 'range keys from in-memory index tree' (duration: 251.314589ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:16.144913Z","caller":"traceutil/trace.go:171","msg":"trace[1501273915] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"182.789521ms","start":"2026-10-15T19:48:15.962099Z","end":"2026-10-15T19:48:16.144889Z","steps":["trace[1501273915] 'process raft request' (duration: 182.658559ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:16.421508Z","caller":"traceutil/trace.go:171","msg":"trace[441417004] linearizableReadLoop","detail":"{readStateIndex:26122; appliedIndex:26120; }","duration":"453.347775ms","start":"2026-10-15T19:48:15.968127Z","end":"2026-10-15T19:48:16.421475Z","steps":["trace[441417004] 'read index received' (duration: 176.717558ms)","trace[441417004] 'applied index is now lower than readState.Index' (duration: 276.628867ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:16.421702Z","caller":"traceutil/trace.go:171","msg":"trace[1317088748] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"458.848626ms","start":"2026-10-15T19:48:15.962817Z","end":"2026-10-15T19:48:16.421665Z","steps":["trace[1317088748] 'process raft request' (duration: 365.433281ms)","trace[1317088748] 'compare' (duration: 93.053287ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:48:16.421732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.57531ms","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-10-15T19:48:16.421796Z","caller":"traceutil/trace.go:171","msg":"trace[446865400] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25295; }","duration":"453.680821ms","start":"2026-10-15T19:48:15.968097Z","end":"2026-10-15T19:48:16.421778Z","steps":["trace[446865400] 'agreement among raft nodes before linearized reading' (duration: 453.551548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:16.421827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:15.968082Z","time spent":"453.739353ms","remote":"127.0.0.1:57006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:48:16.42186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:15.96279Z","time spent":"458.991668ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:48:16.580221Z","caller":"traceutil/trace.go:171","msg":"trace[925280018] linearizableReadLoop","detail":"{readStateIndex:26124; appliedIndex:26122; }","duration":"158.567759ms","start":"2026-10-15T19:48:16.421627Z","end":"2026-10-15T19:48:16.580195Z","steps":["trace[925280018] 'read index received' (duration: 157.536256ms)","trace[925280018] 'applied index is now lower than readState.Index' (duration: 1.030413ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:48:16.580262Z","caller":"traceutil/trace.go:171","msg":"trace[1132090578] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"332.443134ms","start":"2026-10-15T19:48:16.247783Z","end":"2026-10-15T19:48:16.580226Z","steps":["trace[1132090578] 'process raft request' (duration: 332.369613ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:48:16.580337Z","caller":"traceutil/trace.go:171","msg":"trace[1961105243] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"332.872273ms","start":"2026-10-15T19:48:16.247442Z","end":"2026-10-15T19:48:16.580315Z","steps":["trace[1961105243] 'process raft request' (duration: 332.555976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:16.580391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:16.247766Z","time spent":"332.558666ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:16.580459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.381807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-15T19:48:16.580498Z","caller":"traceutil/trace.go:171","msg":"trace[1030816088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25297; }","duration":"204.463158ms","start":"2026-10-15T19:48:16.376025Z","end":"2026-10-15T19:48:16.580488Z","steps":["trace[1030816088] 'agreement among raft nodes before linearized reading' (duration: 204.288834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:16.580526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:16.247406Z","time spent":"332.986966ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:48:16.580562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.482807ms","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-10-15T19:48:16.580656Z","caller":"traceutil/trace.go:171","msg":"trace[1253048993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25297; }","duration":"248.63259ms","start":"2026-10-15T19:48:16.331998Z","end":"2026-10-15T19:48:16.58063Z","steps":["trace[1253048993] 'agreement among raft nodes before linearized reading' (duration: 248.340094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:16.580585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.105727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:48:16.580757Z","caller":"traceutil/trace.go:171","msg":"trace[1663370355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25297; }","duration":"442.313521ms","start":"2026-10-15T19:48:16.138425Z","end":"2026-10-15T19:48:16.580738Z","steps":["trace[1663370355] 'agreement among raft nodes before linearized reading' (duration: 442.030665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:48:16.580824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:48:16.138408Z","time spent":"442.403873ms","remote":"127.0.0.1:59544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T19:48:45.986973Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000671c.wal"} {"level":"info","ts":"2026-10-15T19:48:47.518272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22202} {"level":"info","ts":"2026-10-15T19:48:47.637772Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22202,"took":"115.668935ms","hash":1220659206,"current-db-size-bytes":56684544,"current-db-size":"57 MB","current-db-size-in-use-bytes":45494272,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-15T19:48:47.637847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1220659206,"revision":22202,"compact-revision":19884} {"level":"info","ts":"2026-10-15T19:52:06.443399Z","caller":"traceutil/trace.go:171","msg":"trace[2056233651] transaction","detail":"{read_only:false; response_revision:27635; number_of_response:1; }","duration":"117.747972ms","start":"2026-10-15T19:52:06.325626Z","end":"2026-10-15T19:52:06.443374Z","steps":["trace[2056233651] 'process raft request' (duration: 117.580549ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:53:47.528997Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25660} {"level":"info","ts":"2026-10-15T19:53:47.702836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25660,"took":"169.831918ms","hash":3559198471,"current-db-size-bytes":57528320,"current-db-size":"58 MB","current-db-size-in-use-bytes":34988032,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-15T19:53:47.702903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3559198471,"revision":25660,"compact-revision":22202} {"level":"info","ts":"2026-10-15T19:55:16.77497Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:55:16.782454Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T19:55:16.782613Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T19:58:47.537187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28386} {"level":"info","ts":"2026-10-15T19:58:47.691223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28386,"took":"149.790947ms","hash":822691823,"current-db-size-bytes":57528320,"current-db-size":"58 MB","current-db-size-in-use-bytes":30072832,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T19:58:47.691292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":822691823,"revision":28386,"compact-revision":25660} {"level":"info","ts":"2026-10-15T20:03:47.547415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30607} {"level":"info","ts":"2026-10-15T20:03:47.676502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30607,"took":"124.560296ms","hash":4168792276,"current-db-size-bytes":57528320,"current-db-size":"58 MB","current-db-size-in-use-bytes":31297536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:03:47.676585Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4168792276,"revision":30607,"compact-revision":28386} {"level":"info","ts":"2026-10-15T20:07:33.289703Z","caller":"traceutil/trace.go:171","msg":"trace[637458599] transaction","detail":"{read_only:false; response_revision:34708; number_of_response:1; }","duration":"127.59102ms","start":"2026-10-15T20:07:33.162086Z","end":"2026-10-15T20:07:33.289677Z","steps":["trace[637458599] 'process raft request' (duration: 117.537049ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T20:07:33.290018Z","caller":"traceutil/trace.go:171","msg":"trace[574268348] transaction","detail":"{read_only:false; response_revision:34709; number_of_response:1; }","duration":"127.776594ms","start":"2026-10-15T20:07:33.162212Z","end":"2026-10-15T20:07:33.289989Z","steps":["trace[574268348] 'process raft request' (duration: 127.372585ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T20:07:33.290095Z","caller":"traceutil/trace.go:171","msg":"trace[895047406] transaction","detail":"{read_only:false; response_revision:34710; number_of_response:1; }","duration":"120.74832ms","start":"2026-10-15T20:07:33.169318Z","end":"2026-10-15T20:07:33.290066Z","steps":["trace[895047406] 'process raft request' (duration: 120.580946ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T20:08:47.558136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32936} {"level":"info","ts":"2026-10-15T20:08:47.763793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32936,"took":"197.931809ms","hash":2692049667,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":31432704,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:08:47.763851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2692049667,"revision":32936,"compact-revision":30607} {"level":"info","ts":"2026-10-15T20:13:47.574764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35257} {"level":"info","ts":"2026-10-15T20:13:47.719968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35257,"took":"140.519046ms","hash":2907246856,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":32985088,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T20:13:47.720037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2907246856,"revision":35257,"compact-revision":32936} {"level":"info","ts":"2026-10-15T20:16:35.590497Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a6342599e225e01b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:16:35.596061Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T20:16:35.596187Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T20:18:47.584651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37487} {"level":"info","ts":"2026-10-15T20:18:47.714755Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37487,"took":"126.20766ms","hash":3079258177,"current-db-size-bytes":58572800,"current-db-size":"59 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:18:47.71482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3079258177,"revision":37487,"compact-revision":35257}