{"level":"warn","ts":"2026-10-16T14:27:10.674882Z","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-16T14:27:10.674953Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.175: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.175:2380","--initial-cluster=instance=https://162.253.55.175:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.175:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.175: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-16T14:27:10.675007Z","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-16T14:27:10.675014Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.175:2380"]} {"level":"info","ts":"2026-10-16T14:27:10.675029Z","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-16T14:27:10.675576Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.175:2379"]} {"level":"info","ts":"2026-10-16T14:27:10.675645Z","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.175:2380"],"listen-peer-urls":["https://162.253.55.175:2380"],"advertise-client-urls":["https://162.253.55.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.175: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-16T14:27:10.680273Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.416259ms"} {"level":"info","ts":"2026-10-16T14:27:10.699853Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5420a740f4813dc9","cluster-id":"5ecb181dc9675400"} {"level":"info","ts":"2026-10-16T14:27:10.699929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-10-16T14:27:10.699947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 became follower at term 0"} {"level":"info","ts":"2026-10-16T14:27:10.699969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5420a740f4813dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-16T14:27:10.699975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 became follower at term 1"} {"level":"info","ts":"2026-10-16T14:27:10.700005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 switched to configuration voters=(6062028995862543817)"} {"level":"warn","ts":"2026-10-16T14:27:10.708519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-16T14:27:10.710019Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-16T14:27:10.711449Z","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-16T14:27:10.713505Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5420a740f4813dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-16T14:27:10.714257Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5420a740f4813dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-16T14:27:10.714396Z","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-16T14:27:10.714435Z","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-16T14:27:10.714444Z","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-16T14:27:10.716279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 switched to configuration voters=(6062028995862543817)"} {"level":"info","ts":"2026-10-16T14:27:10.716383Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5ecb181dc9675400","local-member-id":"5420a740f4813dc9","added-peer-id":"5420a740f4813dc9","added-peer-peer-urls":["https://162.253.55.175:2380"]} {"level":"info","ts":"2026-10-16T14:27:10.719112Z","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-16T14:27:10.719175Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.175:2380"} {"level":"info","ts":"2026-10-16T14:27:10.719219Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.175:2380"} {"level":"info","ts":"2026-10-16T14:27:10.719509Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5420a740f4813dc9","initial-advertise-peer-urls":["https://162.253.55.175:2380"],"listen-peer-urls":["https://162.253.55.175:2380"],"advertise-client-urls":["https://162.253.55.175:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.175:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-16T14:27:10.719586Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-16T14:27:11.500442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-10-16T14:27:11.500552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-16T14:27:11.500608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 received MsgPreVoteResp from 5420a740f4813dc9 at term 1"} {"level":"info","ts":"2026-10-16T14:27:11.500632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 became candidate at term 2"} {"level":"info","ts":"2026-10-16T14:27:11.50065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 received MsgVoteResp from 5420a740f4813dc9 at term 2"} {"level":"info","ts":"2026-10-16T14:27:11.500672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5420a740f4813dc9 became leader at term 2"} {"level":"info","ts":"2026-10-16T14:27:11.500696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5420a740f4813dc9 elected leader 5420a740f4813dc9 at term 2"} {"level":"info","ts":"2026-10-16T14:27:11.50218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5420a740f4813dc9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.175:2379]}","request-path":"/0/members/5420a740f4813dc9/attributes","cluster-id":"5ecb181dc9675400","publish-timeout":"7s"} {"level":"info","ts":"2026-10-16T14:27:11.502219Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:11.502216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:27:11.502562Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-16T14:27:11.503067Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-16T14:27:11.503111Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-16T14:27:11.503403Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5ecb181dc9675400","local-member-id":"5420a740f4813dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:11.503522Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:11.503564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-16T14:27:11.506822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-16T14:27:11.507144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.175:2379"} {"level":"info","ts":"2026-10-16T14:27:27.101144Z","caller":"traceutil/trace.go:171","msg":"trace[1031671878] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"217.365347ms","start":"2026-10-16T14:27:26.883746Z","end":"2026-10-16T14:27:27.101111Z","steps":["trace[1031671878] 'process raft request' (duration: 217.163331ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:27:27.106007Z","caller":"traceutil/trace.go:171","msg":"trace[314803476] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"102.006283ms","start":"2026-10-16T14:27:27.003985Z","end":"2026-10-16T14:27:27.105991Z","steps":["trace[314803476] 'process raft request' (duration: 101.876509ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:27:37.798163Z","caller":"traceutil/trace.go:171","msg":"trace[113636504] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"123.908797ms","start":"2026-10-16T14:27:37.674224Z","end":"2026-10-16T14:27:37.798132Z","steps":["trace[113636504] 'process raft request' (duration: 123.755933ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:28:02.572168Z","caller":"traceutil/trace.go:171","msg":"trace[1754291391] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"158.89843ms","start":"2026-10-16T14:28:02.413241Z","end":"2026-10-16T14:28:02.572139Z","steps":["trace[1754291391] 'read index received' (duration: 158.693964ms)","trace[1754291391] 'applied index is now lower than readState.Index' (duration: 203.316µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:28:02.572363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.107916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-16T14:28:02.572458Z","caller":"traceutil/trace.go:171","msg":"trace[445020866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"159.27116ms","start":"2026-10-16T14:28:02.41317Z","end":"2026-10-16T14:28:02.572441Z","steps":["trace[445020866] 'agreement among raft nodes before linearized reading' (duration: 159.084015ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:28:02.572426Z","caller":"traceutil/trace.go:171","msg":"trace[1476432081] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"161.224123ms","start":"2026-10-16T14:28:02.411002Z","end":"2026-10-16T14:28:02.572226Z","steps":["trace[1476432081] 'process raft request' (duration: 160.958876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:03.092192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.970224ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:28:03.092323Z","caller":"traceutil/trace.go:171","msg":"trace[2120171057] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:653; }","duration":"261.108708ms","start":"2026-10-16T14:28:02.831191Z","end":"2026-10-16T14:28:03.0923Z","steps":["trace[2120171057] 'range keys from in-memory index tree' (duration: 260.954134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:03.093116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.443852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:28:03.093219Z","caller":"traceutil/trace.go:171","msg":"trace[1388635638] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"242.698668ms","start":"2026-10-16T14:28:02.850504Z","end":"2026-10-16T14:28:03.093203Z","steps":["trace[1388635638] 'read index received' (duration: 35.461µs)","trace[1388635638] 'applied index is now lower than readState.Index' (duration: 242.662097ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:28:03.093266Z","caller":"traceutil/trace.go:171","msg":"trace[1179836664] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"488.031597ms","start":"2026-10-16T14:28:02.60521Z","end":"2026-10-16T14:28:03.093242Z","steps":["trace[1179836664] 'process raft request' (duration: 106.70539ms)","trace[1179836664] 'compare' (duration: 380.323339ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:28:03.09332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.811542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:28:03.093358Z","caller":"traceutil/trace.go:171","msg":"trace[496993306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"242.883344ms","start":"2026-10-16T14:28:02.850463Z","end":"2026-10-16T14:28:03.093346Z","steps":["trace[496993306] 'agreement among raft nodes before linearized reading' (duration: 242.821312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:03.093392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:28:02.605194Z","time spent":"488.12815ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:28:03.408095Z","caller":"traceutil/trace.go:171","msg":"trace[1732990679] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"314.808948ms","start":"2026-10-16T14:28:03.093251Z","end":"2026-10-16T14:28:03.40806Z","steps":["trace[1732990679] 'read index received' (duration: 313.575695ms)","trace[1732990679] 'applied index is now lower than readState.Index' (duration: 1.232063ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:28:03.408149Z","caller":"traceutil/trace.go:171","msg":"trace[1771190107] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"416.408319ms","start":"2026-10-16T14:28:02.991701Z","end":"2026-10-16T14:28:03.40811Z","steps":["trace[1771190107] 'process raft request' (duration: 415.576727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:03.408328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:28:02.991681Z","time spent":"416.550293ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:28:03.408399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.89893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xl7xd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-16T14:28:03.408516Z","caller":"traceutil/trace.go:171","msg":"trace[2042385992] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xl7xd; range_end:; response_count:1; response_revision:655; }","duration":"443.034064ms","start":"2026-10-16T14:28:02.965442Z","end":"2026-10-16T14:28:03.408477Z","steps":["trace[2042385992] 'agreement among raft nodes before linearized reading' (duration: 442.741916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:03.408598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:28:02.965432Z","time spent":"443.154067ms","remote":"127.0.0.1:44472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-xl7xd\" "} {"level":"warn","ts":"2026-10-16T14:28:18.828304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.87595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-16T14:28:18.828401Z","caller":"traceutil/trace.go:171","msg":"trace[191337319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"127.027144ms","start":"2026-10-16T14:28:18.701352Z","end":"2026-10-16T14:28:18.828379Z","steps":["trace[191337319] 'range keys from in-memory index tree' (duration: 126.657884ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:28:18.944206Z","caller":"traceutil/trace.go:171","msg":"trace[1119643123] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"109.905728ms","start":"2026-10-16T14:28:18.834269Z","end":"2026-10-16T14:28:18.944175Z","steps":["trace[1119643123] 'process raft request' (duration: 109.693812ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:28:47.454396Z","caller":"traceutil/trace.go:171","msg":"trace[1051629409] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"135.238526ms","start":"2026-10-16T14:28:47.319135Z","end":"2026-10-16T14:28:47.454374Z","steps":["trace[1051629409] 'read index received' (duration: 135.081792ms)","trace[1051629409] 'applied index is now lower than readState.Index' (duration: 155.974µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:28:47.454484Z","caller":"traceutil/trace.go:171","msg":"trace[359208650] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"141.200088ms","start":"2026-10-16T14:28:47.313253Z","end":"2026-10-16T14:28:47.454453Z","steps":["trace[359208650] 'process raft request' (duration: 141.009983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:47.454565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.425602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-16T14:28:47.454615Z","caller":"traceutil/trace.go:171","msg":"trace[1400875360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1213; }","duration":"135.530944ms","start":"2026-10-16T14:28:47.319074Z","end":"2026-10-16T14:28:47.454605Z","steps":["trace[1400875360] 'agreement among raft nodes before linearized reading' (duration: 135.391021ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:28:47.604621Z","caller":"traceutil/trace.go:171","msg":"trace[934141183] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"101.650834ms","start":"2026-10-16T14:28:47.502925Z","end":"2026-10-16T14:28:47.604576Z","steps":["trace[934141183] 'read index received' (duration: 101.434378ms)","trace[934141183] 'applied index is now lower than readState.Index' (duration: 215.396µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:28:47.604683Z","caller":"traceutil/trace.go:171","msg":"trace[290064104] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"146.38938ms","start":"2026-10-16T14:28:47.458262Z","end":"2026-10-16T14:28:47.604652Z","steps":["trace[290064104] 'process raft request' (duration: 146.188254ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:28:47.604778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.816808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:28:47.604841Z","caller":"traceutil/trace.go:171","msg":"trace[842586753] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1214; }","duration":"101.90428ms","start":"2026-10-16T14:28:47.502921Z","end":"2026-10-16T14:28:47.604826Z","steps":["trace[842586753] 'agreement among raft nodes before linearized reading' (duration: 101.777097ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:29:35.512694Z","caller":"traceutil/trace.go:171","msg":"trace[2061953755] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"137.222946ms","start":"2026-10-16T14:29:35.375445Z","end":"2026-10-16T14:29:35.512668Z","steps":["trace[2061953755] 'process raft request' (duration: 136.989779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:35.572853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.946464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18df08e8ba201c59\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-10-16T14:29:35.573063Z","caller":"traceutil/trace.go:171","msg":"trace[187408630] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18df08e8ba201c59; range_end:; response_count:1; response_revision:1896; }","duration":"117.19406ms","start":"2026-10-16T14:29:35.455851Z","end":"2026-10-16T14:29:35.573045Z","steps":["trace[187408630] 'agreement among raft nodes before linearized reading' (duration: 116.637846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:29:35.573197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.322413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4cbf31c9-f8f5-42fa-8537-345ebd2a79b1\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-16T14:29:35.5733Z","caller":"traceutil/trace.go:171","msg":"trace[134842770] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4cbf31c9-f8f5-42fa-8537-345ebd2a79b1; range_end:; response_count:1; response_revision:1896; }","duration":"110.502718ms","start":"2026-10-16T14:29:35.462772Z","end":"2026-10-16T14:29:35.573274Z","steps":["trace[134842770] 'agreement among raft nodes before linearized reading' (duration: 110.104687ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:29:51.350822Z","caller":"traceutil/trace.go:171","msg":"trace[549587715] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"128.788635ms","start":"2026-10-16T14:29:51.222007Z","end":"2026-10-16T14:29:51.350796Z","steps":["trace[549587715] 'process raft request' (duration: 128.620851ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:23.674563Z","caller":"traceutil/trace.go:171","msg":"trace[1770538066] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"135.869472ms","start":"2026-10-16T14:30:23.538664Z","end":"2026-10-16T14:30:23.674534Z","steps":["trace[1770538066] 'process raft request' (duration: 135.668456ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:30.150866Z","caller":"traceutil/trace.go:171","msg":"trace[1927891120] linearizableReadLoop","detail":"{readStateIndex:2326; appliedIndex:2325; }","duration":"363.665269ms","start":"2026-10-16T14:30:29.787171Z","end":"2026-10-16T14:30:30.150836Z","steps":["trace[1927891120] 'read index received' (duration: 363.570926ms)","trace[1927891120] 'applied index is now lower than readState.Index' (duration: 93.163µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:30:30.15098Z","caller":"traceutil/trace.go:171","msg":"trace[1466966673] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"448.944626ms","start":"2026-10-16T14:30:29.701976Z","end":"2026-10-16T14:30:30.150921Z","steps":["trace[1466966673] 'process raft request' (duration: 448.680169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:30.151095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.899775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-16T14:30:30.151137Z","caller":"traceutil/trace.go:171","msg":"trace[1599339927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2240; }","duration":"364.002997ms","start":"2026-10-16T14:30:29.787122Z","end":"2026-10-16T14:30:30.151125Z","steps":["trace[1599339927] 'agreement among raft nodes before linearized reading' (duration: 363.851833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:30.15117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:30:29.787098Z","time spent":"364.064349ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-16T14:30:30.151178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:30:29.701954Z","time spent":"449.114231ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:30:30.3971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.865612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:30:30.397359Z","caller":"traceutil/trace.go:171","msg":"trace[1237155875] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"511.492166ms","start":"2026-10-16T14:30:29.885822Z","end":"2026-10-16T14:30:30.397314Z","steps":["trace[1237155875] 'process raft request' (duration: 386.307515ms)","trace[1237155875] 'compare' (duration: 124.714808ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:30:30.397404Z","caller":"traceutil/trace.go:171","msg":"trace[1711376840] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"242.706283ms","start":"2026-10-16T14:30:30.154676Z","end":"2026-10-16T14:30:30.397382Z","steps":["trace[1711376840] 'process raft request' (duration: 242.652201ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:30.397429Z","caller":"traceutil/trace.go:171","msg":"trace[1380276111] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"502.239034ms","start":"2026-10-16T14:30:29.895163Z","end":"2026-10-16T14:30:30.397402Z","steps":["trace[1380276111] 'process raft request' (duration: 502.036949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:30.397572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:30:29.885799Z","time spent":"511.649689ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:30:30.397651Z","caller":"traceutil/trace.go:171","msg":"trace[1112537003] linearizableReadLoop","detail":"{readStateIndex:2328; appliedIndex:2326; }","duration":"246.693772ms","start":"2026-10-16T14:30:30.150941Z","end":"2026-10-16T14:30:30.397635Z","steps":["trace[1112537003] 'read index received' (duration: 121.200814ms)","trace[1112537003] 'applied index is now lower than readState.Index' (duration: 125.491318ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:30:30.397635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:30:29.895146Z","time spent":"502.355677ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:30:30.397771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.427478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:30:30.397817Z","caller":"traceutil/trace.go:171","msg":"trace[987727024] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2243; }","duration":"252.504151ms","start":"2026-10-16T14:30:30.145301Z","end":"2026-10-16T14:30:30.397805Z","steps":["trace[987727024] 'agreement among raft nodes before linearized reading' (duration: 252.418879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:30.397866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.27254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:30:30.397853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.867926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:30:30.397935Z","caller":"traceutil/trace.go:171","msg":"trace[1921162398] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2243; }","duration":"285.380553ms","start":"2026-10-16T14:30:30.112538Z","end":"2026-10-16T14:30:30.397919Z","steps":["trace[1921162398] 'agreement among raft nodes before linearized reading' (duration: 285.27944ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:30.39798Z","caller":"traceutil/trace.go:171","msg":"trace[317058490] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2243; }","duration":"544.02316ms","start":"2026-10-16T14:30:29.853909Z","end":"2026-10-16T14:30:30.397933Z","steps":["trace[317058490] 'agreement among raft nodes before linearized reading' (duration: 543.837925ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:30.398032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.018266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-16T14:30:30.398044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.409709ms","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-16T14:30:30.398101Z","caller":"traceutil/trace.go:171","msg":"trace[572229250] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2243; }","duration":"157.122228ms","start":"2026-10-16T14:30:30.240958Z","end":"2026-10-16T14:30:30.39808Z","steps":["trace[572229250] 'agreement among raft nodes before linearized reading' (duration: 156.923513ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:30:30.398122Z","caller":"traceutil/trace.go:171","msg":"trace[1127473714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2243; }","duration":"290.535973ms","start":"2026-10-16T14:30:30.107563Z","end":"2026-10-16T14:30:30.398099Z","steps":["trace[1127473714] 'agreement among raft nodes before linearized reading' (duration: 290.300916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:30.398025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:30:29.853888Z","time spent":"544.124783ms","remote":"127.0.0.1:44790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-16T14:30:30.398189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.107344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:30:30.398273Z","caller":"traceutil/trace.go:171","msg":"trace[1622637136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2243; }","duration":"547.230337ms","start":"2026-10-16T14:30:29.851019Z","end":"2026-10-16T14:30:30.39825Z","steps":["trace[1622637136] 'agreement among raft nodes before linearized reading' (duration: 547.021451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:30:30.398325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:30:29.851006Z","time spent":"547.31183ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-16T14:30:30.398362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.889361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-16T14:30:30.398438Z","caller":"traceutil/trace.go:171","msg":"trace[1784255070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2243; }","duration":"132.998464ms","start":"2026-10-16T14:30:30.265418Z","end":"2026-10-16T14:30:30.398416Z","steps":["trace[1784255070] 'agreement among raft nodes before linearized reading' (duration: 132.794879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:04.86309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.273194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:31:04.863177Z","caller":"traceutil/trace.go:171","msg":"trace[2056797808] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2606; }","duration":"174.414639ms","start":"2026-10-16T14:31:04.688746Z","end":"2026-10-16T14:31:04.863161Z","steps":["trace[2056797808] 'count revisions from in-memory index tree' (duration: 174.195242ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:38.606977Z","caller":"traceutil/trace.go:171","msg":"trace[1189723746] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"100.270644ms","start":"2026-10-16T14:31:38.506679Z","end":"2026-10-16T14:31:38.60695Z","steps":["trace[1189723746] 'process raft request' (duration: 100.082329ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:31:38.85775Z","caller":"traceutil/trace.go:171","msg":"trace[798295602] linearizableReadLoop","detail":"{readStateIndex:2918; appliedIndex:2917; }","duration":"147.841136ms","start":"2026-10-16T14:31:38.709875Z","end":"2026-10-16T14:31:38.857716Z","steps":["trace[798295602] 'read index received' (duration: 147.493207ms)","trace[798295602] 'applied index is now lower than readState.Index' (duration: 346.609µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:31:38.857798Z","caller":"traceutil/trace.go:171","msg":"trace[841336817] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"221.962539ms","start":"2026-10-16T14:31:38.635803Z","end":"2026-10-16T14:31:38.857765Z","steps":["trace[841336817] 'process raft request' (duration: 221.672061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:38.857955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.067941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:31:38.85803Z","caller":"traceutil/trace.go:171","msg":"trace[647867517] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2809; }","duration":"148.195706ms","start":"2026-10-16T14:31:38.709815Z","end":"2026-10-16T14:31:38.858011Z","steps":["trace[647867517] 'agreement among raft nodes before linearized reading' (duration: 148.065171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:31:38.858195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.152493ms","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-16T14:31:38.858248Z","caller":"traceutil/trace.go:171","msg":"trace[496162924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2809; }","duration":"145.345719ms","start":"2026-10-16T14:31:38.712887Z","end":"2026-10-16T14:31:38.858233Z","steps":["trace[496162924] 'agreement among raft nodes before linearized reading' (duration: 145.021509ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:34:46.19699Z","caller":"traceutil/trace.go:171","msg":"trace[864462759] transaction","detail":"{read_only:false; response_revision:4006; number_of_response:1; }","duration":"136.416578ms","start":"2026-10-16T14:34:46.06054Z","end":"2026-10-16T14:34:46.196957Z","steps":["trace[864462759] 'process raft request' (duration: 136.13304ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:10.844215Z","caller":"traceutil/trace.go:171","msg":"trace[1249142746] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"109.951676ms","start":"2026-10-16T14:36:10.734233Z","end":"2026-10-16T14:36:10.844184Z","steps":["trace[1249142746] 'process raft request' (duration: 109.779911ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:10.865127Z","caller":"traceutil/trace.go:171","msg":"trace[645354622] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"130.767443ms","start":"2026-10-16T14:36:10.734322Z","end":"2026-10-16T14:36:10.86509Z","steps":["trace[645354622] 'process raft request' (duration: 130.500646ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:10.865163Z","caller":"traceutil/trace.go:171","msg":"trace[844874700] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5234; }","duration":"128.242254ms","start":"2026-10-16T14:36:10.736872Z","end":"2026-10-16T14:36:10.865114Z","steps":["trace[844874700] 'read index received' (duration: 107.20418ms)","trace[844874700] 'applied index is now lower than readState.Index' (duration: 21.036924ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:36:10.865226Z","caller":"traceutil/trace.go:171","msg":"trace[1585453229] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"124.379248ms","start":"2026-10-16T14:36:10.740821Z","end":"2026-10-16T14:36:10.8652Z","steps":["trace[1585453229] 'process raft request' (duration: 124.213484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:10.865416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.521002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-10-16T14:36:10.865477Z","caller":"traceutil/trace.go:171","msg":"trace[519936913] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5047; }","duration":"128.632615ms","start":"2026-10-16T14:36:10.736829Z","end":"2026-10-16T14:36:10.865461Z","steps":["trace[519936913] 'agreement among raft nodes before linearized reading' (duration: 128.45306ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:10.865469Z","caller":"traceutil/trace.go:171","msg":"trace[687381825] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"129.853448ms","start":"2026-10-16T14:36:10.735407Z","end":"2026-10-16T14:36:10.86526Z","steps":["trace[687381825] 'process raft request' (duration: 129.57268ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:10.982638Z","caller":"traceutil/trace.go:171","msg":"trace[1670067958] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"104.856307ms","start":"2026-10-16T14:36:10.877748Z","end":"2026-10-16T14:36:10.982604Z","steps":["trace[1670067958] 'process raft request' (duration: 93.449216ms)","trace[1670067958] 'compare' (duration: 11.247146ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:36:12.362801Z","caller":"traceutil/trace.go:171","msg":"trace[106735900] linearizableReadLoop","detail":"{readStateIndex:5264; appliedIndex:5262; }","duration":"114.970343ms","start":"2026-10-16T14:36:12.247798Z","end":"2026-10-16T14:36:12.362769Z","steps":["trace[106735900] 'read index received' (duration: 49.947631ms)","trace[106735900] 'applied index is now lower than readState.Index' (duration: 65.021822ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:36:12.362859Z","caller":"traceutil/trace.go:171","msg":"trace[1172428369] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"121.557022ms","start":"2026-10-16T14:36:12.241277Z","end":"2026-10-16T14:36:12.362834Z","steps":["trace[1172428369] 'process raft request' (duration: 119.228089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:12.362983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.163189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-16T14:36:12.363017Z","caller":"traceutil/trace.go:171","msg":"trace[1631540369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5071; }","duration":"115.22338ms","start":"2026-10-16T14:36:12.247784Z","end":"2026-10-16T14:36:12.363007Z","steps":["trace[1631540369] 'agreement among raft nodes before linearized reading' (duration: 115.082936ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:16.055378Z","caller":"traceutil/trace.go:171","msg":"trace[1960270906] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"108.248189ms","start":"2026-10-16T14:36:15.947103Z","end":"2026-10-16T14:36:16.055351Z","steps":["trace[1960270906] 'process raft request' (duration: 108.098165ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:16.28382Z","caller":"traceutil/trace.go:171","msg":"trace[951275448] linearizableReadLoop","detail":"{readStateIndex:5413; appliedIndex:5411; }","duration":"314.429727ms","start":"2026-10-16T14:36:15.969371Z","end":"2026-10-16T14:36:16.283801Z","steps":["trace[951275448] 'read index received' (duration: 85.830919ms)","trace[951275448] 'applied index is now lower than readState.Index' (duration: 228.598058ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:36:16.284016Z","caller":"traceutil/trace.go:171","msg":"trace[1652444286] transaction","detail":"{read_only:false; response_revision:5216; number_of_response:1; }","duration":"326.374961ms","start":"2026-10-16T14:36:15.957632Z","end":"2026-10-16T14:36:16.284007Z","steps":["trace[1652444286] 'process raft request' (duration: 321.89456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:16.284101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:36:15.957607Z","time spent":"326.428043ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:36:16.284239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.887074ms","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-16T14:36:16.284294Z","caller":"traceutil/trace.go:171","msg":"trace[199435473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5216; }","duration":"306.003627ms","start":"2026-10-16T14:36:15.978279Z","end":"2026-10-16T14:36:16.284283Z","steps":["trace[199435473] 'agreement among raft nodes before linearized reading' (duration: 305.813291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:16.284319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:36:15.978267Z","time spent":"306.047037ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-16T14:36:16.284461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.089684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18e5b9fe-0408-424b-ba0e-80eaa219b72a\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-16T14:36:16.284535Z","caller":"traceutil/trace.go:171","msg":"trace[796243637] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-18e5b9fe-0408-424b-ba0e-80eaa219b72a; range_end:; response_count:1; response_revision:5216; }","duration":"315.200677ms","start":"2026-10-16T14:36:15.969316Z","end":"2026-10-16T14:36:16.284516Z","steps":["trace[796243637] 'agreement among raft nodes before linearized reading' (duration: 314.986531ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:16.284576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:36:15.969293Z","time spent":"315.272469ms","remote":"127.0.0.1:44472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-18e5b9fe-0408-424b-ba0e-80eaa219b72a\" "} {"level":"warn","ts":"2026-10-16T14:36:16.28466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.08986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787384"} {"level":"info","ts":"2026-10-16T14:36:16.28469Z","caller":"traceutil/trace.go:171","msg":"trace[1487471815] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5216; }","duration":"273.156131ms","start":"2026-10-16T14:36:16.011525Z","end":"2026-10-16T14:36:16.284681Z","steps":["trace[1487471815] 'agreement among raft nodes before linearized reading' (duration: 272.809112ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:16.610481Z","caller":"traceutil/trace.go:171","msg":"trace[2105439184] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"201.664074ms","start":"2026-10-16T14:36:16.408798Z","end":"2026-10-16T14:36:16.610462Z","steps":["trace[2105439184] 'process raft request' (duration: 158.821456ms)","trace[2105439184] 'compare' (duration: 42.425367ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:36:16.610598Z","caller":"traceutil/trace.go:171","msg":"trace[2141752273] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"194.48174ms","start":"2026-10-16T14:36:16.416078Z","end":"2026-10-16T14:36:16.61056Z","steps":["trace[2141752273] 'process raft request' (duration: 194.050178ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:30.468992Z","caller":"traceutil/trace.go:171","msg":"trace[887634772] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"298.903455ms","start":"2026-10-16T14:36:30.170064Z","end":"2026-10-16T14:36:30.468968Z","steps":["trace[887634772] 'process raft request' (duration: 298.759841ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:30.476399Z","caller":"traceutil/trace.go:171","msg":"trace[1654096964] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"146.162072ms","start":"2026-10-16T14:36:30.330215Z","end":"2026-10-16T14:36:30.476377Z","steps":["trace[1654096964] 'process raft request' (duration: 145.952896ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:30.478032Z","caller":"traceutil/trace.go:171","msg":"trace[537553355] linearizableReadLoop","detail":"{readStateIndex:5671; appliedIndex:5669; }","duration":"113.365339ms","start":"2026-10-16T14:36:30.364646Z","end":"2026-10-16T14:36:30.478011Z","steps":["trace[537553355] 'read index received' (duration: 104.188039ms)","trace[537553355] 'applied index is now lower than readState.Index' (duration: 9.17627ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:36:30.478343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.398309ms","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-16T14:36:30.478377Z","caller":"traceutil/trace.go:171","msg":"trace[966716260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5467; }","duration":"102.465591ms","start":"2026-10-16T14:36:30.375903Z","end":"2026-10-16T14:36:30.478369Z","steps":["trace[966716260] 'agreement among raft nodes before linearized reading' (duration: 102.334188ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:30.478363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.695607ms","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-16T14:36:30.478447Z","caller":"traceutil/trace.go:171","msg":"trace[2105207476] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5467; }","duration":"113.832871ms","start":"2026-10-16T14:36:30.364595Z","end":"2026-10-16T14:36:30.478428Z","steps":["trace[2105207476] 'agreement among raft nodes before linearized reading' (duration: 113.606905ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:38.146168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.763664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:36:38.146399Z","caller":"traceutil/trace.go:171","msg":"trace[1318881849] transaction","detail":"{read_only:false; response_revision:5620; number_of_response:1; }","duration":"299.274053ms","start":"2026-10-16T14:36:37.847102Z","end":"2026-10-16T14:36:38.146376Z","steps":["trace[1318881849] 'process raft request' (duration: 194.18903ms)","trace[1318881849] 'compare' (duration: 104.428885ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:36:38.146574Z","caller":"traceutil/trace.go:171","msg":"trace[1809642008] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"298.399089ms","start":"2026-10-16T14:36:37.848154Z","end":"2026-10-16T14:36:38.146553Z","steps":["trace[1809642008] 'process raft request' (duration: 298.127962ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:38.147012Z","caller":"traceutil/trace.go:171","msg":"trace[95636709] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"295.861821ms","start":"2026-10-16T14:36:37.851141Z","end":"2026-10-16T14:36:38.147003Z","steps":["trace[95636709] 'process raft request' (duration: 295.372727ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:38.147037Z","caller":"traceutil/trace.go:171","msg":"trace[1134788308] linearizableReadLoop","detail":"{readStateIndex:5831; appliedIndex:5828; }","duration":"295.713968ms","start":"2026-10-16T14:36:37.851294Z","end":"2026-10-16T14:36:38.147008Z","steps":["trace[1134788308] 'read index received' (duration: 190.078959ms)","trace[1134788308] 'applied index is now lower than readState.Index' (duration: 105.634089ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:36:38.147112Z","caller":"traceutil/trace.go:171","msg":"trace[1534944949] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"164.244095ms","start":"2026-10-16T14:36:37.982861Z","end":"2026-10-16T14:36:38.147105Z","steps":["trace[1534944949] 'process raft request' (duration: 164.04042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:38.14727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.947354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:36:38.147283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.705569ms","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":"info","ts":"2026-10-16T14:36:38.147313Z","caller":"traceutil/trace.go:171","msg":"trace[107892216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5623; }","duration":"296.040896ms","start":"2026-10-16T14:36:37.851257Z","end":"2026-10-16T14:36:38.147298Z","steps":["trace[107892216] 'agreement among raft nodes before linearized reading' (duration: 295.883682ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:36:38.147343Z","caller":"traceutil/trace.go:171","msg":"trace[1556643519] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5623; }","duration":"229.923864ms","start":"2026-10-16T14:36:37.917399Z","end":"2026-10-16T14:36:38.147323Z","steps":["trace[1556643519] 'agreement among raft nodes before linearized reading' (duration: 229.800451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:36:38.147391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.430342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a81478d-25a4-4ea3-bde4-18d2d5af8693\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-10-16T14:36:38.148055Z","caller":"traceutil/trace.go:171","msg":"trace[957730067] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a81478d-25a4-4ea3-bde4-18d2d5af8693; range_end:; response_count:1; response_revision:5623; }","duration":"197.13244ms","start":"2026-10-16T14:36:37.950869Z","end":"2026-10-16T14:36:38.148002Z","steps":["trace[957730067] 'agreement among raft nodes before linearized reading' (duration: 196.407161ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:37:11.541087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2994} {"level":"info","ts":"2026-10-16T14:37:11.635407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2994,"took":"92.965553ms","hash":3263844334,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":32456704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T14:37:11.635481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3263844334,"revision":2994,"compact-revision":-1} {"level":"info","ts":"2026-10-16T14:37:31.426768Z","caller":"traceutil/trace.go:171","msg":"trace[69531437] transaction","detail":"{read_only:false; response_revision:6142; number_of_response:1; }","duration":"104.688983ms","start":"2026-10-16T14:37:31.322043Z","end":"2026-10-16T14:37:31.426732Z","steps":["trace[69531437] 'process raft request' (duration: 104.183858ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:42:11.548724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5927} {"level":"info","ts":"2026-10-16T14:42:11.639542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5927,"took":"89.06569ms","hash":2371102053,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":25829376,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T14:42:11.63962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2371102053,"revision":5927,"compact-revision":2994} {"level":"info","ts":"2026-10-16T14:42:58.192299Z","caller":"traceutil/trace.go:171","msg":"trace[1151056911] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"245.933454ms","start":"2026-10-16T14:42:57.946341Z","end":"2026-10-16T14:42:58.192275Z","steps":["trace[1151056911] 'process raft request' (duration: 245.771769ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:42:58.336619Z","caller":"traceutil/trace.go:171","msg":"trace[1737022712] linearizableReadLoop","detail":"{readStateIndex:8831; appliedIndex:8829; }","duration":"336.159125ms","start":"2026-10-16T14:42:58.00044Z","end":"2026-10-16T14:42:58.3366Z","steps":["trace[1737022712] 'read index received' (duration: 191.674909ms)","trace[1737022712] 'applied index is now lower than readState.Index' (duration: 144.483316ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:42:58.336734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.279948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:42:58.336761Z","caller":"traceutil/trace.go:171","msg":"trace[370482612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8529; }","duration":"336.316819ms","start":"2026-10-16T14:42:58.000434Z","end":"2026-10-16T14:42:58.33675Z","steps":["trace[370482612] 'agreement among raft nodes before linearized reading' (duration: 336.242157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:42:58.336801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:42:58.000377Z","time spent":"336.419042ms","remote":"127.0.0.1:44260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-16T14:42:58.336871Z","caller":"traceutil/trace.go:171","msg":"trace[1150818569] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"389.345331ms","start":"2026-10-16T14:42:57.947498Z","end":"2026-10-16T14:42:58.336843Z","steps":["trace[1150818569] 'process raft request' (duration: 386.010979ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:42:58.337007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:42:57.947475Z","time spent":"389.457243ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:43:09.934594Z","caller":"traceutil/trace.go:171","msg":"trace[1509655354] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"382.889195ms","start":"2026-10-16T14:43:09.55168Z","end":"2026-10-16T14:43:09.934569Z","steps":["trace[1509655354] 'process raft request' (duration: 382.755321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:09.934728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:43:09.551651Z","time spent":"383.009419ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:43:09.945278Z","caller":"traceutil/trace.go:171","msg":"trace[1976415077] linearizableReadLoop","detail":"{readStateIndex:8892; appliedIndex:8890; }","duration":"307.588648ms","start":"2026-10-16T14:43:09.637658Z","end":"2026-10-16T14:43:09.945247Z","steps":["trace[1976415077] 'read index received' (duration: 296.861687ms)","trace[1976415077] 'applied index is now lower than readState.Index' (duration: 10.725611ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:43:09.945379Z","caller":"traceutil/trace.go:171","msg":"trace[354984431] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"349.375183ms","start":"2026-10-16T14:43:09.59597Z","end":"2026-10-16T14:43:09.945345Z","steps":["trace[354984431] 'process raft request' (duration: 349.154237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:09.945524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.916276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-16T14:43:09.945545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.871557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bswk9\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-10-16T14:43:09.945557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:43:09.595933Z","time spent":"349.539068ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:43:09.945612Z","caller":"traceutil/trace.go:171","msg":"trace[1147875945] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:8588; }","duration":"308.04058ms","start":"2026-10-16T14:43:09.637532Z","end":"2026-10-16T14:43:09.945572Z","steps":["trace[1147875945] 'agreement among raft nodes before linearized reading' (duration: 307.854325ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:09.945622Z","caller":"traceutil/trace.go:171","msg":"trace[602346707] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bswk9; range_end:; response_count:1; response_revision:8588; }","duration":"303.96125ms","start":"2026-10-16T14:43:09.641617Z","end":"2026-10-16T14:43:09.945578Z","steps":["trace[602346707] 'agreement among raft nodes before linearized reading' (duration: 303.788025ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:09.945663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:43:09.637506Z","time spent":"308.140953ms","remote":"127.0.0.1:44384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":84,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-10-16T14:43:09.945672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:43:09.641572Z","time spent":"304.088223ms","remote":"127.0.0.1:44472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-bswk9\" "} {"level":"warn","ts":"2026-10-16T14:43:10.746131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.157145ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-16T14:43:10.746485Z","caller":"traceutil/trace.go:171","msg":"trace[1747379963] linearizableReadLoop","detail":"{readStateIndex:8896; appliedIndex:8893; }","duration":"273.103131ms","start":"2026-10-16T14:43:10.473361Z","end":"2026-10-16T14:43:10.746464Z","steps":["trace[1747379963] 'read index received' (duration: 152.793711ms)","trace[1747379963] 'applied index is now lower than readState.Index' (duration: 120.30834ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:43:10.746515Z","caller":"traceutil/trace.go:171","msg":"trace[1932169601] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"364.184126ms","start":"2026-10-16T14:43:10.382301Z","end":"2026-10-16T14:43:10.746485Z","steps":["trace[1932169601] 'process raft request' (duration: 363.94348ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:10.746606Z","caller":"traceutil/trace.go:171","msg":"trace[791654742] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"299.17972ms","start":"2026-10-16T14:43:10.447401Z","end":"2026-10-16T14:43:10.746581Z","steps":["trace[791654742] 'process raft request' (duration: 298.993845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:10.746734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:43:10.382277Z","time spent":"364.351371ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:43:10.746742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.364728ms","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-16T14:43:10.746795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.93934ms","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-16T14:43:10.746854Z","caller":"traceutil/trace.go:171","msg":"trace[1958875116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8591; }","duration":"233.104475ms","start":"2026-10-16T14:43:10.513731Z","end":"2026-10-16T14:43:10.746836Z","steps":["trace[1958875116] 'agreement among raft nodes before linearized reading' (duration: 232.966321ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:10.746855Z","caller":"traceutil/trace.go:171","msg":"trace[1335449972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8591; }","duration":"273.480491ms","start":"2026-10-16T14:43:10.473348Z","end":"2026-10-16T14:43:10.746829Z","steps":["trace[1335449972] 'agreement among raft nodes before linearized reading' (duration: 273.228035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:10.746889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.599763ms","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-16T14:43:10.746946Z","caller":"traceutil/trace.go:171","msg":"trace[1523528779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8591; }","duration":"220.698957ms","start":"2026-10-16T14:43:10.52623Z","end":"2026-10-16T14:43:10.746929Z","steps":["trace[1523528779] 'agreement among raft nodes before linearized reading' (duration: 220.48177ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:10.856537Z","caller":"traceutil/trace.go:171","msg":"trace[1506864487] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"104.658005ms","start":"2026-10-16T14:43:10.751849Z","end":"2026-10-16T14:43:10.856507Z","steps":["trace[1506864487] 'process raft request' (duration: 104.416248ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:10.856539Z","caller":"traceutil/trace.go:171","msg":"trace[1016797031] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"103.77014ms","start":"2026-10-16T14:43:10.752743Z","end":"2026-10-16T14:43:10.856514Z","steps":["trace[1016797031] 'process raft request' (duration: 103.672117ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:16.702324Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022eb.wal"} {"level":"info","ts":"2026-10-16T14:43:40.0994Z","caller":"traceutil/trace.go:171","msg":"trace[2074290853] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"151.648811ms","start":"2026-10-16T14:43:39.947734Z","end":"2026-10-16T14:43:40.099383Z","steps":["trace[2074290853] 'process raft request' (duration: 151.527788ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:40.226044Z","caller":"traceutil/trace.go:171","msg":"trace[1825381434] transaction","detail":"{read_only:false; response_revision:8806; number_of_response:1; }","duration":"178.149651ms","start":"2026-10-16T14:43:40.047874Z","end":"2026-10-16T14:43:40.226024Z","steps":["trace[1825381434] 'process raft request' (duration: 144.338762ms)","trace[1825381434] 'compare' (duration: 33.289124ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:43:43.041013Z","caller":"traceutil/trace.go:171","msg":"trace[784005229] transaction","detail":"{read_only:false; response_revision:8818; number_of_response:1; }","duration":"178.466729ms","start":"2026-10-16T14:43:42.862529Z","end":"2026-10-16T14:43:43.040996Z","steps":["trace[784005229] 'process raft request' (duration: 178.166281ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:43.222368Z","caller":"traceutil/trace.go:171","msg":"trace[1560303487] transaction","detail":"{read_only:false; response_revision:8819; number_of_response:1; }","duration":"177.972066ms","start":"2026-10-16T14:43:43.044371Z","end":"2026-10-16T14:43:43.222343Z","steps":["trace[1560303487] 'process raft request' (duration: 175.007365ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:43.228598Z","caller":"traceutil/trace.go:171","msg":"trace[1104310714] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"184.060291ms","start":"2026-10-16T14:43:43.044515Z","end":"2026-10-16T14:43:43.228575Z","steps":["trace[1104310714] 'process raft request' (duration: 183.908296ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:43.228917Z","caller":"traceutil/trace.go:171","msg":"trace[2066329232] linearizableReadLoop","detail":"{readStateIndex:9135; appliedIndex:9131; }","duration":"162.367881ms","start":"2026-10-16T14:43:43.066523Z","end":"2026-10-16T14:43:43.22889Z","steps":["trace[2066329232] 'read index received' (duration: 152.971696ms)","trace[2066329232] 'applied index is now lower than readState.Index' (duration: 9.394765ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:43:43.229029Z","caller":"traceutil/trace.go:171","msg":"trace[305850913] transaction","detail":"{read_only:false; response_revision:8822; number_of_response:1; }","duration":"179.986621ms","start":"2026-10-16T14:43:43.04903Z","end":"2026-10-16T14:43:43.229017Z","steps":["trace[305850913] 'process raft request' (duration: 179.750374ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:43.229081Z","caller":"traceutil/trace.go:171","msg":"trace[963081485] transaction","detail":"{read_only:false; response_revision:8821; number_of_response:1; }","duration":"184.515034ms","start":"2026-10-16T14:43:43.044548Z","end":"2026-10-16T14:43:43.229063Z","steps":["trace[963081485] 'process raft request' (duration: 183.990549ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:43.229188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.756573ms","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-16T14:43:43.229213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.67367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-16T14:43:43.229246Z","caller":"traceutil/trace.go:171","msg":"trace[1889261151] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8822; }","duration":"162.730461ms","start":"2026-10-16T14:43:43.066506Z","end":"2026-10-16T14:43:43.229237Z","steps":["trace[1889261151] 'agreement among raft nodes before linearized reading' (duration: 162.603678ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:43.229248Z","caller":"traceutil/trace.go:171","msg":"trace[1983884592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8822; }","duration":"148.834425ms","start":"2026-10-16T14:43:43.080397Z","end":"2026-10-16T14:43:43.229232Z","steps":["trace[1983884592] 'agreement among raft nodes before linearized reading' (duration: 148.65554ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:51.485872Z","caller":"traceutil/trace.go:171","msg":"trace[758589819] transaction","detail":"{read_only:false; response_revision:8864; number_of_response:1; }","duration":"152.589447ms","start":"2026-10-16T14:43:51.333267Z","end":"2026-10-16T14:43:51.485857Z","steps":["trace[758589819] 'process raft request' (duration: 152.507844ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:51.485945Z","caller":"traceutil/trace.go:171","msg":"trace[1705197520] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"152.756011ms","start":"2026-10-16T14:43:51.333156Z","end":"2026-10-16T14:43:51.485912Z","steps":["trace[1705197520] 'process raft request' (duration: 108.401286ms)","trace[1705197520] 'compare' (duration: 43.973935ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:43:51.485992Z","caller":"traceutil/trace.go:171","msg":"trace[1911560708] transaction","detail":"{read_only:false; response_revision:8865; number_of_response:1; }","duration":"150.963743ms","start":"2026-10-16T14:43:51.33502Z","end":"2026-10-16T14:43:51.485984Z","steps":["trace[1911560708] 'process raft request' (duration: 150.813039ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:51.486005Z","caller":"traceutil/trace.go:171","msg":"trace[470067529] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"150.923541ms","start":"2026-10-16T14:43:51.335059Z","end":"2026-10-16T14:43:51.485983Z","steps":["trace[470067529] 'process raft request' (duration: 150.847439ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:51.754682Z","caller":"traceutil/trace.go:171","msg":"trace[732985141] transaction","detail":"{read_only:false; response_revision:8867; number_of_response:1; }","duration":"275.96726ms","start":"2026-10-16T14:43:51.47869Z","end":"2026-10-16T14:43:51.754657Z","steps":["trace[732985141] 'process raft request' (duration: 265.188737ms)","trace[732985141] 'compare' (duration: 10.516896ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:43:51.760298Z","caller":"traceutil/trace.go:171","msg":"trace[328317723] linearizableReadLoop","detail":"{readStateIndex:9183; appliedIndex:9181; }","duration":"148.917647ms","start":"2026-10-16T14:43:51.611358Z","end":"2026-10-16T14:43:51.760276Z","steps":["trace[328317723] 'read index received' (duration: 132.534542ms)","trace[328317723] 'applied index is now lower than readState.Index' (duration: 16.382155ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:43:51.76049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.114002ms","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-16T14:43:51.760541Z","caller":"traceutil/trace.go:171","msg":"trace[180295788] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8868; }","duration":"149.211045ms","start":"2026-10-16T14:43:51.611315Z","end":"2026-10-16T14:43:51.760526Z","steps":["trace[180295788] 'agreement among raft nodes before linearized reading' (duration: 149.114842ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:51.760543Z","caller":"traceutil/trace.go:171","msg":"trace[1893194203] transaction","detail":"{read_only:false; response_revision:8868; number_of_response:1; }","duration":"231.811779ms","start":"2026-10-16T14:43:51.528705Z","end":"2026-10-16T14:43:51.760517Z","steps":["trace[1893194203] 'process raft request' (duration: 231.45945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:51.760693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.390093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:43:51.760787Z","caller":"traceutil/trace.go:171","msg":"trace[637294520] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8868; }","duration":"148.521746ms","start":"2026-10-16T14:43:51.612249Z","end":"2026-10-16T14:43:51.760771Z","steps":["trace[637294520] 'agreement among raft nodes before linearized reading' (duration: 148.313821ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:53.556322Z","caller":"traceutil/trace.go:171","msg":"trace[1675813823] linearizableReadLoop","detail":"{readStateIndex:9198; appliedIndex:9197; }","duration":"112.767434ms","start":"2026-10-16T14:43:53.44353Z","end":"2026-10-16T14:43:53.556298Z","steps":["trace[1675813823] 'read index received' (duration: 102.091204ms)","trace[1675813823] 'applied index is now lower than readState.Index' (duration: 10.67521ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:43:53.55634Z","caller":"traceutil/trace.go:171","msg":"trace[2063377731] transaction","detail":"{read_only:false; response_revision:8883; number_of_response:1; }","duration":"143.766646ms","start":"2026-10-16T14:43:53.412541Z","end":"2026-10-16T14:43:53.556308Z","steps":["trace[2063377731] 'process raft request' (duration: 133.003184ms)","trace[2063377731] 'compare' (duration: 10.492945ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:43:53.556545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.0064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"info","ts":"2026-10-16T14:43:53.556584Z","caller":"traceutil/trace.go:171","msg":"trace[866581256] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8883; }","duration":"113.095942ms","start":"2026-10-16T14:43:53.443478Z","end":"2026-10-16T14:43:53.556573Z","steps":["trace[866581256] 'agreement among raft nodes before linearized reading' (duration: 112.932848ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:53.623789Z","caller":"traceutil/trace.go:171","msg":"trace[826279966] transaction","detail":"{read_only:false; response_revision:8884; number_of_response:1; }","duration":"153.227904ms","start":"2026-10-16T14:43:53.470536Z","end":"2026-10-16T14:43:53.623764Z","steps":["trace[826279966] 'process raft request' (duration: 153.07419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:53.634839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.098573ms","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":"warn","ts":"2026-10-16T14:43:53.634897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qqvl7\" ","response":"range_response_count:1 size:4790"} {"level":"info","ts":"2026-10-16T14:43:53.634962Z","caller":"traceutil/trace.go:171","msg":"trace[1874352224] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qqvl7; range_end:; response_count:1; response_revision:8885; }","duration":"165.310393ms","start":"2026-10-16T14:43:53.469623Z","end":"2026-10-16T14:43:53.634933Z","steps":["trace[1874352224] 'agreement among raft nodes before linearized reading' (duration: 165.19126ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:53.634966Z","caller":"traceutil/trace.go:171","msg":"trace[36885605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8885; }","duration":"145.262787ms","start":"2026-10-16T14:43:53.489658Z","end":"2026-10-16T14:43:53.634921Z","steps":["trace[36885605] 'agreement among raft nodes before linearized reading' (duration: 140.700783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:53.63509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.280126ms","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:7"} {"level":"info","ts":"2026-10-16T14:43:53.63518Z","caller":"traceutil/trace.go:171","msg":"trace[811107838] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8885; }","duration":"149.41686ms","start":"2026-10-16T14:43:53.485728Z","end":"2026-10-16T14:43:53.635145Z","steps":["trace[811107838] 'agreement among raft nodes before linearized reading' (duration: 149.264166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:43:53.63553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.3733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-16T14:43:53.635565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.156995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-16T14:43:53.635724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.648168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-16T14:43:53.635714Z","caller":"traceutil/trace.go:171","msg":"trace[1197809805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8885; }","duration":"145.262608ms","start":"2026-10-16T14:43:53.490374Z","end":"2026-10-16T14:43:53.635637Z","steps":["trace[1197809805] 'agreement among raft nodes before linearized reading' (duration: 144.98458ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:53.635813Z","caller":"traceutil/trace.go:171","msg":"trace[987798951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8885; }","duration":"145.741821ms","start":"2026-10-16T14:43:53.490044Z","end":"2026-10-16T14:43:53.635786Z","steps":["trace[987798951] 'agreement among raft nodes before linearized reading' (duration: 140.320033ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:43:53.635987Z","caller":"traceutil/trace.go:171","msg":"trace[1488215566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8885; }","duration":"145.461883ms","start":"2026-10-16T14:43:53.490115Z","end":"2026-10-16T14:43:53.635577Z","steps":["trace[1488215566] 'agreement among raft nodes before linearized reading' (duration: 145.294929ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:45:10.195748Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5420a740f4813dc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T14:45:10.204431Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-16T14:45:10.204542Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-10-16T14:45:22.571713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.334756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:45:22.571862Z","caller":"traceutil/trace.go:171","msg":"trace[712972560] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:9734; }","duration":"111.566793ms","start":"2026-10-16T14:45:22.460269Z","end":"2026-10-16T14:45:22.571836Z","steps":["trace[712972560] 'count revisions from in-memory index tree' (duration: 111.243563ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:47:11.55891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8098} {"level":"info","ts":"2026-10-16T14:47:11.628742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8098,"took":"67.631059ms","hash":4200251841,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":28188672,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:47:11.628812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4200251841,"revision":8098,"compact-revision":5927} {"level":"info","ts":"2026-10-16T14:50:59.774339Z","caller":"traceutil/trace.go:171","msg":"trace[736957657] linearizableReadLoop","detail":"{readStateIndex:12578; appliedIndex:12577; }","duration":"119.303366ms","start":"2026-10-16T14:50:59.655005Z","end":"2026-10-16T14:50:59.774309Z","steps":["trace[736957657] 'read index received' (duration: 119.129101ms)","trace[736957657] 'applied index is now lower than readState.Index' (duration: 172.595µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:50:59.774456Z","caller":"traceutil/trace.go:171","msg":"trace[1544126613] transaction","detail":"{read_only:false; response_revision:12157; number_of_response:1; }","duration":"198.06012ms","start":"2026-10-16T14:50:59.576359Z","end":"2026-10-16T14:50:59.774419Z","steps":["trace[1544126613] 'process raft request' (duration: 197.773952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:50:59.774546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.533914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-16T14:50:59.774588Z","caller":"traceutil/trace.go:171","msg":"trace[1106618923] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:12157; }","duration":"119.655417ms","start":"2026-10-16T14:50:59.654921Z","end":"2026-10-16T14:50:59.774577Z","steps":["trace[1106618923] 'agreement among raft nodes before linearized reading' (duration: 119.550404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:50:59.810516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.434052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:50:59.810548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.703362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:50:59.810581Z","caller":"traceutil/trace.go:171","msg":"trace[1409156251] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12158; }","duration":"122.552515ms","start":"2026-10-16T14:50:59.688015Z","end":"2026-10-16T14:50:59.810567Z","steps":["trace[1409156251] 'agreement among raft nodes before linearized reading' (duration: 122.420971ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:50:59.810618Z","caller":"traceutil/trace.go:171","msg":"trace[548246731] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12158; }","duration":"121.792555ms","start":"2026-10-16T14:50:59.688805Z","end":"2026-10-16T14:50:59.810598Z","steps":["trace[548246731] 'agreement among raft nodes before linearized reading' (duration: 121.651551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:50:59.810678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.251107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-10-16T14:50:59.810665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.516234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:50:59.81071Z","caller":"traceutil/trace.go:171","msg":"trace[1719153024] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12158; }","duration":"122.339729ms","start":"2026-10-16T14:50:59.688356Z","end":"2026-10-16T14:50:59.810696Z","steps":["trace[1719153024] 'agreement among raft nodes before linearized reading' (duration: 122.237437ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:50:59.810556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.113133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:50:59.810767Z","caller":"traceutil/trace.go:171","msg":"trace[956079384] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12158; }","duration":"122.36252ms","start":"2026-10-16T14:50:59.68839Z","end":"2026-10-16T14:50:59.810753Z","steps":["trace[956079384] 'agreement among raft nodes before linearized reading' (duration: 122.088613ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:50:59.81076Z","caller":"traceutil/trace.go:171","msg":"trace[411114481] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:12158; }","duration":"122.675299ms","start":"2026-10-16T14:50:59.688061Z","end":"2026-10-16T14:50:59.810736Z","steps":["trace[411114481] 'agreement among raft nodes before linearized reading' (duration: 122.470873ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:50:59.810763Z","caller":"traceutil/trace.go:171","msg":"trace[404603864] transaction","detail":"{read_only:false; response_revision:12158; number_of_response:1; }","duration":"109.636854ms","start":"2026-10-16T14:50:59.701087Z","end":"2026-10-16T14:50:59.810724Z","steps":["trace[404603864] 'process raft request' (duration: 109.207083ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:52:11.565486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10478} {"level":"info","ts":"2026-10-16T14:52:11.651818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10478,"took":"84.033167ms","hash":3383886195,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":26079232,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-16T14:52:11.65188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3383886195,"revision":10478,"compact-revision":8098} {"level":"info","ts":"2026-10-16T14:57:05.659488Z","caller":"traceutil/trace.go:171","msg":"trace[276474615] transaction","detail":"{read_only:false; response_revision:14631; number_of_response:1; }","duration":"120.213627ms","start":"2026-10-16T14:57:05.539245Z","end":"2026-10-16T14:57:05.659459Z","steps":["trace[276474615] 'process raft request' (duration: 120.021411ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:57:09.01187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.348015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-10-16T14:57:09.01197Z","caller":"traceutil/trace.go:171","msg":"trace[367013874] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:14654; }","duration":"122.506239ms","start":"2026-10-16T14:57:08.889442Z","end":"2026-10-16T14:57:09.011948Z","steps":["trace[367013874] 'range keys from in-memory index tree' (duration: 122.090908ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:57:11.572484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12644} {"level":"info","ts":"2026-10-16T14:57:11.657719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12644,"took":"82.676843ms","hash":1514571246,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":27488256,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-16T14:57:11.657789Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1514571246,"revision":12644,"compact-revision":10478} {"level":"info","ts":"2026-10-16T14:57:30.179654Z","caller":"traceutil/trace.go:171","msg":"trace[1918445964] transaction","detail":"{read_only:false; response_revision:14766; number_of_response:1; }","duration":"104.60888ms","start":"2026-10-16T14:57:30.075019Z","end":"2026-10-16T14:57:30.179628Z","steps":["trace[1918445964] 'process raft request' (duration: 104.406945ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:57:47.486708Z","caller":"traceutil/trace.go:171","msg":"trace[226175314] transaction","detail":"{read_only:false; response_revision:14868; number_of_response:1; }","duration":"264.478127ms","start":"2026-10-16T14:57:47.222204Z","end":"2026-10-16T14:57:47.486682Z","steps":["trace[226175314] 'process raft request' (duration: 264.298282ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:57:49.632686Z","caller":"traceutil/trace.go:171","msg":"trace[744612182] transaction","detail":"{read_only:false; response_revision:14887; number_of_response:1; }","duration":"138.736322ms","start":"2026-10-16T14:57:49.493882Z","end":"2026-10-16T14:57:49.632619Z","steps":["trace[744612182] 'process raft request' (duration: 93.90905ms)","trace[744612182] 'compare' (duration: 44.631266ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:57:49.632729Z","caller":"traceutil/trace.go:171","msg":"trace[1245654458] transaction","detail":"{read_only:false; response_revision:14888; number_of_response:1; }","duration":"123.920278ms","start":"2026-10-16T14:57:49.508737Z","end":"2026-10-16T14:57:49.632657Z","steps":["trace[1245654458] 'process raft request' (duration: 123.824515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:10.923129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4452267024828433008,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-16T14:58:10.938075Z","caller":"traceutil/trace.go:171","msg":"trace[292171679] transaction","detail":"{read_only:false; response_revision:15182; number_of_response:1; }","duration":"516.716172ms","start":"2026-10-16T14:58:10.421333Z","end":"2026-10-16T14:58:10.938049Z","steps":["trace[292171679] 'process raft request' (duration: 516.561548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:10.938379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:58:10.421309Z","time spent":"516.925167ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T14:58:10.995535Z","caller":"traceutil/trace.go:171","msg":"trace[843946967] linearizableReadLoop","detail":"{readStateIndex:15714; appliedIndex:15711; }","duration":"572.935105ms","start":"2026-10-16T14:58:10.422574Z","end":"2026-10-16T14:58:10.995509Z","steps":["trace[843946967] 'read index received' (duration: 515.357006ms)","trace[843946967] 'applied index is now lower than readState.Index' (duration: 57.577069ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:58:10.995626Z","caller":"traceutil/trace.go:171","msg":"trace[811603919] transaction","detail":"{read_only:false; response_revision:15183; number_of_response:1; }","duration":"458.143696ms","start":"2026-10-16T14:58:10.537438Z","end":"2026-10-16T14:58:10.995582Z","steps":["trace[811603919] 'process raft request' (duration: 457.952341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:10.995789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.189042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T14:58:10.99583Z","caller":"traceutil/trace.go:171","msg":"trace[556099372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15183; }","duration":"573.284895ms","start":"2026-10-16T14:58:10.422533Z","end":"2026-10-16T14:58:10.995818Z","steps":["trace[556099372] 'agreement among raft nodes before linearized reading' (duration: 573.09839ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:10.995833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:58:10.537408Z","time spent":"458.319291ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T14:58:10.995862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:58:10.42252Z","time spent":"573.334256ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-16T14:58:10.999659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.152928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-16T14:58:10.99969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.425965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-16T14:58:10.999732Z","caller":"traceutil/trace.go:171","msg":"trace[159918384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15184; }","duration":"570.225259ms","start":"2026-10-16T14:58:10.429492Z","end":"2026-10-16T14:58:10.999718Z","steps":["trace[159918384] 'agreement among raft nodes before linearized reading' (duration: 570.073955ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:10.999762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:58:10.429462Z","time spent":"570.293651ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-16T14:58:10.999823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.219708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"warn","ts":"2026-10-16T14:58:10.99985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.195568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7fc31de-fd7f-4d2e-a8d0-a1a796221ba8\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-10-16T14:58:10.999918Z","caller":"traceutil/trace.go:171","msg":"trace[173596899] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:15184; }","duration":"218.358291ms","start":"2026-10-16T14:58:10.781539Z","end":"2026-10-16T14:58:10.999897Z","steps":["trace[173596899] 'agreement among raft nodes before linearized reading' (duration: 218.093684ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:58:10.999925Z","caller":"traceutil/trace.go:171","msg":"trace[2136255381] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7fc31de-fd7f-4d2e-a8d0-a1a796221ba8; range_end:; response_count:1; response_revision:15184; }","duration":"461.302622ms","start":"2026-10-16T14:58:10.538602Z","end":"2026-10-16T14:58:10.999905Z","steps":["trace[2136255381] 'agreement among raft nodes before linearized reading' (duration: 461.106856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:10.999967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.683541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7fc31de-fd7f-4d2e-a8d0-a1a796221ba8\" ","response":"range_response_count:1 size:3637"} {"level":"warn","ts":"2026-10-16T14:58:10.999986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:58:10.538587Z","time spent":"461.371013ms","remote":"127.0.0.1:44472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3659,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7fc31de-fd7f-4d2e-a8d0-a1a796221ba8\" "} {"level":"info","ts":"2026-10-16T14:58:11.000008Z","caller":"traceutil/trace.go:171","msg":"trace[930606493] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7fc31de-fd7f-4d2e-a8d0-a1a796221ba8; range_end:; response_count:1; response_revision:15184; }","duration":"483.718412ms","start":"2026-10-16T14:58:10.516275Z","end":"2026-10-16T14:58:10.999993Z","steps":["trace[930606493] 'agreement among raft nodes before linearized reading' (duration: 483.618679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:11.000032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T14:58:10.516257Z","time spent":"483.769714ms","remote":"127.0.0.1:44472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3659,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7fc31de-fd7f-4d2e-a8d0-a1a796221ba8\" "} {"level":"warn","ts":"2026-10-16T14:58:11.000221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.688311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ztsck\" ","response":"range_response_count:1 size:20791"} {"level":"info","ts":"2026-10-16T14:58:11.000339Z","caller":"traceutil/trace.go:171","msg":"trace[1417417128] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-ztsck; range_end:; response_count:1; response_revision:15184; }","duration":"192.846356ms","start":"2026-10-16T14:58:10.807471Z","end":"2026-10-16T14:58:11.000318Z","steps":["trace[1417417128] 'agreement among raft nodes before linearized reading' (duration: 192.406333ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:58:11.000362Z","caller":"traceutil/trace.go:171","msg":"trace[2082037176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15184; }","duration":"148.570689ms","start":"2026-10-16T14:58:10.85118Z","end":"2026-10-16T14:58:10.99975Z","steps":["trace[2082037176] 'agreement among raft nodes before linearized reading' (duration: 148.430156ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:58:11.107176Z","caller":"traceutil/trace.go:171","msg":"trace[1691705087] transaction","detail":"{read_only:false; response_revision:15186; number_of_response:1; }","duration":"101.98455ms","start":"2026-10-16T14:58:11.005152Z","end":"2026-10-16T14:58:11.107137Z","steps":["trace[1691705087] 'process raft request' (duration: 101.561789ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:11.107859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.412111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:633236"} {"level":"info","ts":"2026-10-16T14:58:11.107984Z","caller":"traceutil/trace.go:171","msg":"trace[236688513] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:15187; }","duration":"102.562066ms","start":"2026-10-16T14:58:11.005381Z","end":"2026-10-16T14:58:11.107943Z","steps":["trace[236688513] 'agreement among raft nodes before linearized reading' (duration: 101.60515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:58:11.107984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.793757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:633236"} {"level":"info","ts":"2026-10-16T14:58:11.108061Z","caller":"traceutil/trace.go:171","msg":"trace[422714275] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:15187; }","duration":"100.90258ms","start":"2026-10-16T14:58:11.007138Z","end":"2026-10-16T14:58:11.108041Z","steps":["trace[422714275] 'agreement among raft nodes before linearized reading' (duration: 100.011356ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:59:38.655243Z","caller":"traceutil/trace.go:171","msg":"trace[1991732433] transaction","detail":"{read_only:false; response_revision:16001; number_of_response:1; }","duration":"130.148102ms","start":"2026-10-16T14:59:38.525058Z","end":"2026-10-16T14:59:38.655206Z","steps":["trace[1991732433] 'process raft request' (duration: 129.938616ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:59:38.892259Z","caller":"traceutil/trace.go:171","msg":"trace[1713260901] transaction","detail":"{read_only:false; response_revision:16003; number_of_response:1; }","duration":"161.544486ms","start":"2026-10-16T14:59:38.73068Z","end":"2026-10-16T14:59:38.892224Z","steps":["trace[1713260901] 'process raft request' (duration: 123.347556ms)","trace[1713260901] 'compare' (duration: 38.006765ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:59:39.4528Z","caller":"traceutil/trace.go:171","msg":"trace[300885274] transaction","detail":"{read_only:false; response_revision:16004; number_of_response:1; }","duration":"114.400484ms","start":"2026-10-16T14:59:39.338358Z","end":"2026-10-16T14:59:39.452759Z","steps":["trace[300885274] 'process raft request' (duration: 114.138337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:39.717924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.823838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-16T14:59:39.718165Z","caller":"traceutil/trace.go:171","msg":"trace[1296081365] transaction","detail":"{read_only:false; response_revision:16005; number_of_response:1; }","duration":"252.625455ms","start":"2026-10-16T14:59:39.46551Z","end":"2026-10-16T14:59:39.718136Z","steps":["trace[1296081365] 'process raft request' (duration: 127.50191ms)","trace[1296081365] 'compare' (duration: 123.86094ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T14:59:39.718235Z","caller":"traceutil/trace.go:171","msg":"trace[635980304] linearizableReadLoop","detail":"{readStateIndex:16558; appliedIndex:16557; }","duration":"203.285053ms","start":"2026-10-16T14:59:39.514927Z","end":"2026-10-16T14:59:39.718212Z","steps":["trace[635980304] 'read index received' (duration: 78.002733ms)","trace[635980304] 'applied index is now lower than readState.Index' (duration: 125.2797ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T14:59:39.718428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.318107ms","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-16T14:59:39.718463Z","caller":"traceutil/trace.go:171","msg":"trace[1564661626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16005; }","duration":"180.400359ms","start":"2026-10-16T14:59:39.538051Z","end":"2026-10-16T14:59:39.718451Z","steps":["trace[1564661626] 'agreement among raft nodes before linearized reading' (duration: 180.276225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T14:59:39.718474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.411354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-16T14:59:39.718507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.55409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-16T14:59:39.718593Z","caller":"traceutil/trace.go:171","msg":"trace[1720568503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16005; }","duration":"203.705284ms","start":"2026-10-16T14:59:39.514868Z","end":"2026-10-16T14:59:39.718573Z","steps":["trace[1720568503] 'agreement among raft nodes before linearized reading' (duration: 203.452657ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T14:59:39.718756Z","caller":"traceutil/trace.go:171","msg":"trace[715762821] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16005; }","duration":"157.565778ms","start":"2026-10-16T14:59:39.560996Z","end":"2026-10-16T14:59:39.718562Z","steps":["trace[715762821] 'agreement among raft nodes before linearized reading' (duration: 157.395003ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:02:11.580982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14668} {"level":"info","ts":"2026-10-16T15:02:11.668183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14668,"took":"85.105997ms","hash":1188517221,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":31821824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T15:02:11.668348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1188517221,"revision":14668,"compact-revision":12644} {"level":"info","ts":"2026-10-16T15:05:43.110479Z","caller":"traceutil/trace.go:171","msg":"trace[2121081177] transaction","detail":"{read_only:false; response_revision:18740; number_of_response:1; }","duration":"101.060102ms","start":"2026-10-16T15:05:43.009371Z","end":"2026-10-16T15:05:43.110431Z","steps":["trace[2121081177] 'process raft request' (duration: 100.738872ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:05:50.477477Z","caller":"traceutil/trace.go:171","msg":"trace[1482899308] transaction","detail":"{read_only:false; response_revision:18786; number_of_response:1; }","duration":"272.318634ms","start":"2026-10-16T15:05:50.205124Z","end":"2026-10-16T15:05:50.477442Z","steps":["trace[1482899308] 'process raft request' (duration: 272.148259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:05:50.917376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.129296ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-16T15:05:50.917508Z","caller":"traceutil/trace.go:171","msg":"trace[1674067965] linearizableReadLoop","detail":"{readStateIndex:19430; appliedIndex:19429; }","duration":"220.38357ms","start":"2026-10-16T15:05:50.697104Z","end":"2026-10-16T15:05:50.917488Z","steps":["trace[1674067965] 'read index received' (duration: 56.292µs)","trace[1674067965] 'applied index is now lower than readState.Index' (duration: 220.323338ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:05:50.918041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.929535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T15:05:50.918129Z","caller":"traceutil/trace.go:171","msg":"trace[605273038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18786; }","duration":"221.073859ms","start":"2026-10-16T15:05:50.697037Z","end":"2026-10-16T15:05:50.918111Z","steps":["trace[605273038] 'agreement among raft nodes before linearized reading' (duration: 220.841333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:05:50.925356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.669457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T15:05:50.925455Z","caller":"traceutil/trace.go:171","msg":"trace[1994948073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18787; }","duration":"181.81259ms","start":"2026-10-16T15:05:50.743619Z","end":"2026-10-16T15:05:50.925432Z","steps":["trace[1994948073] 'agreement among raft nodes before linearized reading' (duration: 181.563684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:05:51.39804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.289998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-16T15:05:51.398152Z","caller":"traceutil/trace.go:171","msg":"trace[1146425266] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:18789; }","duration":"274.473893ms","start":"2026-10-16T15:05:51.123648Z","end":"2026-10-16T15:05:51.398122Z","steps":["trace[1146425266] 'count revisions from in-memory index tree' (duration: 274.161345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:05:51.398233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.73788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-ds2jg\" ","response":"range_response_count:1 size:22101"} {"level":"info","ts":"2026-10-16T15:05:51.398359Z","caller":"traceutil/trace.go:171","msg":"trace[258262166] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-ds2jg; range_end:; response_count:1; response_revision:18789; }","duration":"126.875664ms","start":"2026-10-16T15:05:51.271457Z","end":"2026-10-16T15:05:51.398332Z","steps":["trace[258262166] 'range keys from in-memory index tree' (duration: 126.496424ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:05:51.613332Z","caller":"traceutil/trace.go:171","msg":"trace[1935673731] transaction","detail":"{read_only:false; response_revision:18790; number_of_response:1; }","duration":"164.507619ms","start":"2026-10-16T15:05:51.448789Z","end":"2026-10-16T15:05:51.613297Z","steps":["trace[1935673731] 'process raft request' (duration: 164.330254ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:05:51.67851Z","caller":"traceutil/trace.go:171","msg":"trace[420265802] linearizableReadLoop","detail":"{readStateIndex:19435; appliedIndex:19433; }","duration":"182.421177ms","start":"2026-10-16T15:05:51.496066Z","end":"2026-10-16T15:05:51.678487Z","steps":["trace[420265802] 'read index received' (duration: 117.036847ms)","trace[420265802] 'applied index is now lower than readState.Index' (duration: 65.38341ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:05:51.678753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.652412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:05:51.678758Z","caller":"traceutil/trace.go:171","msg":"trace[1743721237] transaction","detail":"{read_only:false; response_revision:18791; number_of_response:1; }","duration":"217.639446ms","start":"2026-10-16T15:05:51.461027Z","end":"2026-10-16T15:05:51.678666Z","steps":["trace[1743721237] 'process raft request' (duration: 217.361299ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:05:51.67883Z","caller":"traceutil/trace.go:171","msg":"trace[838080323] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:18791; }","duration":"182.808577ms","start":"2026-10-16T15:05:51.49601Z","end":"2026-10-16T15:05:51.678819Z","steps":["trace[838080323] 'agreement among raft nodes before linearized reading' (duration: 182.604962ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:06:33.785457Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5420a740f4813dc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:06:33.794075Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-16T15:06:33.794193Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-16T15:07:11.590809Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17090} {"level":"info","ts":"2026-10-16T15:07:11.688209Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17090,"took":"93.907458ms","hash":2239363250,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":33812480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-16T15:07:11.688278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2239363250,"revision":17090,"compact-revision":14668} {"level":"info","ts":"2026-10-16T15:12:00.474346Z","caller":"traceutil/trace.go:171","msg":"trace[1943203077] transaction","detail":"{read_only:false; response_revision:21611; number_of_response:1; }","duration":"166.291198ms","start":"2026-10-16T15:12:00.308028Z","end":"2026-10-16T15:12:00.474319Z","steps":["trace[1943203077] 'process raft request' (duration: 166.112513ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:12:00.496957Z","caller":"traceutil/trace.go:171","msg":"trace[726913790] transaction","detail":"{read_only:false; response_revision:21612; number_of_response:1; }","duration":"174.197462ms","start":"2026-10-16T15:12:00.322723Z","end":"2026-10-16T15:12:00.49692Z","steps":["trace[726913790] 'process raft request' (duration: 174.022907ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:00.96852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.21ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-16T15:12:00.96862Z","caller":"traceutil/trace.go:171","msg":"trace[867068887] linearizableReadLoop","detail":"{readStateIndex:22350; appliedIndex:22349; }","duration":"364.71514ms","start":"2026-10-16T15:12:00.603887Z","end":"2026-10-16T15:12:00.968602Z","steps":["trace[867068887] 'read index received' (duration: 41.422568ms)","trace[867068887] 'applied index is now lower than readState.Index' (duration: 323.291152ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:12:00.968925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.89103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T15:12:00.968986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.693189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T15:12:00.969032Z","caller":"traceutil/trace.go:171","msg":"trace[1223893908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21612; }","duration":"173.780791ms","start":"2026-10-16T15:12:00.795238Z","end":"2026-10-16T15:12:00.969019Z","steps":["trace[1223893908] 'agreement among raft nodes before linearized reading' (duration: 173.610827ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:12:00.969044Z","caller":"traceutil/trace.go:171","msg":"trace[114763331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21612; }","duration":"118.121126ms","start":"2026-10-16T15:12:00.850896Z","end":"2026-10-16T15:12:00.969017Z","steps":["trace[114763331] 'agreement among raft nodes before linearized reading' (duration: 117.932631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:00.969345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.263855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T15:12:00.969429Z","caller":"traceutil/trace.go:171","msg":"trace[911845089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21612; }","duration":"195.381539ms","start":"2026-10-16T15:12:00.774023Z","end":"2026-10-16T15:12:00.969405Z","steps":["trace[911845089] 'agreement among raft nodes before linearized reading' (duration: 194.793143ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:00.970223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.300873ms","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-16T15:12:00.970323Z","caller":"traceutil/trace.go:171","msg":"trace[1172223938] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:21612; }","duration":"366.452628ms","start":"2026-10-16T15:12:00.603848Z","end":"2026-10-16T15:12:00.9703Z","steps":["trace[1172223938] 'agreement among raft nodes before linearized reading' (duration: 364.950966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:00.970377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:12:00.603829Z","time spent":"366.532729ms","remote":"127.0.0.1:44358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2180,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-10-16T15:12:01.138142Z","caller":"traceutil/trace.go:171","msg":"trace[1219907032] transaction","detail":"{read_only:false; response_revision:21614; number_of_response:1; }","duration":"163.944504ms","start":"2026-10-16T15:12:00.974171Z","end":"2026-10-16T15:12:01.138116Z","steps":["trace[1219907032] 'process raft request' (duration: 163.670256ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:12:01.466454Z","caller":"traceutil/trace.go:171","msg":"trace[1583500394] transaction","detail":"{read_only:false; response_revision:21615; number_of_response:1; }","duration":"359.155089ms","start":"2026-10-16T15:12:01.107271Z","end":"2026-10-16T15:12:01.466426Z","steps":["trace[1583500394] 'process raft request' (duration: 358.950603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:01.466623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:12:01.107245Z","time spent":"359.289473ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:12:01.466852Z","caller":"traceutil/trace.go:171","msg":"trace[786636460] transaction","detail":"{read_only:false; response_revision:21616; number_of_response:1; }","duration":"358.204053ms","start":"2026-10-16T15:12:01.108616Z","end":"2026-10-16T15:12:01.46682Z","steps":["trace[786636460] 'process raft request' (duration: 357.759141ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:12:01.466937Z","caller":"traceutil/trace.go:171","msg":"trace[2146813539] transaction","detail":"{read_only:false; response_revision:21617; number_of_response:1; }","duration":"331.390973ms","start":"2026-10-16T15:12:01.135522Z","end":"2026-10-16T15:12:01.466913Z","steps":["trace[2146813539] 'process raft request' (duration: 331.130186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:01.466991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.724617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-16T15:12:01.467012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:12:01.1086Z","time spent":"358.331476ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:12:01.46705Z","caller":"traceutil/trace.go:171","msg":"trace[1791921894] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:21617; }","duration":"120.84091ms","start":"2026-10-16T15:12:01.346191Z","end":"2026-10-16T15:12:01.467032Z","steps":["trace[1791921894] 'agreement among raft nodes before linearized reading' (duration: 120.725917ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:01.467078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:12:01.135496Z","time spent":"331.498186ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:12:01.466853Z","caller":"traceutil/trace.go:171","msg":"trace[486739473] linearizableReadLoop","detail":"{readStateIndex:22355; appliedIndex:22352; }","duration":"120.578313ms","start":"2026-10-16T15:12:01.34624Z","end":"2026-10-16T15:12:01.466819Z","steps":["trace[486739473] 'read index received' (duration: 119.922296ms)","trace[486739473] 'applied index is now lower than readState.Index' (duration: 654.757µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:12:01.467276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.629177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12430"} {"level":"info","ts":"2026-10-16T15:12:01.467316Z","caller":"traceutil/trace.go:171","msg":"trace[472159463] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21617; }","duration":"101.691629ms","start":"2026-10-16T15:12:01.365613Z","end":"2026-10-16T15:12:01.467305Z","steps":["trace[472159463] 'agreement among raft nodes before linearized reading' (duration: 101.565286ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:12:01.678953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.118777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:6012"} {"level":"info","ts":"2026-10-16T15:12:01.679038Z","caller":"traceutil/trace.go:171","msg":"trace[1405866919] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21617; }","duration":"114.239161ms","start":"2026-10-16T15:12:01.564776Z","end":"2026-10-16T15:12:01.679015Z","steps":["trace[1405866919] 'range keys from in-memory index tree' (duration: 113.949973ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:12:11.597652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19643} {"level":"info","ts":"2026-10-16T15:12:11.723299Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19643,"took":"122.071013ms","hash":3140667291,"current-db-size-bytes":57212928,"current-db-size":"57 MB","current-db-size-in-use-bytes":29933568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:12:11.723362Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3140667291,"revision":19643,"compact-revision":17090} {"level":"info","ts":"2026-10-16T15:16:12.587643Z","caller":"traceutil/trace.go:171","msg":"trace[1573743098] linearizableReadLoop","detail":"{readStateIndex:24989; appliedIndex:24988; }","duration":"255.885071ms","start":"2026-10-16T15:16:12.331727Z","end":"2026-10-16T15:16:12.587612Z","steps":["trace[1573743098] 'read index received' (duration: 255.748857ms)","trace[1573743098] 'applied index is now lower than readState.Index' (duration: 135.163µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:16:12.587731Z","caller":"traceutil/trace.go:171","msg":"trace[1987832517] transaction","detail":"{read_only:false; response_revision:24178; number_of_response:1; }","duration":"286.707428ms","start":"2026-10-16T15:16:12.300996Z","end":"2026-10-16T15:16:12.587704Z","steps":["trace[1987832517] 'process raft request' (duration: 286.42382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:16:12.58802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.277131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12430"} {"level":"info","ts":"2026-10-16T15:16:12.588105Z","caller":"traceutil/trace.go:171","msg":"trace[205296584] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24178; }","duration":"256.415655ms","start":"2026-10-16T15:16:12.331671Z","end":"2026-10-16T15:16:12.588087Z","steps":["trace[205296584] 'agreement among raft nodes before linearized reading' (duration: 256.086196ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:16:12.601303Z","caller":"traceutil/trace.go:171","msg":"trace[1233736566] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"103.700143ms","start":"2026-10-16T15:16:12.497576Z","end":"2026-10-16T15:16:12.601276Z","steps":["trace[1233736566] 'process raft request' (duration: 103.421315ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:16:12.601541Z","caller":"traceutil/trace.go:171","msg":"trace[848131213] transaction","detail":"{read_only:false; response_revision:24180; number_of_response:1; }","duration":"102.953123ms","start":"2026-10-16T15:16:12.498554Z","end":"2026-10-16T15:16:12.601507Z","steps":["trace[848131213] 'process raft request' (duration: 102.588343ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:16:12.602141Z","caller":"traceutil/trace.go:171","msg":"trace[2102397446] transaction","detail":"{read_only:false; response_revision:24181; number_of_response:1; }","duration":"100.495495ms","start":"2026-10-16T15:16:12.501624Z","end":"2026-10-16T15:16:12.602119Z","steps":["trace[2102397446] 'process raft request' (duration: 99.627922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:16:12.602424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.270869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T15:16:12.602478Z","caller":"traceutil/trace.go:171","msg":"trace[77392806] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24182; }","duration":"176.365382ms","start":"2026-10-16T15:16:12.426099Z","end":"2026-10-16T15:16:12.602465Z","steps":["trace[77392806] 'agreement among raft nodes before linearized reading' (duration: 176.168736ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:17:11.603701Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21668} {"level":"info","ts":"2026-10-16T15:17:11.719867Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21668,"took":"112.510085ms","hash":3506416723,"current-db-size-bytes":58056704,"current-db-size":"58 MB","current-db-size-in-use-bytes":42774528,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-16T15:17:11.719942Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3506416723,"revision":21668,"compact-revision":19643} {"level":"info","ts":"2026-10-16T15:18:40.164472Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000677a.wal"} {"level":"info","ts":"2026-10-16T15:19:50.832108Z","caller":"traceutil/trace.go:171","msg":"trace[2001042764] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"154.57102ms","start":"2026-10-16T15:19:50.677513Z","end":"2026-10-16T15:19:50.832084Z","steps":["trace[2001042764] 'process raft request' (duration: 154.429066ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:19:51.61086Z","caller":"traceutil/trace.go:171","msg":"trace[1309487552] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"121.88039ms","start":"2026-10-16T15:19:51.488958Z","end":"2026-10-16T15:19:51.610839Z","steps":["trace[1309487552] 'process raft request' (duration: 121.687724ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:19:51.963573Z","caller":"traceutil/trace.go:171","msg":"trace[524973813] transaction","detail":"{read_only:false; response_revision:26282; number_of_response:1; }","duration":"459.224656ms","start":"2026-10-16T15:19:51.504324Z","end":"2026-10-16T15:19:51.963549Z","steps":["trace[524973813] 'process raft request' (duration: 459.00628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:19:51.963743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:19:51.504313Z","time spent":"459.333539ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:19:51.963831Z","caller":"traceutil/trace.go:171","msg":"trace[1991569003] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"417.603124ms","start":"2026-10-16T15:19:51.546212Z","end":"2026-10-16T15:19:51.963815Z","steps":["trace[1991569003] 'process raft request' (duration: 417.276755ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:19:51.963918Z","caller":"traceutil/trace.go:171","msg":"trace[2022897424] linearizableReadLoop","detail":"{readStateIndex:27147; appliedIndex:27144; }","duration":"401.369072ms","start":"2026-10-16T15:19:51.562538Z","end":"2026-10-16T15:19:51.963907Z","steps":["trace[2022897424] 'read index received' (duration: 48.186383ms)","trace[2022897424] 'applied index is now lower than readState.Index' (duration: 353.181919ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:19:51.963923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:19:51.546198Z","time spent":"417.677576ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-16T15:19:51.964093Z","caller":"traceutil/trace.go:171","msg":"trace[821208387] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"355.939593ms","start":"2026-10-16T15:19:51.60814Z","end":"2026-10-16T15:19:51.96408Z","steps":["trace[821208387] 'process raft request' (duration: 355.613275ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:19:51.964119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.516906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T15:19:51.964154Z","caller":"traceutil/trace.go:171","msg":"trace[1818069800] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26284; }","duration":"263.594559ms","start":"2026-10-16T15:19:51.700546Z","end":"2026-10-16T15:19:51.964141Z","steps":["trace[1818069800] 'agreement among raft nodes before linearized reading' (duration: 263.472836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:19:51.964176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:19:51.608116Z","time spent":"356.013966ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:19:51.964334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.246708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T15:19:51.964437Z","caller":"traceutil/trace.go:171","msg":"trace[1539238581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26284; }","duration":"253.391131ms","start":"2026-10-16T15:19:51.711017Z","end":"2026-10-16T15:19:51.964408Z","steps":["trace[1539238581] 'agreement among raft nodes before linearized reading' (duration: 253.046312ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:19:51.964294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.582378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:19:51.964631Z","caller":"traceutil/trace.go:171","msg":"trace[247470899] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:26284; }","duration":"402.03835ms","start":"2026-10-16T15:19:51.562525Z","end":"2026-10-16T15:19:51.964563Z","steps":["trace[247470899] 'agreement among raft nodes before linearized reading' (duration: 401.491896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:19:51.964965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:19:51.562466Z","time spent":"402.473852ms","remote":"127.0.0.1:60712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"info","ts":"2026-10-16T15:20:54.419216Z","caller":"traceutil/trace.go:171","msg":"trace[49840778] linearizableReadLoop","detail":"{readStateIndex:27761; appliedIndex:27758; }","duration":"111.895513ms","start":"2026-10-16T15:20:54.307296Z","end":"2026-10-16T15:20:54.419192Z","steps":["trace[49840778] 'read index received' (duration: 111.639906ms)","trace[49840778] 'applied index is now lower than readState.Index' (duration: 254.587µs)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:20:54.419603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.894563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-16T15:20:54.419589Z","caller":"traceutil/trace.go:171","msg":"trace[77571153] transaction","detail":"{read_only:false; response_revision:26882; number_of_response:1; }","duration":"209.725241ms","start":"2026-10-16T15:20:54.209802Z","end":"2026-10-16T15:20:54.419528Z","steps":["trace[77571153] 'process raft request' (duration: 209.29038ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:54.419646Z","caller":"traceutil/trace.go:171","msg":"trace[1041972724] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26883; }","duration":"107.971885ms","start":"2026-10-16T15:20:54.311663Z","end":"2026-10-16T15:20:54.419635Z","steps":["trace[1041972724] 'agreement among raft nodes before linearized reading' (duration: 107.824351ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:54.419626Z","caller":"traceutil/trace.go:171","msg":"trace[1599717007] transaction","detail":"{read_only:false; response_revision:26883; number_of_response:1; }","duration":"198.253699ms","start":"2026-10-16T15:20:54.221352Z","end":"2026-10-16T15:20:54.419606Z","steps":["trace[1599717007] 'process raft request' (duration: 197.793317ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:54.419604Z","caller":"traceutil/trace.go:171","msg":"trace[25406405] transaction","detail":"{read_only:false; response_revision:26881; number_of_response:1; }","duration":"214.162422ms","start":"2026-10-16T15:20:54.205403Z","end":"2026-10-16T15:20:54.419565Z","steps":["trace[25406405] 'process raft request' (duration: 213.550016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:20:54.420011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.686225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-16T15:20:54.420086Z","caller":"traceutil/trace.go:171","msg":"trace[1347952607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26883; }","duration":"112.816368ms","start":"2026-10-16T15:20:54.307251Z","end":"2026-10-16T15:20:54.420067Z","steps":["trace[1347952607] 'agreement among raft nodes before linearized reading' (duration: 112.593682ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:54.591952Z","caller":"traceutil/trace.go:171","msg":"trace[544840100] transaction","detail":"{read_only:false; response_revision:26885; number_of_response:1; }","duration":"166.983036ms","start":"2026-10-16T15:20:54.424935Z","end":"2026-10-16T15:20:54.591918Z","steps":["trace[544840100] 'process raft request' (duration: 166.847412ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:54.592113Z","caller":"traceutil/trace.go:171","msg":"trace[191766057] transaction","detail":"{read_only:false; response_revision:26884; number_of_response:1; }","duration":"167.357846ms","start":"2026-10-16T15:20:54.424726Z","end":"2026-10-16T15:20:54.592084Z","steps":["trace[191766057] 'process raft request' (duration: 150.940108ms)","trace[191766057] 'compare' (duration: 15.907015ms)"],"step_count":2} {"level":"warn","ts":"2026-10-16T15:20:54.960809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.536699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-16T15:20:54.961056Z","caller":"traceutil/trace.go:171","msg":"trace[1388858723] transaction","detail":"{read_only:false; response_revision:26887; number_of_response:1; }","duration":"385.436256ms","start":"2026-10-16T15:20:54.575572Z","end":"2026-10-16T15:20:54.961009Z","steps":["trace[1388858723] 'process raft request' (duration: 161.533397ms)","trace[1388858723] 'compare' (duration: 222.84895ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:20:54.961111Z","caller":"traceutil/trace.go:171","msg":"trace[35463974] linearizableReadLoop","detail":"{readStateIndex:27768; appliedIndex:27764; }","duration":"273.601844ms","start":"2026-10-16T15:20:54.687492Z","end":"2026-10-16T15:20:54.961094Z","steps":["trace[35463974] 'read index received' (duration: 49.630724ms)","trace[35463974] 'applied index is now lower than readState.Index' (duration: 223.97021ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:20:54.961206Z","caller":"traceutil/trace.go:171","msg":"trace[299949944] transaction","detail":"{read_only:false; response_revision:26888; number_of_response:1; }","duration":"364.233327ms","start":"2026-10-16T15:20:54.596962Z","end":"2026-10-16T15:20:54.961195Z","steps":["trace[299949944] 'process raft request' (duration: 363.958469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:20:54.96125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:20:54.575541Z","time spent":"385.606529ms","remote":"127.0.0.1:44558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-16T15:20:54.961271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T15:20:54.596939Z","time spent":"364.29698ms","remote":"127.0.0.1:44558","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-16T15:20:54.96136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.853621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-16T15:20:54.961401Z","caller":"traceutil/trace.go:171","msg":"trace[1848784045] transaction","detail":"{read_only:false; response_revision:26889; number_of_response:1; }","duration":"293.554219ms","start":"2026-10-16T15:20:54.667836Z","end":"2026-10-16T15:20:54.961391Z","steps":["trace[1848784045] 'process raft request' (duration: 293.189918ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:54.961454Z","caller":"traceutil/trace.go:171","msg":"trace[1332691080] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26890; }","duration":"273.996705ms","start":"2026-10-16T15:20:54.687434Z","end":"2026-10-16T15:20:54.961431Z","steps":["trace[1332691080] 'agreement among raft nodes before linearized reading' (duration: 273.854681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:20:54.961615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.629437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-16T15:20:54.961649Z","caller":"traceutil/trace.go:171","msg":"trace[1677939306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26890; }","duration":"221.707249ms","start":"2026-10-16T15:20:54.73993Z","end":"2026-10-16T15:20:54.961637Z","steps":["trace[1677939306] 'agreement among raft nodes before linearized reading' (duration: 221.555494ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:54.961652Z","caller":"traceutil/trace.go:171","msg":"trace[759571022] transaction","detail":"{read_only:false; response_revision:26890; number_of_response:1; }","duration":"285.039507ms","start":"2026-10-16T15:20:54.676601Z","end":"2026-10-16T15:20:54.961641Z","steps":["trace[759571022] 'process raft request' (duration: 284.45758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:20:54.961809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.670407ms","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-16T15:20:54.961877Z","caller":"traceutil/trace.go:171","msg":"trace[848537573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26890; }","duration":"221.785581ms","start":"2026-10-16T15:20:54.740073Z","end":"2026-10-16T15:20:54.961859Z","steps":["trace[848537573] 'agreement among raft nodes before linearized reading' (duration: 221.561694ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:55.152492Z","caller":"traceutil/trace.go:171","msg":"trace[2134231806] transaction","detail":"{read_only:false; response_revision:26892; number_of_response:1; }","duration":"184.77153ms","start":"2026-10-16T15:20:54.967697Z","end":"2026-10-16T15:20:55.152469Z","steps":["trace[2134231806] 'process raft request' (duration: 184.684358ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:20:55.152586Z","caller":"traceutil/trace.go:171","msg":"trace[2134314438] transaction","detail":"{read_only:false; response_revision:26891; number_of_response:1; }","duration":"186.655472ms","start":"2026-10-16T15:20:54.965888Z","end":"2026-10-16T15:20:55.152543Z","steps":["trace[2134314438] 'process raft request' (duration: 155.145313ms)","trace[2134314438] 'compare' (duration: 31.084138ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:20:55.514647Z","caller":"traceutil/trace.go:171","msg":"trace[1638921207] transaction","detail":"{read_only:false; response_revision:26894; number_of_response:1; }","duration":"189.88262ms","start":"2026-10-16T15:20:55.324733Z","end":"2026-10-16T15:20:55.514616Z","steps":["trace[1638921207] 'process raft request' (duration: 158.001351ms)","trace[1638921207] 'compare' (duration: 31.717565ms)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:22:11.611019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24769} {"level":"info","ts":"2026-10-16T15:22:11.777753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24769,"took":"162.781741ms","hash":2140712379,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":35688448,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-16T15:22:11.77784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140712379,"revision":24769,"compact-revision":21668} {"level":"info","ts":"2026-10-16T15:22:58.498681Z","caller":"traceutil/trace.go:171","msg":"trace[1732932544] linearizableReadLoop","detail":"{readStateIndex:28980; appliedIndex:28979; }","duration":"109.773095ms","start":"2026-10-16T15:22:58.388884Z","end":"2026-10-16T15:22:58.498657Z","steps":["trace[1732932544] 'read index received' (duration: 109.488867ms)","trace[1732932544] 'applied index is now lower than readState.Index' (duration: 283.338µs)"],"step_count":2} {"level":"info","ts":"2026-10-16T15:22:58.498755Z","caller":"traceutil/trace.go:171","msg":"trace[492601051] transaction","detail":"{read_only:false; response_revision:28073; number_of_response:1; }","duration":"110.929535ms","start":"2026-10-16T15:22:58.38778Z","end":"2026-10-16T15:22:58.49871Z","steps":["trace[492601051] 'process raft request' (duration: 110.651208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-16T15:22:58.499758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.847465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21419"} {"level":"info","ts":"2026-10-16T15:22:58.499852Z","caller":"traceutil/trace.go:171","msg":"trace[443664763] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:28073; }","duration":"110.969067ms","start":"2026-10-16T15:22:58.388862Z","end":"2026-10-16T15:22:58.499831Z","steps":["trace[443664763] 'agreement among raft nodes before linearized reading' (duration: 109.960729ms)"],"step_count":1} {"level":"info","ts":"2026-10-16T15:24:43.405849Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5420a740f4813dc9","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:24:43.418762Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-16T15:24:43.418929Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-16T15:27:11.616792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27632} {"level":"info","ts":"2026-10-16T15:27:11.740832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27632,"took":"121.123014ms","hash":603820412,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":36810752,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-16T15:27:11.740914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":603820412,"revision":27632,"compact-revision":24769} {"level":"info","ts":"2026-10-16T15:32:11.628413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30331} {"level":"info","ts":"2026-10-16T15:32:11.773157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30331,"took":"140.832927ms","hash":2489860687,"current-db-size-bytes":58900480,"current-db-size":"59 MB","current-db-size-in-use-bytes":30740480,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:32:11.77322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2489860687,"revision":30331,"compact-revision":27632} {"level":"info","ts":"2026-10-16T15:37:11.636076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32586} {"level":"info","ts":"2026-10-16T15:37:11.80703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32586,"took":"166.725949ms","hash":3041075173,"current-db-size-bytes":59944960,"current-db-size":"60 MB","current-db-size-in-use-bytes":30384128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-16T15:37:11.807098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3041075173,"revision":32586,"compact-revision":30331} {"level":"info","ts":"2026-10-16T15:42:11.64413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34816} {"level":"info","ts":"2026-10-16T15:42:11.787904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34816,"took":"139.372763ms","hash":2389352910,"current-db-size-bytes":59944960,"current-db-size":"60 MB","current-db-size-in-use-bytes":31215616,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-16T15:42:11.787989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2389352910,"revision":34816,"compact-revision":32586} {"level":"info","ts":"2026-10-16T15:45:50.609849Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5420a740f4813dc9","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-16T15:45:50.618083Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-16T15:45:50.618209Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-16T15:47:11.650502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37113} {"level":"info","ts":"2026-10-16T15:47:11.771617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37113,"took":"117.964988ms","hash":1848008510,"current-db-size-bytes":59944960,"current-db-size":"60 MB","current-db-size-in-use-bytes":32149504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T15:47:11.771676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1848008510,"revision":37113,"compact-revision":34816} {"level":"info","ts":"2026-10-16T15:52:11.658458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39393} {"level":"info","ts":"2026-10-16T15:52:11.799359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39393,"took":"137.06617ms","hash":810240308,"current-db-size-bytes":61644800,"current-db-size":"62 MB","current-db-size-in-use-bytes":32165888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-16T15:52:11.799461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":810240308,"revision":39393,"compact-revision":37113}