{"level":"warn","ts":"2026-07-30T23:48:13.510945Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:48:13.511117Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-07-30T23:48:13.511223Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:48:13.511242Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-30T23:48:13.511264Z","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-07-30T23:48:13.512218Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-07-30T23:48:13.512334Z","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://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-07-30T23:48:13.517098Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.472556ms"} {"level":"info","ts":"2026-07-30T23:48:13.523721Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-07-30T23:48:13.523895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T23:48:13.523924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-07-30T23:48:13.524052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T23:48:13.524173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-07-30T23:48:13.524363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-07-30T23:48:13.535658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T23:48:13.537323Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T23:48:13.539128Z","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-07-30T23:48:13.542225Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T23:48:13.542448Z","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-07-30T23:48:13.545081Z","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-07-30T23:48:13.545119Z","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-07-30T23:48:13.544986Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T23:48:13.545229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-07-30T23:48:13.545413Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-07-30T23:48:13.54973Z","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-07-30T23:48:13.549856Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-30T23:48:13.549902Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-07-30T23:48:13.549995Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T23:48:13.550087Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T23:48:14.125656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T23:48:14.125748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T23:48:14.125792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-07-30T23:48:14.125821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-07-30T23:48:14.125838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-30T23:48:14.125856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-07-30T23:48:14.125884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-07-30T23:48:14.12732Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T23:48:14.127363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:48:14.127614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:48:14.128052Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T23:48:14.128101Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T23:48:14.140219Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:14.141622Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:14.141728Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:14.141753Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:48:14.144492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T23:48:14.14455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-07-30T23:48:19.682898Z","caller":"traceutil/trace.go:171","msg":"trace[1095096842] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"104.023428ms","start":"2026-07-30T23:48:19.578828Z","end":"2026-07-30T23:48:19.682852Z","steps":["trace[1095096842] 'process raft request' (duration: 103.685691ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.747878Z","caller":"traceutil/trace.go:171","msg":"trace[1417968577] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"194.031816ms","start":"2026-07-30T23:48:50.553812Z","end":"2026-07-30T23:48:50.747843Z","steps":["trace[1417968577] 'process raft request' (duration: 173.783665ms)","trace[1417968577] 'compare' (duration: 20.103408ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:48:50.749542Z","caller":"traceutil/trace.go:171","msg":"trace[776371757] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"189.718983ms","start":"2026-07-30T23:48:50.559766Z","end":"2026-07-30T23:48:50.749485Z","steps":["trace[776371757] 'process raft request' (duration: 189.358226ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.749702Z","caller":"traceutil/trace.go:171","msg":"trace[1704198497] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"184.295258ms","start":"2026-07-30T23:48:50.56538Z","end":"2026-07-30T23:48:50.749676Z","steps":["trace[1704198497] 'process raft request' (duration: 183.924111ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750208Z","caller":"traceutil/trace.go:171","msg":"trace[1071937461] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"147.124989ms","start":"2026-07-30T23:48:50.603065Z","end":"2026-07-30T23:48:50.75019Z","steps":["trace[1071937461] 'process raft request' (duration: 146.798553ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750285Z","caller":"traceutil/trace.go:171","msg":"trace[1339024366] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"170.617124ms","start":"2026-07-30T23:48:50.57965Z","end":"2026-07-30T23:48:50.750267Z","steps":["trace[1339024366] 'process raft request' (duration: 169.956641ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750203Z","caller":"traceutil/trace.go:171","msg":"trace[1900266517] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"166.216158ms","start":"2026-07-30T23:48:50.583964Z","end":"2026-07-30T23:48:50.75018Z","steps":["trace[1900266517] 'process raft request' (duration: 165.78022ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750265Z","caller":"traceutil/trace.go:171","msg":"trace[547575562] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"165.606187ms","start":"2026-07-30T23:48:50.584598Z","end":"2026-07-30T23:48:50.750204Z","steps":["trace[547575562] 'process raft request' (duration: 165.199299ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750411Z","caller":"traceutil/trace.go:171","msg":"trace[1212701193] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"180.942282ms","start":"2026-07-30T23:48:50.569452Z","end":"2026-07-30T23:48:50.750394Z","steps":["trace[1212701193] 'process raft request' (duration: 180.011665ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750484Z","caller":"traceutil/trace.go:171","msg":"trace[1040330768] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"179.159489ms","start":"2026-07-30T23:48:50.571308Z","end":"2026-07-30T23:48:50.750468Z","steps":["trace[1040330768] 'process raft request' (duration: 178.222881ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750553Z","caller":"traceutil/trace.go:171","msg":"trace[384376263] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"167.099225ms","start":"2026-07-30T23:48:50.583442Z","end":"2026-07-30T23:48:50.750541Z","steps":["trace[384376263] 'process raft request' (duration: 166.227319ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:50.750525Z","caller":"traceutil/trace.go:171","msg":"trace[1855917753] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"176.061899ms","start":"2026-07-30T23:48:50.574429Z","end":"2026-07-30T23:48:50.750491Z","steps":["trace[1855917753] 'process raft request' (duration: 175.136792ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:56.582606Z","caller":"traceutil/trace.go:171","msg":"trace[1829491970] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"117.357856ms","start":"2026-07-30T23:48:56.46521Z","end":"2026-07-30T23:48:56.582568Z","steps":["trace[1829491970] 'process raft request' (duration: 117.155932ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:57.449347Z","caller":"traceutil/trace.go:171","msg":"trace[456961840] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"355.063184ms","start":"2026-07-30T23:48:57.094248Z","end":"2026-07-30T23:48:57.449311Z","steps":["trace[456961840] 'read index received' (duration: 353.940422ms)","trace[456961840] 'applied index is now lower than readState.Index' (duration: 1.121612ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:48:57.449431Z","caller":"traceutil/trace.go:171","msg":"trace[1467956620] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"432.4696ms","start":"2026-07-30T23:48:57.016913Z","end":"2026-07-30T23:48:57.449383Z","steps":["trace[1467956620] 'process raft request' (duration: 431.520341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:48:57.449535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.279139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:48:57.449699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.935399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-30T23:48:57.449634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.549597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T23:48:57.449749Z","caller":"traceutil/trace.go:171","msg":"trace[1862807399] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:592; }","duration":"215.015761ms","start":"2026-07-30T23:48:57.234719Z","end":"2026-07-30T23:48:57.449735Z","steps":["trace[1862807399] 'agreement among raft nodes before linearized reading' (duration: 214.95007ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:57.449754Z","caller":"traceutil/trace.go:171","msg":"trace[1408246740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"249.73554ms","start":"2026-07-30T23:48:57.200006Z","end":"2026-07-30T23:48:57.449741Z","steps":["trace[1408246740] 'agreement among raft nodes before linearized reading' (duration: 249.527326ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:48:57.449702Z","caller":"traceutil/trace.go:171","msg":"trace[1666667714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"355.503763ms","start":"2026-07-30T23:48:57.094171Z","end":"2026-07-30T23:48:57.449674Z","steps":["trace[1666667714] 'agreement among raft nodes before linearized reading' (duration: 355.249798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:48:57.449814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.350946ms","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-07-30T23:48:57.449939Z","caller":"traceutil/trace.go:171","msg":"trace[2022113123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"202.57145ms","start":"2026-07-30T23:48:57.247348Z","end":"2026-07-30T23:48:57.449919Z","steps":["trace[2022113123] 'agreement among raft nodes before linearized reading' (duration: 202.256634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:48:57.449983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:48:57.09415Z","time spent":"355.752967ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T23:48:57.450344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:48:57.016875Z","time spent":"432.671973ms","remote":"127.0.0.1:50912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:26.812689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.073986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:50:26.813145Z","caller":"traceutil/trace.go:171","msg":"trace[1765433771] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"364.784697ms","start":"2026-07-30T23:50:26.448311Z","end":"2026-07-30T23:50:26.813095Z","steps":["trace[1765433771] 'process raft request' (duration: 217.971806ms)","trace[1765433771] 'compare' (duration: 145.811551ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:26.813188Z","caller":"traceutil/trace.go:171","msg":"trace[1280006845] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1887; }","duration":"106.440024ms","start":"2026-07-30T23:50:26.706718Z","end":"2026-07-30T23:50:26.813158Z","steps":["trace[1280006845] 'read index received' (duration: 27.907746ms)","trace[1280006845] 'applied index is now lower than readState.Index' (duration: 78.531108ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:26.813402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:26.44829Z","time spent":"364.975981ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3725,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:26.813468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.713309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-30T23:50:26.813498Z","caller":"traceutil/trace.go:171","msg":"trace[1494917255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1816; }","duration":"106.802451ms","start":"2026-07-30T23:50:26.706685Z","end":"2026-07-30T23:50:26.813487Z","steps":["trace[1494917255] 'agreement among raft nodes before linearized reading' (duration: 106.568367ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:26.813484Z","caller":"traceutil/trace.go:171","msg":"trace[1941618969] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"288.524532ms","start":"2026-07-30T23:50:26.524908Z","end":"2026-07-30T23:50:26.813433Z","steps":["trace[1941618969] 'process raft request' (duration: 288.107394ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:10.273152Z","caller":"traceutil/trace.go:171","msg":"trace[531293979] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"110.674167ms","start":"2026-07-30T23:51:10.162441Z","end":"2026-07-30T23:51:10.273115Z","steps":["trace[531293979] 'process raft request' (duration: 110.497613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:10.451862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.603118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:51:10.452184Z","caller":"traceutil/trace.go:171","msg":"trace[1620358166] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"173.582835ms","start":"2026-07-30T23:51:10.278559Z","end":"2026-07-30T23:51:10.452142Z","steps":["trace[1620358166] 'process raft request' (duration: 173.477052ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:10.45217Z","caller":"traceutil/trace.go:171","msg":"trace[1469010386] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"174.830498ms","start":"2026-07-30T23:51:10.2773Z","end":"2026-07-30T23:51:10.45213Z","steps":["trace[1469010386] 'process raft request' (duration: 68.774581ms)","trace[1469010386] 'compare' (duration: 105.434995ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:10.913376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.136175ms","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-07-30T23:51:10.913488Z","caller":"traceutil/trace.go:171","msg":"trace[1458432092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2112; }","duration":"224.285087ms","start":"2026-07-30T23:51:10.689169Z","end":"2026-07-30T23:51:10.913454Z","steps":["trace[1458432092] 'range keys from in-memory index tree' (duration: 223.835059ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:16.664246Z","caller":"traceutil/trace.go:171","msg":"trace[1329289463] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"180.671314ms","start":"2026-07-30T23:51:16.483547Z","end":"2026-07-30T23:51:16.664218Z","steps":["trace[1329289463] 'process raft request' (duration: 180.507841ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:16.696882Z","caller":"traceutil/trace.go:171","msg":"trace[709354621] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"212.731329ms","start":"2026-07-30T23:51:16.48412Z","end":"2026-07-30T23:51:16.696852Z","steps":["trace[709354621] 'process raft request' (duration: 212.544275ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:16.697109Z","caller":"traceutil/trace.go:171","msg":"trace[1364475580] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"190.449142ms","start":"2026-07-30T23:51:16.506619Z","end":"2026-07-30T23:51:16.697069Z","steps":["trace[1364475580] 'process raft request' (duration: 190.186607ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:16.697308Z","caller":"traceutil/trace.go:171","msg":"trace[197675605] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"186.532287ms","start":"2026-07-30T23:51:16.510756Z","end":"2026-07-30T23:51:16.697288Z","steps":["trace[197675605] 'process raft request' (duration: 186.259932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:51:17.382496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.918854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-30T23:51:17.382641Z","caller":"traceutil/trace.go:171","msg":"trace[1591741741] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:2147; }","duration":"137.139748ms","start":"2026-07-30T23:51:17.245461Z","end":"2026-07-30T23:51:17.382601Z","steps":["trace[1591741741] 'range keys from in-memory index tree' (duration: 136.592988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:55:28.07567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.30222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-30T23:55:28.075765Z","caller":"traceutil/trace.go:171","msg":"trace[1066959708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3872; }","duration":"156.438542ms","start":"2026-07-30T23:55:27.919306Z","end":"2026-07-30T23:55:28.075745Z","steps":["trace[1066959708] 'range keys from in-memory index tree' (duration: 156.042005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:55:28.075927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.684636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:63161"} {"level":"warn","ts":"2026-07-30T23:55:28.075923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.134793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T23:55:28.076025Z","caller":"traceutil/trace.go:171","msg":"trace[425181688] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:3872; }","duration":"206.815889ms","start":"2026-07-30T23:55:27.869187Z","end":"2026-07-30T23:55:28.076003Z","steps":["trace[425181688] 'range keys from in-memory index tree' (duration: 206.463971ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:55:28.076045Z","caller":"traceutil/trace.go:171","msg":"trace[1640972562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3872; }","duration":"122.305466ms","start":"2026-07-30T23:55:27.953714Z","end":"2026-07-30T23:55:28.07602Z","steps":["trace[1640972562] 'range keys from in-memory index tree' (duration: 121.937549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:56:57.511885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.695416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5be000b-a946-4398-896f-7aeb34925959\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-30T23:56:57.512013Z","caller":"traceutil/trace.go:171","msg":"trace[1538010915] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5be000b-a946-4398-896f-7aeb34925959; range_end:; response_count:1; response_revision:5083; }","duration":"158.864168ms","start":"2026-07-30T23:56:57.353121Z","end":"2026-07-30T23:56:57.511985Z","steps":["trace[1538010915] 'range keys from in-memory index tree' (duration: 158.500352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:56:57.512304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.150677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-30T23:56:57.512365Z","caller":"traceutil/trace.go:171","msg":"trace[499199221] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:5083; }","duration":"247.266079ms","start":"2026-07-30T23:56:57.265082Z","end":"2026-07-30T23:56:57.512348Z","steps":["trace[499199221] 'range keys from in-memory index tree' (duration: 247.050945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:56:57.512461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.075424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786909"} {"level":"warn","ts":"2026-07-30T23:56:57.512497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.082014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-07-30T23:56:57.512546Z","caller":"traceutil/trace.go:171","msg":"trace[236822709] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5083; }","duration":"170.178316ms","start":"2026-07-30T23:56:57.342353Z","end":"2026-07-30T23:56:57.512531Z","steps":["trace[236822709] 'range keys from in-memory index tree' (duration: 169.949341ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:56:57.512547Z","caller":"traceutil/trace.go:171","msg":"trace[1224204532] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5083; }","duration":"156.189926ms","start":"2026-07-30T23:56:57.356336Z","end":"2026-07-30T23:56:57.512526Z","steps":["trace[1224204532] 'range keys from in-memory index tree' (duration: 155.557314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:57:10.815765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.611685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-30T23:57:10.815897Z","caller":"traceutil/trace.go:171","msg":"trace[757109169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5333; }","duration":"182.838349ms","start":"2026-07-30T23:57:10.633038Z","end":"2026-07-30T23:57:10.815876Z","steps":["trace[757109169] 'range keys from in-memory index tree' (duration: 182.304309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:57:10.815942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.961432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4gsr8\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-30T23:57:10.816094Z","caller":"traceutil/trace.go:171","msg":"trace[2112971743] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4gsr8; range_end:; response_count:1; response_revision:5333; }","duration":"181.127486ms","start":"2026-07-30T23:57:10.634933Z","end":"2026-07-30T23:57:10.816061Z","steps":["trace[2112971743] 'range keys from in-memory index tree' (duration: 180.700787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:57:10.81622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.52234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-30T23:57:10.816346Z","caller":"traceutil/trace.go:171","msg":"trace[1960197598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5333; }","duration":"187.721183ms","start":"2026-07-30T23:57:10.628596Z","end":"2026-07-30T23:57:10.816317Z","steps":["trace[1960197598] 'range keys from in-memory index tree' (duration: 187.291526ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:57:18.40213Z","caller":"traceutil/trace.go:171","msg":"trace[1336115950] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"113.200119ms","start":"2026-07-30T23:57:18.288895Z","end":"2026-07-30T23:57:18.402095Z","steps":["trace[1336115950] 'process raft request' (duration: 112.960074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:57:18.609563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.899761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:57:18.609766Z","caller":"traceutil/trace.go:171","msg":"trace[1290803823] linearizableReadLoop","detail":"{readStateIndex:5725; appliedIndex:5722; }","duration":"306.275055ms","start":"2026-07-30T23:57:18.303472Z","end":"2026-07-30T23:57:18.609747Z","steps":["trace[1290803823] 'read index received' (duration: 98.364163ms)","trace[1290803823] 'applied index is now lower than readState.Index' (duration: 207.909542ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:57:18.609858Z","caller":"traceutil/trace.go:171","msg":"trace[1104734236] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"319.205625ms","start":"2026-07-30T23:57:18.290638Z","end":"2026-07-30T23:57:18.609843Z","steps":["trace[1104734236] 'process raft request' (duration: 163.926256ms)","trace[1104734236] 'compare' (duration: 154.739528ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:57:18.609928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:57:18.290609Z","time spent":"319.287127ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:57:18.609918Z","caller":"traceutil/trace.go:171","msg":"trace[403876379] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"317.901459ms","start":"2026-07-30T23:57:18.291985Z","end":"2026-07-30T23:57:18.609886Z","steps":["trace[403876379] 'process raft request' (duration: 317.691715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:57:18.610079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:57:18.291974Z","time spent":"318.025492ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:57:18.610287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.676604ms","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-07-30T23:57:18.610393Z","caller":"traceutil/trace.go:171","msg":"trace[736720926] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:5515; }","duration":"307.00017ms","start":"2026-07-30T23:57:18.303372Z","end":"2026-07-30T23:57:18.610372Z","steps":["trace[736720926] 'agreement among raft nodes before linearized reading' (duration: 306.660003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:57:18.611142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:57:18.303304Z","time spent":"307.828426ms","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:57:18.655835Z","caller":"traceutil/trace.go:171","msg":"trace[1707206279] transaction","detail":"{read_only:false; response_revision:5516; number_of_response:1; }","duration":"117.999152ms","start":"2026-07-30T23:57:18.537779Z","end":"2026-07-30T23:57:18.655778Z","steps":["trace[1707206279] 'process raft request' (duration: 117.829728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:57:18.655948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.259695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2542e968-0506-4bff-aeac-ff8e0bc4fd98\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-30T23:57:18.656015Z","caller":"traceutil/trace.go:171","msg":"trace[1550882427] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2542e968-0506-4bff-aeac-ff8e0bc4fd98; range_end:; response_count:1; response_revision:5516; }","duration":"187.384248ms","start":"2026-07-30T23:57:18.468614Z","end":"2026-07-30T23:57:18.655998Z","steps":["trace[1550882427] 'agreement among raft nodes before linearized reading' (duration: 187.112913ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:12.800743Z","caller":"traceutil/trace.go:171","msg":"trace[249686172] transaction","detail":"{read_only:false; response_revision:6012; number_of_response:1; }","duration":"115.042455ms","start":"2026-07-30T23:58:12.685674Z","end":"2026-07-30T23:58:12.800716Z","steps":["trace[249686172] 'process raft request' (duration: 114.934633ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:12.800801Z","caller":"traceutil/trace.go:171","msg":"trace[413643092] transaction","detail":"{read_only:false; response_revision:6011; number_of_response:1; }","duration":"115.089315ms","start":"2026-07-30T23:58:12.685647Z","end":"2026-07-30T23:58:12.800737Z","steps":["trace[413643092] 'process raft request' (duration: 114.787769ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:12.801188Z","caller":"traceutil/trace.go:171","msg":"trace[467895745] transaction","detail":"{read_only:false; response_revision:6013; number_of_response:1; }","duration":"115.426181ms","start":"2026-07-30T23:58:12.685744Z","end":"2026-07-30T23:58:12.80117Z","steps":["trace[467895745] 'process raft request' (duration: 114.929122ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:12.801285Z","caller":"traceutil/trace.go:171","msg":"trace[961145572] transaction","detail":"{read_only:false; response_revision:6014; number_of_response:1; }","duration":"115.268409ms","start":"2026-07-30T23:58:12.685936Z","end":"2026-07-30T23:58:12.801204Z","steps":["trace[961145572] 'process raft request' (duration: 114.923362ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:12.801336Z","caller":"traceutil/trace.go:171","msg":"trace[1898235600] transaction","detail":"{read_only:false; response_revision:6015; number_of_response:1; }","duration":"115.224938ms","start":"2026-07-30T23:58:12.686067Z","end":"2026-07-30T23:58:12.801292Z","steps":["trace[1898235600] 'process raft request' (duration: 115.027904ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:12.801245Z","caller":"traceutil/trace.go:171","msg":"trace[1675377403] linearizableReadLoop","detail":"{readStateIndex:6238; appliedIndex:6233; }","duration":"110.308473ms","start":"2026-07-30T23:58:12.690883Z","end":"2026-07-30T23:58:12.801191Z","steps":["trace[1675377403] 'read index received' (duration: 109.63412ms)","trace[1675377403] 'applied index is now lower than readState.Index' (duration: 672.383µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:58:12.801688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.785203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-30T23:58:12.801774Z","caller":"traceutil/trace.go:171","msg":"trace[88459423] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:6015; }","duration":"110.915745ms","start":"2026-07-30T23:58:12.690844Z","end":"2026-07-30T23:58:12.801759Z","steps":["trace[88459423] 'agreement among raft nodes before linearized reading' (duration: 110.497757ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:12.81837Z","caller":"traceutil/trace.go:171","msg":"trace[1973708556] transaction","detail":"{read_only:false; response_revision:6016; number_of_response:1; }","duration":"126.982154ms","start":"2026-07-30T23:58:12.691373Z","end":"2026-07-30T23:58:12.818355Z","steps":["trace[1973708556] 'process raft request' (duration: 126.76132ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:58:14.173896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2973} {"level":"info","ts":"2026-07-30T23:58:14.249557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2973,"took":"73.76013ms","hash":902356386,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":33755136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-30T23:58:14.249707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":902356386,"revision":2973,"compact-revision":-1} {"level":"info","ts":"2026-07-31T00:03:14.181143Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6027} {"level":"info","ts":"2026-07-31T00:03:14.276335Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6027,"took":"92.550294ms","hash":2429447522,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":21831680,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-31T00:03:14.276404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2429447522,"revision":6027,"compact-revision":2973} {"level":"info","ts":"2026-07-31T00:03:46.275641Z","caller":"traceutil/trace.go:171","msg":"trace[105107956] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"136.928994ms","start":"2026-07-31T00:03:46.138664Z","end":"2026-07-31T00:03:46.275593Z","steps":["trace[105107956] 'process raft request' (duration: 136.661109ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:03:46.275653Z","caller":"traceutil/trace.go:171","msg":"trace[1629565943] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"131.791639ms","start":"2026-07-31T00:03:46.14383Z","end":"2026-07-31T00:03:46.275622Z","steps":["trace[1629565943] 'process raft request' (duration: 131.679907ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:03:47.250277Z","caller":"traceutil/trace.go:171","msg":"trace[1109760921] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"130.422893ms","start":"2026-07-31T00:03:47.119811Z","end":"2026-07-31T00:03:47.250234Z","steps":["trace[1109760921] 'process raft request' (duration: 130.26089ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:03:47.277921Z","caller":"traceutil/trace.go:171","msg":"trace[1940217229] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"156.876258ms","start":"2026-07-31T00:03:47.12102Z","end":"2026-07-31T00:03:47.277896Z","steps":["trace[1940217229] 'process raft request' (duration: 156.600943ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:03:47.277845Z","caller":"traceutil/trace.go:171","msg":"trace[753568168] linearizableReadLoop","detail":"{readStateIndex:8782; appliedIndex:8780; }","duration":"156.140645ms","start":"2026-07-31T00:03:47.121679Z","end":"2026-07-31T00:03:47.27782Z","steps":["trace[753568168] 'read index received' (duration: 128.384985ms)","trace[753568168] 'applied index is now lower than readState.Index' (duration: 27.75428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:03:47.278184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.42484ms","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-07-31T00:03:47.278306Z","caller":"traceutil/trace.go:171","msg":"trace[1040461178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8477; }","duration":"156.643653ms","start":"2026-07-31T00:03:47.121648Z","end":"2026-07-31T00:03:47.278291Z","steps":["trace[1040461178] 'agreement among raft nodes before linearized reading' (duration: 156.328978ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:04:28.474675Z","caller":"traceutil/trace.go:171","msg":"trace[5800864] linearizableReadLoop","detail":"{readStateIndex:9114; appliedIndex:9113; }","duration":"227.159755ms","start":"2026-07-31T00:04:28.247478Z","end":"2026-07-31T00:04:28.474638Z","steps":["trace[5800864] 'read index received' (duration: 129.017447ms)","trace[5800864] 'applied index is now lower than readState.Index' (duration: 98.140908ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:04:28.474952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.44611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-31T00:04:28.474998Z","caller":"traceutil/trace.go:171","msg":"trace[356676616] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8794; }","duration":"227.543861ms","start":"2026-07-31T00:04:28.24744Z","end":"2026-07-31T00:04:28.474984Z","steps":["trace[356676616] 'agreement among raft nodes before linearized reading' (duration: 227.336627ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:04:28.475054Z","caller":"traceutil/trace.go:171","msg":"trace[1960624352] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"236.52931ms","start":"2026-07-31T00:04:28.238484Z","end":"2026-07-31T00:04:28.475013Z","steps":["trace[1960624352] 'process raft request' (duration: 137.998675ms)","trace[1960624352] 'compare' (duration: 97.926974ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:04:28.478502Z","caller":"traceutil/trace.go:171","msg":"trace[518944082] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"112.883013ms","start":"2026-07-31T00:04:28.365591Z","end":"2026-07-31T00:04:28.478474Z","steps":["trace[518944082] 'process raft request' (duration: 112.69368ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:04:32.505153Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c9.wal"} {"level":"info","ts":"2026-07-31T00:05:46.306572Z","caller":"traceutil/trace.go:171","msg":"trace[1007243956] transaction","detail":"{read_only:false; response_revision:9551; number_of_response:1; }","duration":"265.327749ms","start":"2026-07-31T00:05:46.041201Z","end":"2026-07-31T00:05:46.306528Z","steps":["trace[1007243956] 'process raft request' (duration: 256.927302ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:05:46.306619Z","caller":"traceutil/trace.go:171","msg":"trace[1772278591] linearizableReadLoop","detail":"{readStateIndex:9892; appliedIndex:9890; }","duration":"249.545635ms","start":"2026-07-31T00:05:46.05702Z","end":"2026-07-31T00:05:46.306566Z","steps":["trace[1772278591] 'read index received' (duration: 241.099616ms)","trace[1772278591] 'applied index is now lower than readState.Index' (duration: 8.444299ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:05:46.306758Z","caller":"traceutil/trace.go:171","msg":"trace[1189902316] transaction","detail":"{read_only:false; response_revision:9552; number_of_response:1; }","duration":"264.555636ms","start":"2026-07-31T00:05:46.042164Z","end":"2026-07-31T00:05:46.30672Z","steps":["trace[1189902316] 'process raft request' (duration: 264.249099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:05:46.30688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.820879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T00:05:46.306941Z","caller":"traceutil/trace.go:171","msg":"trace[705131529] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:9552; }","duration":"249.974712ms","start":"2026-07-31T00:05:46.056951Z","end":"2026-07-31T00:05:46.306925Z","steps":["trace[705131529] 'agreement among raft nodes before linearized reading' (duration: 249.83623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:05:46.307495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.141801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T00:05:46.307557Z","caller":"traceutil/trace.go:171","msg":"trace[1168358647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9552; }","duration":"213.235843ms","start":"2026-07-31T00:05:46.094299Z","end":"2026-07-31T00:05:46.307535Z","steps":["trace[1168358647] 'agreement among raft nodes before linearized reading' (duration: 213.152971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:05:46.30764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.688759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T00:05:46.307852Z","caller":"traceutil/trace.go:171","msg":"trace[627186430] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:9552; }","duration":"165.947544ms","start":"2026-07-31T00:05:46.141869Z","end":"2026-07-31T00:05:46.307817Z","steps":["trace[627186430] 'agreement among raft nodes before linearized reading' (duration: 165.621998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:05:46.308041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.049298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4gsr8\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-31T00:05:46.308146Z","caller":"traceutil/trace.go:171","msg":"trace[1511414566] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4gsr8; range_end:; response_count:1; response_revision:9552; }","duration":"132.179ms","start":"2026-07-31T00:05:46.17594Z","end":"2026-07-31T00:05:46.308119Z","steps":["trace[1511414566] 'agreement among raft nodes before linearized reading' (duration: 131.511898ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:05:58.960092Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T00:05:58.968144Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-31T00:05:58.968313Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-31T00:08:14.188512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8131} {"level":"info","ts":"2026-07-31T00:08:14.255811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8131,"took":"64.483801ms","hash":2300562201,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":28499968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-31T00:08:14.255907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2300562201,"revision":8131,"compact-revision":6027} {"level":"info","ts":"2026-07-31T00:13:14.194905Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10536} {"level":"info","ts":"2026-07-31T00:13:14.280108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10536,"took":"81.423176ms","hash":345603403,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":26472448,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-31T00:13:14.28022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":345603403,"revision":10536,"compact-revision":8131} {"level":"info","ts":"2026-07-31T00:17:48.214715Z","caller":"traceutil/trace.go:171","msg":"trace[1843188343] transaction","detail":"{read_only:false; response_revision:14549; number_of_response:1; }","duration":"129.296715ms","start":"2026-07-31T00:17:48.085383Z","end":"2026-07-31T00:17:48.21468Z","steps":["trace[1843188343] 'process raft request' (duration: 129.10786ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:17:48.217588Z","caller":"traceutil/trace.go:171","msg":"trace[718925440] linearizableReadLoop","detail":"{readStateIndex:15061; appliedIndex:15059; }","duration":"124.990761ms","start":"2026-07-31T00:17:48.092564Z","end":"2026-07-31T00:17:48.217555Z","steps":["trace[718925440] 'read index received' (duration: 122.033763ms)","trace[718925440] 'applied index is now lower than readState.Index' (duration: 2.956008ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:17:48.217921Z","caller":"traceutil/trace.go:171","msg":"trace[1525699651] transaction","detail":"{read_only:false; response_revision:14550; number_of_response:1; }","duration":"131.572708ms","start":"2026-07-31T00:17:48.086331Z","end":"2026-07-31T00:17:48.217904Z","steps":["trace[1525699651] 'process raft request' (duration: 129.663741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:17:48.218215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.621703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T00:17:48.218287Z","caller":"traceutil/trace.go:171","msg":"trace[368136362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14550; }","duration":"125.725895ms","start":"2026-07-31T00:17:48.092548Z","end":"2026-07-31T00:17:48.218274Z","steps":["trace[368136362] 'agreement among raft nodes before linearized reading' (duration: 125.591342ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:18:05.012834Z","caller":"traceutil/trace.go:171","msg":"trace[131234107] linearizableReadLoop","detail":"{readStateIndex:15168; appliedIndex:15167; }","duration":"177.925892ms","start":"2026-07-31T00:18:04.834882Z","end":"2026-07-31T00:18:05.012808Z","steps":["trace[131234107] 'read index received' (duration: 177.696928ms)","trace[131234107] 'applied index is now lower than readState.Index' (duration: 227.204µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:18:05.012889Z","caller":"traceutil/trace.go:171","msg":"trace[246453964] transaction","detail":"{read_only:false; response_revision:14652; number_of_response:1; }","duration":"326.828854ms","start":"2026-07-31T00:18:04.686012Z","end":"2026-07-31T00:18:05.012841Z","steps":["trace[246453964] 'process raft request' (duration: 326.60398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:18:05.013037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.905919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-07-31T00:18:05.013077Z","caller":"traceutil/trace.go:171","msg":"trace[61601447] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:14652; }","duration":"110.989911ms","start":"2026-07-31T00:18:04.902077Z","end":"2026-07-31T00:18:05.013067Z","steps":["trace[61601447] 'agreement among raft nodes before linearized reading' (duration: 110.884179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:18:05.01314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:18:04.685959Z","time spent":"327.044548ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:18:05.013128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.199157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T00:18:05.013255Z","caller":"traceutil/trace.go:171","msg":"trace[802790950] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:14652; }","duration":"178.392241ms","start":"2026-07-31T00:18:04.834835Z","end":"2026-07-31T00:18:05.013227Z","steps":["trace[802790950] 'agreement among raft nodes before linearized reading' (duration: 178.124436ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:18:14.370984Z","caller":"traceutil/trace.go:171","msg":"trace[782627016] compact","detail":"{revision:12718; response_revision:14821; }","duration":"147.981001ms","start":"2026-07-31T00:18:14.222984Z","end":"2026-07-31T00:18:14.370965Z","steps":["trace[782627016] 'process raft request' (duration: 111.802649ms)","trace[782627016] 'check and update compact revision' (duration: 36.077229ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:18:14.370994Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12718} {"level":"info","ts":"2026-07-31T00:18:14.450732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12718,"took":"74.793771ms","hash":1300774413,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":27996160,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-31T00:18:14.450796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1300774413,"revision":12718,"compact-revision":10536} {"level":"warn","ts":"2026-07-31T00:19:56.280682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.721952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T00:19:56.280796Z","caller":"traceutil/trace.go:171","msg":"trace[1321395909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15913; }","duration":"186.915696ms","start":"2026-07-31T00:19:56.093856Z","end":"2026-07-31T00:19:56.280772Z","steps":["trace[1321395909] 'range keys from in-memory index tree' (duration: 186.585079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:19:56.281558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.648114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T00:19:56.281643Z","caller":"traceutil/trace.go:171","msg":"trace[420677924] transaction","detail":"{read_only:false; response_revision:15914; number_of_response:1; }","duration":"164.384274ms","start":"2026-07-31T00:19:56.117241Z","end":"2026-07-31T00:19:56.281626Z","steps":["trace[420677924] 'process raft request' (duration: 50.584457ms)","trace[420677924] 'compare' (duration: 112.696246ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:19:56.367049Z","caller":"traceutil/trace.go:171","msg":"trace[2104369674] transaction","detail":"{read_only:false; response_revision:15915; number_of_response:1; }","duration":"245.439416ms","start":"2026-07-31T00:19:56.121574Z","end":"2026-07-31T00:19:56.367013Z","steps":["trace[2104369674] 'process raft request' (duration: 245.159101ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:19:56.367318Z","caller":"traceutil/trace.go:171","msg":"trace[2121703801] linearizableReadLoop","detail":"{readStateIndex:16470; appliedIndex:16468; }","duration":"196.947297ms","start":"2026-07-31T00:19:56.170338Z","end":"2026-07-31T00:19:56.367285Z","steps":["trace[2121703801] 'read index received' (duration: 196.56947ms)","trace[2121703801] 'applied index is now lower than readState.Index' (duration: 376.417µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:19:56.367381Z","caller":"traceutil/trace.go:171","msg":"trace[248461686] transaction","detail":"{read_only:false; response_revision:15916; number_of_response:1; }","duration":"245.039517ms","start":"2026-07-31T00:19:56.122305Z","end":"2026-07-31T00:19:56.367344Z","steps":["trace[248461686] 'process raft request' (duration: 244.64973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:19:56.367487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.114631ms","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-07-31T00:19:56.367541Z","caller":"traceutil/trace.go:171","msg":"trace[146930420] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15916; }","duration":"197.221693ms","start":"2026-07-31T00:19:56.170305Z","end":"2026-07-31T00:19:56.367527Z","steps":["trace[146930420] 'agreement among raft nodes before linearized reading' (duration: 197.105351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:19:56.367706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.156373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-31T00:19:56.367808Z","caller":"traceutil/trace.go:171","msg":"trace[2080158086] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15916; }","duration":"127.325666ms","start":"2026-07-31T00:19:56.240461Z","end":"2026-07-31T00:19:56.367787Z","steps":["trace[2080158086] 'agreement among raft nodes before linearized reading' (duration: 127.066971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:19:56.368228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.305204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8n7rz\" ","response":"range_response_count:1 size:22120"} {"level":"info","ts":"2026-07-31T00:19:56.368391Z","caller":"traceutil/trace.go:171","msg":"trace[102140604] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8n7rz; range_end:; response_count:1; response_revision:15916; }","duration":"117.474878ms","start":"2026-07-31T00:19:56.25089Z","end":"2026-07-31T00:19:56.368365Z","steps":["trace[102140604] 'agreement among raft nodes before linearized reading' (duration: 117.143471ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:23:14.379628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14821} {"level":"info","ts":"2026-07-31T00:23:14.475202Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14821,"took":"92.381005ms","hash":407969860,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":30212096,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-31T00:23:14.475282Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":407969860,"revision":14821,"compact-revision":12718} {"level":"info","ts":"2026-07-31T00:25:32.254385Z","caller":"traceutil/trace.go:171","msg":"trace[752538249] transaction","detail":"{read_only:false; response_revision:18432; number_of_response:1; }","duration":"185.145497ms","start":"2026-07-31T00:25:32.069208Z","end":"2026-07-31T00:25:32.254353Z","steps":["trace[752538249] 'process raft request' (duration: 184.956614ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:25:32.269529Z","caller":"traceutil/trace.go:171","msg":"trace[1560637529] linearizableReadLoop","detail":"{readStateIndex:19068; appliedIndex:19066; }","duration":"197.783508ms","start":"2026-07-31T00:25:32.071729Z","end":"2026-07-31T00:25:32.269512Z","steps":["trace[1560637529] 'read index received' (duration: 182.553788ms)","trace[1560637529] 'applied index is now lower than readState.Index' (duration: 15.22836ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:25:32.269679Z","caller":"traceutil/trace.go:171","msg":"trace[1465120878] transaction","detail":"{read_only:false; response_revision:18433; number_of_response:1; }","duration":"199.921559ms","start":"2026-07-31T00:25:32.069716Z","end":"2026-07-31T00:25:32.269637Z","steps":["trace[1465120878] 'process raft request' (duration: 199.653144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:32.269888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.321128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:25:32.269989Z","caller":"traceutil/trace.go:171","msg":"trace[228648214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18433; }","duration":"177.46956ms","start":"2026-07-31T00:25:32.092495Z","end":"2026-07-31T00:25:32.269965Z","steps":["trace[228648214] 'agreement among raft nodes before linearized reading' (duration: 177.278146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:32.270264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.68113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:25:32.270319Z","caller":"traceutil/trace.go:171","msg":"trace[557385615] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:18433; }","duration":"129.798312ms","start":"2026-07-31T00:25:32.140507Z","end":"2026-07-31T00:25:32.270306Z","steps":["trace[557385615] 'agreement among raft nodes before linearized reading' (duration: 129.68907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:32.27055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.239068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:25:32.270592Z","caller":"traceutil/trace.go:171","msg":"trace[906019255] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:18433; }","duration":"176.306118ms","start":"2026-07-31T00:25:32.094276Z","end":"2026-07-31T00:25:32.270582Z","steps":["trace[906019255] 'agreement among raft nodes before linearized reading' (duration: 176.194326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:32.270936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.178634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-07-31T00:25:32.270997Z","caller":"traceutil/trace.go:171","msg":"trace[1206047152] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:18433; }","duration":"199.283706ms","start":"2026-07-31T00:25:32.071697Z","end":"2026-07-31T00:25:32.270981Z","steps":["trace[1206047152] 'agreement among raft nodes before linearized reading' (duration: 199.117063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.331204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.280584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-szgtx\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-07-31T00:25:39.331361Z","caller":"traceutil/trace.go:171","msg":"trace[2142955280] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-szgtx; range_end:; response_count:1; response_revision:18524; }","duration":"423.495457ms","start":"2026-07-31T00:25:38.907841Z","end":"2026-07-31T00:25:39.331336Z","steps":["trace[2142955280] 'range keys from in-memory index tree' (duration: 423.087231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.33141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:25:38.907819Z","time spent":"423.58006ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9819,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-szgtx\" "} {"level":"info","ts":"2026-07-31T00:25:39.346789Z","caller":"traceutil/trace.go:171","msg":"trace[342072250] linearizableReadLoop","detail":"{readStateIndex:19162; appliedIndex:19161; }","duration":"362.715039ms","start":"2026-07-31T00:25:38.984047Z","end":"2026-07-31T00:25:39.346762Z","steps":["trace[342072250] 'read index received' (duration: 362.538435ms)","trace[342072250] 'applied index is now lower than readState.Index' (duration: 175.404µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:25:39.34707Z","caller":"traceutil/trace.go:171","msg":"trace[1988268887] transaction","detail":"{read_only:false; response_revision:18525; number_of_response:1; }","duration":"437.107767ms","start":"2026-07-31T00:25:38.909804Z","end":"2026-07-31T00:25:39.346911Z","steps":["trace[1988268887] 'process raft request' (duration: 436.76813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.347277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:25:38.909769Z","time spent":"437.34353ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20866,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:25:39.347431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.372871ms","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-07-31T00:25:39.347537Z","caller":"traceutil/trace.go:171","msg":"trace[1507917240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18525; }","duration":"363.533274ms","start":"2026-07-31T00:25:38.983976Z","end":"2026-07-31T00:25:39.347509Z","steps":["trace[1507917240] 'agreement among raft nodes before linearized reading' (duration: 363.254538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.347589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:25:38.983944Z","time spent":"363.635146ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T00:25:39.356893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.401007ms","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-07-31T00:25:39.356956Z","caller":"traceutil/trace.go:171","msg":"trace[2077893112] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18526; }","duration":"327.541099ms","start":"2026-07-31T00:25:39.0294Z","end":"2026-07-31T00:25:39.356941Z","steps":["trace[2077893112] 'agreement among raft nodes before linearized reading' (duration: 327.339675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.356988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:25:39.029374Z","time spent":"327.606801ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T00:25:39.357066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.869106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:25:39.357116Z","caller":"traceutil/trace.go:171","msg":"trace[2073230686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18526; }","duration":"263.916517ms","start":"2026-07-31T00:25:39.093186Z","end":"2026-07-31T00:25:39.357102Z","steps":["trace[2073230686] 'agreement among raft nodes before linearized reading' (duration: 263.835566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.357187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.628804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-31T00:25:39.357212Z","caller":"traceutil/trace.go:171","msg":"trace[9811751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18526; }","duration":"324.661734ms","start":"2026-07-31T00:25:39.032542Z","end":"2026-07-31T00:25:39.357204Z","steps":["trace[9811751] 'agreement among raft nodes before linearized reading' (duration: 324.594273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.357231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:25:39.032537Z","time spent":"324.690096ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T00:25:39.357197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.232327ms","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-07-31T00:25:39.357299Z","caller":"traceutil/trace.go:171","msg":"trace[132853384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18526; }","duration":"324.377769ms","start":"2026-07-31T00:25:39.032898Z","end":"2026-07-31T00:25:39.357276Z","steps":["trace[132853384] 'agreement among raft nodes before linearized reading' (duration: 324.040453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:25:39.357344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:25:39.032894Z","time spent":"324.442951ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T00:27:01.675806Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T00:27:01.682146Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-31T00:27:01.682255Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-31T00:28:14.386708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17463} {"level":"info","ts":"2026-07-31T00:28:14.498968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17463,"took":"107.672674ms","hash":788987605,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":34508800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-31T00:28:14.499057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":788987605,"revision":17463,"compact-revision":14821} {"level":"warn","ts":"2026-07-31T00:31:07.978405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.461489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7913"} {"level":"info","ts":"2026-07-31T00:31:07.978536Z","caller":"traceutil/trace.go:171","msg":"trace[191669093] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:21043; }","duration":"186.633774ms","start":"2026-07-31T00:31:07.791873Z","end":"2026-07-31T00:31:07.978507Z","steps":["trace[191669093] 'range keys from in-memory index tree' (duration: 186.221145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:31:07.979127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.647253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:31:07.979169Z","caller":"traceutil/trace.go:171","msg":"trace[1106166799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21043; }","duration":"197.736275ms","start":"2026-07-31T00:31:07.781422Z","end":"2026-07-31T00:31:07.979159Z","steps":["trace[1106166799] 'range keys from in-memory index tree' (duration: 197.558032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:31:25.171154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.271554ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:31:25.171356Z","caller":"traceutil/trace.go:171","msg":"trace[73152791] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:21127; }","duration":"158.509788ms","start":"2026-07-31T00:31:25.012819Z","end":"2026-07-31T00:31:25.171329Z","steps":["trace[73152791] 'range keys from in-memory index tree' (duration: 158.237903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:31:25.171877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.666684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:31:25.172287Z","caller":"traceutil/trace.go:171","msg":"trace[1472395097] transaction","detail":"{read_only:false; response_revision:21128; number_of_response:1; }","duration":"309.132124ms","start":"2026-07-31T00:31:24.863126Z","end":"2026-07-31T00:31:25.172258Z","steps":["trace[1472395097] 'process raft request' (duration: 163.614474ms)","trace[1472395097] 'compare' (duration: 144.544322ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:31:25.172675Z","caller":"traceutil/trace.go:171","msg":"trace[1632741700] transaction","detail":"{read_only:false; response_revision:21129; number_of_response:1; }","duration":"262.340663ms","start":"2026-07-31T00:31:24.910299Z","end":"2026-07-31T00:31:25.17264Z","steps":["trace[1632741700] 'process raft request' (duration: 261.705972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:31:25.172851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:31:24.863094Z","time spent":"309.257797ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:31:25.175062Z","caller":"traceutil/trace.go:171","msg":"trace[248091942] transaction","detail":"{read_only:false; response_revision:21130; number_of_response:1; }","duration":"107.859853ms","start":"2026-07-31T00:31:25.067185Z","end":"2026-07-31T00:31:25.175045Z","steps":["trace[248091942] 'process raft request' (duration: 107.483505ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:31:25.301702Z","caller":"traceutil/trace.go:171","msg":"trace[1402490186] transaction","detail":"{read_only:false; response_revision:21131; number_of_response:1; }","duration":"117.227512ms","start":"2026-07-31T00:31:25.184413Z","end":"2026-07-31T00:31:25.30164Z","steps":["trace[1402490186] 'process raft request' (duration: 116.948236ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:33:14.39982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19827} {"level":"info","ts":"2026-07-31T00:33:14.519879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19827,"took":"113.807097ms","hash":4032249687,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":30154752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-31T00:33:14.519975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4032249687,"revision":19827,"compact-revision":17463} {"level":"info","ts":"2026-07-31T00:38:14.406611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22049} {"level":"info","ts":"2026-07-31T00:38:14.522564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22049,"took":"110.119462ms","hash":367746992,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":44920832,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-31T00:38:14.522636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":367746992,"revision":22049,"compact-revision":19827} {"level":"info","ts":"2026-07-31T00:39:08.901133Z","caller":"traceutil/trace.go:171","msg":"trace[1680856596] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"135.599876ms","start":"2026-07-31T00:39:08.765494Z","end":"2026-07-31T00:39:08.901094Z","steps":["trace[1680856596] 'process raft request' (duration: 135.27426ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:09.316788Z","caller":"traceutil/trace.go:171","msg":"trace[682754512] linearizableReadLoop","detail":"{readStateIndex:26674; appliedIndex:26671; }","duration":"224.310256ms","start":"2026-07-31T00:39:09.092441Z","end":"2026-07-31T00:39:09.316751Z","steps":["trace[682754512] 'read index received' (duration: 88.515977ms)","trace[682754512] 'applied index is now lower than readState.Index' (duration: 135.792279ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:09.316999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.529341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:09.317039Z","caller":"traceutil/trace.go:171","msg":"trace[997956223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25818; }","duration":"224.649513ms","start":"2026-07-31T00:39:09.092377Z","end":"2026-07-31T00:39:09.317026Z","steps":["trace[997956223] 'agreement among raft nodes before linearized reading' (duration: 224.539721ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:09.317001Z","caller":"traceutil/trace.go:171","msg":"trace[1811191179] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"225.789715ms","start":"2026-07-31T00:39:09.091171Z","end":"2026-07-31T00:39:09.31696Z","steps":["trace[1811191179] 'process raft request' (duration: 225.51247ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:09.31706Z","caller":"traceutil/trace.go:171","msg":"trace[1483029171] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"227.54756ms","start":"2026-07-31T00:39:09.089465Z","end":"2026-07-31T00:39:09.317012Z","steps":["trace[1483029171] 'process raft request' (duration: 195.790124ms)","trace[1483029171] 'compare' (duration: 31.256546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:09.317194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.24404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:09.317312Z","caller":"traceutil/trace.go:171","msg":"trace[646077009] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25818; }","duration":"125.443964ms","start":"2026-07-31T00:39:09.191839Z","end":"2026-07-31T00:39:09.317283Z","steps":["trace[646077009] 'agreement among raft nodes before linearized reading' (duration: 125.21969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:09.5941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.627982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11793"} {"level":"info","ts":"2026-07-31T00:39:09.594214Z","caller":"traceutil/trace.go:171","msg":"trace[901818098] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25818; }","duration":"117.826536ms","start":"2026-07-31T00:39:09.476361Z","end":"2026-07-31T00:39:09.594187Z","steps":["trace[901818098] 'range keys from in-memory index tree' (duration: 117.148112ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.108648Z","caller":"traceutil/trace.go:171","msg":"trace[1579380344] linearizableReadLoop","detail":"{readStateIndex:26676; appliedIndex:26675; }","duration":"124.113929ms","start":"2026-07-31T00:39:09.984505Z","end":"2026-07-31T00:39:10.108619Z","steps":["trace[1579380344] 'read index received' (duration: 123.912375ms)","trace[1579380344] 'applied index is now lower than readState.Index' (duration: 200.444µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:10.108889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.039558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-31T00:39:10.108896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.364544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-31T00:39:10.108924Z","caller":"traceutil/trace.go:171","msg":"trace[39321331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25820; }","duration":"121.10165ms","start":"2026-07-31T00:39:09.987812Z","end":"2026-07-31T00:39:10.108913Z","steps":["trace[39321331] 'agreement among raft nodes before linearized reading' (duration: 120.969767ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.108949Z","caller":"traceutil/trace.go:171","msg":"trace[547191451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25820; }","duration":"124.440565ms","start":"2026-07-31T00:39:09.984492Z","end":"2026-07-31T00:39:10.108933Z","steps":["trace[547191451] 'agreement among raft nodes before linearized reading' (duration: 124.235321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:10.108957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.850269ms","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-07-31T00:39:10.10901Z","caller":"traceutil/trace.go:171","msg":"trace[2068365501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25820; }","duration":"108.92628ms","start":"2026-07-31T00:39:10.00007Z","end":"2026-07-31T00:39:10.108996Z","steps":["trace[2068365501] 'agreement among raft nodes before linearized reading' (duration: 108.779507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:10.109103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.616931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-31T00:39:10.109197Z","caller":"traceutil/trace.go:171","msg":"trace[810801818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25820; }","duration":"119.719133ms","start":"2026-07-31T00:39:09.989442Z","end":"2026-07-31T00:39:10.109161Z","steps":["trace[810801818] 'agreement among raft nodes before linearized reading' (duration: 119.500399ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.109402Z","caller":"traceutil/trace.go:171","msg":"trace[1391960621] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"129.959654ms","start":"2026-07-31T00:39:09.979123Z","end":"2026-07-31T00:39:10.109082Z","steps":["trace[1391960621] 'process raft request' (duration: 129.317722ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.302732Z","caller":"traceutil/trace.go:171","msg":"trace[1825137500] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"188.573271ms","start":"2026-07-31T00:39:10.114127Z","end":"2026-07-31T00:39:10.3027Z","steps":["trace[1825137500] 'process raft request' (duration: 188.321796ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.314675Z","caller":"traceutil/trace.go:171","msg":"trace[1472164158] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"200.214461ms","start":"2026-07-31T00:39:10.114432Z","end":"2026-07-31T00:39:10.314646Z","steps":["trace[1472164158] 'process raft request' (duration: 199.956865ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.314798Z","caller":"traceutil/trace.go:171","msg":"trace[1826118016] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"199.418976ms","start":"2026-07-31T00:39:10.115355Z","end":"2026-07-31T00:39:10.314774Z","steps":["trace[1826118016] 'process raft request' (duration: 199.209491ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.314761Z","caller":"traceutil/trace.go:171","msg":"trace[601009377] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"155.691343ms","start":"2026-07-31T00:39:10.15904Z","end":"2026-07-31T00:39:10.314731Z","steps":["trace[601009377] 'process raft request' (duration: 155.640582ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.31485Z","caller":"traceutil/trace.go:171","msg":"trace[1125475555] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"198.1336ms","start":"2026-07-31T00:39:10.11669Z","end":"2026-07-31T00:39:10.314824Z","steps":["trace[1125475555] 'process raft request' (duration: 197.941536ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:10.886595Z","caller":"traceutil/trace.go:171","msg":"trace[973373928] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"226.700664ms","start":"2026-07-31T00:39:10.659852Z","end":"2026-07-31T00:39:10.886553Z","steps":["trace[973373928] 'process raft request' (duration: 226.220524ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:11.07498Z","caller":"traceutil/trace.go:171","msg":"trace[705035049] linearizableReadLoop","detail":"{readStateIndex:26684; appliedIndex:26683; }","duration":"124.480317ms","start":"2026-07-31T00:39:10.950475Z","end":"2026-07-31T00:39:11.074956Z","steps":["trace[705035049] 'read index received' (duration: 123.649621ms)","trace[705035049] 'applied index is now lower than readState.Index' (duration: 829.956µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:11.07514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.65006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:39:11.075169Z","caller":"traceutil/trace.go:171","msg":"trace[824750393] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25828; }","duration":"124.759793ms","start":"2026-07-31T00:39:10.9504Z","end":"2026-07-31T00:39:11.07516Z","steps":["trace[824750393] 'agreement among raft nodes before linearized reading' (duration: 124.648491ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:11.075132Z","caller":"traceutil/trace.go:171","msg":"trace[1357362761] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"167.371923ms","start":"2026-07-31T00:39:10.907728Z","end":"2026-07-31T00:39:11.0751Z","steps":["trace[1357362761] 'process raft request' (duration: 166.477055ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:12.037215Z","caller":"traceutil/trace.go:171","msg":"trace[81583074] linearizableReadLoop","detail":"{readStateIndex:26688; appliedIndex:26687; }","duration":"273.023998ms","start":"2026-07-31T00:39:11.764167Z","end":"2026-07-31T00:39:12.037191Z","steps":["trace[81583074] 'read index received' (duration: 272.860284ms)","trace[81583074] 'applied index is now lower than readState.Index' (duration: 162.544µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:12.03731Z","caller":"traceutil/trace.go:171","msg":"trace[1709592795] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"435.427242ms","start":"2026-07-31T00:39:11.601834Z","end":"2026-07-31T00:39:12.037261Z","steps":["trace[1709592795] 'process raft request' (duration: 435.184357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.037421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.238931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:12.037456Z","caller":"traceutil/trace.go:171","msg":"trace[1560384138] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25832; }","duration":"273.346164ms","start":"2026-07-31T00:39:11.764098Z","end":"2026-07-31T00:39:12.037444Z","steps":["trace[1560384138] 'agreement among raft nodes before linearized reading' (duration: 273.258242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.037487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:11.601799Z","time spent":"435.587045ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:12.03764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.448646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-07-31T00:39:12.037752Z","caller":"traceutil/trace.go:171","msg":"trace[464418] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:25832; }","duration":"124.62105ms","start":"2026-07-31T00:39:11.913103Z","end":"2026-07-31T00:39:12.037724Z","steps":["trace[464418] 'agreement among raft nodes before linearized reading' (duration: 124.292493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.248434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.689841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:12.248548Z","caller":"traceutil/trace.go:171","msg":"trace[216588448] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25832; }","duration":"162.838623ms","start":"2026-07-31T00:39:12.085681Z","end":"2026-07-31T00:39:12.24852Z","steps":["trace[216588448] 'range keys from in-memory index tree' (duration: 162.562128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.24864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.050476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-31T00:39:12.248536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.427947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T00:39:12.248691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.074015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-31T00:39:12.248739Z","caller":"traceutil/trace.go:171","msg":"trace[577961287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25832; }","duration":"154.640931ms","start":"2026-07-31T00:39:12.094083Z","end":"2026-07-31T00:39:12.248724Z","steps":["trace[577961287] 'range keys from in-memory index tree' (duration: 154.333175ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:12.24874Z","caller":"traceutil/trace.go:171","msg":"trace[95755980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25832; }","duration":"134.164458ms","start":"2026-07-31T00:39:12.114552Z","end":"2026-07-31T00:39:12.248717Z","steps":["trace[95755980] 'range keys from in-memory index tree' (duration: 133.813581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.248751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.956866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:12.248778Z","caller":"traceutil/trace.go:171","msg":"trace[1317014922] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25832; }","duration":"139.213267ms","start":"2026-07-31T00:39:12.109544Z","end":"2026-07-31T00:39:12.248757Z","steps":["trace[1317014922] 'range keys from in-memory index tree' (duration: 138.83579ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:12.248818Z","caller":"traceutil/trace.go:171","msg":"trace[1034304844] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25832; }","duration":"164.081359ms","start":"2026-07-31T00:39:12.084716Z","end":"2026-07-31T00:39:12.248797Z","steps":["trace[1034304844] 'count revisions from in-memory index tree' (duration: 163.749592ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:12.645932Z","caller":"traceutil/trace.go:171","msg":"trace[1683482935] transaction","detail":"{read_only:false; response_revision:25837; number_of_response:1; }","duration":"230.195502ms","start":"2026-07-31T00:39:12.4157Z","end":"2026-07-31T00:39:12.645895Z","steps":["trace[1683482935] 'process raft request' (duration: 229.861045ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:12.646108Z","caller":"traceutil/trace.go:171","msg":"trace[110627262] linearizableReadLoop","detail":"{readStateIndex:26694; appliedIndex:26692; }","duration":"210.868751ms","start":"2026-07-31T00:39:12.435206Z","end":"2026-07-31T00:39:12.646075Z","steps":["trace[110627262] 'read index received' (duration: 210.332041ms)","trace[110627262] 'applied index is now lower than readState.Index' (duration: 534.13µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:12.646131Z","caller":"traceutil/trace.go:171","msg":"trace[1225216145] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"230.324505ms","start":"2026-07-31T00:39:12.415767Z","end":"2026-07-31T00:39:12.646092Z","steps":["trace[1225216145] 'process raft request' (duration: 230.054019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.646406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.179878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-31T00:39:12.64651Z","caller":"traceutil/trace.go:171","msg":"trace[2130821670] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25838; }","duration":"211.29596ms","start":"2026-07-31T00:39:12.43519Z","end":"2026-07-31T00:39:12.646486Z","steps":["trace[2130821670] 'agreement among raft nodes before linearized reading' (duration: 211.012455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.795684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.725989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-31T00:39:12.796296Z","caller":"traceutil/trace.go:171","msg":"trace[403438159] linearizableReadLoop","detail":"{readStateIndex:26695; appliedIndex:26694; }","duration":"150.068381ms","start":"2026-07-31T00:39:12.646175Z","end":"2026-07-31T00:39:12.796244Z","steps":["trace[403438159] 'read index received' (duration: 16.840182ms)","trace[403438159] 'applied index is now lower than readState.Index' (duration: 133.218029ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:12.796612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.019802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-31T00:39:12.796648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.150469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:39:12.796712Z","caller":"traceutil/trace.go:171","msg":"trace[1994933320] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25839; }","duration":"255.173727ms","start":"2026-07-31T00:39:12.541517Z","end":"2026-07-31T00:39:12.79669Z","steps":["trace[1994933320] 'agreement among raft nodes before linearized reading' (duration: 254.834429ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:12.796733Z","caller":"traceutil/trace.go:171","msg":"trace[1647450486] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25839; }","duration":"352.273852ms","start":"2026-07-31T00:39:12.444439Z","end":"2026-07-31T00:39:12.796713Z","steps":["trace[1647450486] 'agreement among raft nodes before linearized reading' (duration: 351.957945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:12.796779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:12.444431Z","time spent":"352.337873ms","remote":"127.0.0.1:41116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-07-31T00:39:12.796916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.744575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-07-31T00:39:12.797067Z","caller":"traceutil/trace.go:171","msg":"trace[491359139] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25839; }","duration":"148.94626ms","start":"2026-07-31T00:39:12.648093Z","end":"2026-07-31T00:39:12.797039Z","steps":["trace[491359139] 'agreement among raft nodes before linearized reading' (duration: 148.423889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:13.093608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.850657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:13.093736Z","caller":"traceutil/trace.go:171","msg":"trace[740635105] linearizableReadLoop","detail":"{readStateIndex:26697; appliedIndex:26696; }","duration":"149.481311ms","start":"2026-07-31T00:39:12.944224Z","end":"2026-07-31T00:39:13.093705Z","steps":["trace[740635105] 'read index received' (duration: 2.317616ms)","trace[740635105] 'applied index is now lower than readState.Index' (duration: 147.161825ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:13.093738Z","caller":"traceutil/trace.go:171","msg":"trace[1837742268] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"200.364354ms","start":"2026-07-31T00:39:12.893347Z","end":"2026-07-31T00:39:13.093712Z","steps":["trace[1837742268] 'process raft request' (duration: 53.278491ms)","trace[1837742268] 'compare' (duration: 146.554431ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:13.093806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.580722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:13.093833Z","caller":"traceutil/trace.go:171","msg":"trace[1122744275] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25840; }","duration":"149.662194ms","start":"2026-07-31T00:39:12.944162Z","end":"2026-07-31T00:39:13.093824Z","steps":["trace[1122744275] 'agreement among raft nodes before linearized reading' (duration: 149.599313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:13.394904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.999481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:13.395023Z","caller":"traceutil/trace.go:171","msg":"trace[1067592667] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"208.124097ms","start":"2026-07-31T00:39:13.186878Z","end":"2026-07-31T00:39:13.395002Z","steps":["trace[1067592667] 'process raft request' (duration: 60.496313ms)","trace[1067592667] 'compare' (duration: 146.557273ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:13.407317Z","caller":"traceutil/trace.go:171","msg":"trace[1338743722] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"137.78238ms","start":"2026-07-31T00:39:13.269506Z","end":"2026-07-31T00:39:13.407288Z","steps":["trace[1338743722] 'process raft request' (duration: 137.591725ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:14.169218Z","caller":"traceutil/trace.go:171","msg":"trace[192658540] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"126.031637ms","start":"2026-07-31T00:39:14.043131Z","end":"2026-07-31T00:39:14.169163Z","steps":["trace[192658540] 'process raft request' (duration: 125.042648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:19.676499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.381406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:19.676625Z","caller":"traceutil/trace.go:171","msg":"trace[823841529] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"382.002938ms","start":"2026-07-31T00:39:19.294599Z","end":"2026-07-31T00:39:19.676602Z","steps":["trace[823841529] 'process raft request' (duration: 267.433538ms)","trace[823841529] 'compare' (duration: 113.862506ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:19.676692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:19.294568Z","time spent":"382.09259ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:19.942903Z","caller":"traceutil/trace.go:171","msg":"trace[1048270290] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"517.461931ms","start":"2026-07-31T00:39:19.42541Z","end":"2026-07-31T00:39:19.942872Z","steps":["trace[1048270290] 'process raft request' (duration: 516.92896ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:19.942998Z","caller":"traceutil/trace.go:171","msg":"trace[58812686] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"516.668756ms","start":"2026-07-31T00:39:19.426311Z","end":"2026-07-31T00:39:19.94298Z","steps":["trace[58812686] 'process raft request' (duration: 516.495912ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:19.942998Z","caller":"traceutil/trace.go:171","msg":"trace[1849851685] linearizableReadLoop","detail":"{readStateIndex:26750; appliedIndex:26747; }","duration":"238.510087ms","start":"2026-07-31T00:39:19.704461Z","end":"2026-07-31T00:39:19.942971Z","steps":["trace[1849851685] 'read index received' (duration: 237.843833ms)","trace[1849851685] 'applied index is now lower than readState.Index' (duration: 664.484µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:19.943024Z","caller":"traceutil/trace.go:171","msg":"trace[1609756391] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"514.039284ms","start":"2026-07-31T00:39:19.428947Z","end":"2026-07-31T00:39:19.942987Z","steps":["trace[1609756391] 'process raft request' (duration: 513.945093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:19.943067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:19.425378Z","time spent":"517.593984ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:19.943174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:19.428923Z","time spent":"514.174317ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:19.943173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:19.42628Z","time spent":"516.772308ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:19.943192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.715301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:19.943232Z","caller":"traceutil/trace.go:171","msg":"trace[1765136478] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25892; }","duration":"238.805043ms","start":"2026-07-31T00:39:19.704414Z","end":"2026-07-31T00:39:19.943219Z","steps":["trace[1765136478] 'agreement among raft nodes before linearized reading' (duration: 238.718241ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:28.234215Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068e3.wal"} {"level":"info","ts":"2026-07-31T00:39:29.019711Z","caller":"traceutil/trace.go:171","msg":"trace[1072309001] linearizableReadLoop","detail":"{readStateIndex:26870; appliedIndex:26869; }","duration":"276.14755ms","start":"2026-07-31T00:39:28.743529Z","end":"2026-07-31T00:39:29.019676Z","steps":["trace[1072309001] 'read index received' (duration: 275.950676ms)","trace[1072309001] 'applied index is now lower than readState.Index' (duration: 191.834µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:29.019811Z","caller":"traceutil/trace.go:171","msg":"trace[392693050] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"290.101746ms","start":"2026-07-31T00:39:28.729664Z","end":"2026-07-31T00:39:29.019765Z","steps":["trace[392693050] 'process raft request' (duration: 289.701948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:29.020004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.453885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-31T00:39:29.020062Z","caller":"traceutil/trace.go:171","msg":"trace[917889365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26009; }","duration":"276.560878ms","start":"2026-07-31T00:39:28.743483Z","end":"2026-07-31T00:39:29.020044Z","steps":["trace[917889365] 'agreement among raft nodes before linearized reading' (duration: 276.366514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:29.361811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.782117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:29.361911Z","caller":"traceutil/trace.go:171","msg":"trace[672567084] linearizableReadLoop","detail":"{readStateIndex:26871; appliedIndex:26870; }","duration":"342.07891ms","start":"2026-07-31T00:39:29.019815Z","end":"2026-07-31T00:39:29.361894Z","steps":["trace[672567084] 'read index received' (duration: 197.071968ms)","trace[672567084] 'applied index is now lower than readState.Index' (duration: 145.005432ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:29.36199Z","caller":"traceutil/trace.go:171","msg":"trace[132429548] transaction","detail":"{read_only:false; response_revision:26010; number_of_response:1; }","duration":"502.293751ms","start":"2026-07-31T00:39:28.859681Z","end":"2026-07-31T00:39:29.361975Z","steps":["trace[132429548] 'process raft request' (duration: 357.244949ms)","trace[132429548] 'compare' (duration: 144.42572ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:29.362051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:28.859659Z","time spent":"502.360643ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:29.362276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.697685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:29.362373Z","caller":"traceutil/trace.go:171","msg":"trace[1546427717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26010; }","duration":"482.848258ms","start":"2026-07-31T00:39:28.8795Z","end":"2026-07-31T00:39:29.362348Z","steps":["trace[1546427717] 'agreement among raft nodes before linearized reading' (duration: 482.674634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:29.362421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:28.879491Z","time spent":"482.921819ms","remote":"127.0.0.1:50838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T00:39:29.362769Z","caller":"traceutil/trace.go:171","msg":"trace[576918300] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"336.847917ms","start":"2026-07-31T00:39:29.025883Z","end":"2026-07-31T00:39:29.362731Z","steps":["trace[576918300] 'process raft request' (duration: 336.429719ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:29.362874Z","caller":"traceutil/trace.go:171","msg":"trace[1722657326] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"212.043904ms","start":"2026-07-31T00:39:29.150815Z","end":"2026-07-31T00:39:29.362859Z","steps":["trace[1722657326] 'process raft request' (duration: 211.851561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:29.363081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:29.025865Z","time spent":"337.02089ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:29.363188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.937255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-07-31T00:39:29.363226Z","caller":"traceutil/trace.go:171","msg":"trace[1845422350] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26010; }","duration":"614.018236ms","start":"2026-07-31T00:39:28.749195Z","end":"2026-07-31T00:39:29.363213Z","steps":["trace[1845422350] 'agreement among raft nodes before linearized reading' (duration: 613.065928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:29.363246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:28.74917Z","time spent":"614.071458ms","remote":"127.0.0.1:50920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225425,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-31T00:39:29.667723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.07967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:29.66787Z","caller":"traceutil/trace.go:171","msg":"trace[1196602214] linearizableReadLoop","detail":"{readStateIndex:26874; appliedIndex:26873; }","duration":"305.910516ms","start":"2026-07-31T00:39:29.361938Z","end":"2026-07-31T00:39:29.667849Z","steps":["trace[1196602214] 'read index received' (duration: 144.581272ms)","trace[1196602214] 'applied index is now lower than readState.Index' (duration: 161.327144ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:29.667962Z","caller":"traceutil/trace.go:171","msg":"trace[1096238313] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"429.353982ms","start":"2026-07-31T00:39:29.238566Z","end":"2026-07-31T00:39:29.66792Z","steps":["trace[1096238313] 'process raft request' (duration: 267.943587ms)","trace[1096238313] 'compare' (duration: 160.62477ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:29.668154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:29.238539Z","time spent":"429.511215ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:29.668193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.715652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-31T00:39:29.668236Z","caller":"traceutil/trace.go:171","msg":"trace[1082184283] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426; range_end:; response_count:1; response_revision:26013; }","duration":"420.791393ms","start":"2026-07-31T00:39:29.247428Z","end":"2026-07-31T00:39:29.668219Z","steps":["trace[1082184283] 'agreement among raft nodes before linearized reading' (duration: 420.580369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:29.668289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:29.247416Z","time spent":"420.863245ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426\" "} {"level":"warn","ts":"2026-07-31T00:39:29.668503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.002727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:29.668546Z","caller":"traceutil/trace.go:171","msg":"trace[995976546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26013; }","duration":"574.080819ms","start":"2026-07-31T00:39:29.094454Z","end":"2026-07-31T00:39:29.668534Z","steps":["trace[995976546] 'agreement among raft nodes before linearized reading' (duration: 574.002688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:29.668576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:29.094436Z","time spent":"574.13487ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T00:39:29.668585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.255522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-07-31T00:39:29.66867Z","caller":"traceutil/trace.go:171","msg":"trace[1650120090] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:26013; }","duration":"271.405375ms","start":"2026-07-31T00:39:29.397242Z","end":"2026-07-31T00:39:29.668647Z","steps":["trace[1650120090] 'agreement among raft nodes before linearized reading' (duration: 271.14679ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:30.01759Z","caller":"traceutil/trace.go:171","msg":"trace[669020727] linearizableReadLoop","detail":"{readStateIndex:26875; appliedIndex:26874; }","duration":"206.903763ms","start":"2026-07-31T00:39:29.810656Z","end":"2026-07-31T00:39:30.01756Z","steps":["trace[669020727] 'read index received' (duration: 206.591987ms)","trace[669020727] 'applied index is now lower than readState.Index' (duration: 310.566µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:30.017789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.103438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:30.017838Z","caller":"traceutil/trace.go:171","msg":"trace[2084348284] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:26014; }","duration":"207.211739ms","start":"2026-07-31T00:39:29.810611Z","end":"2026-07-31T00:39:30.017823Z","steps":["trace[2084348284] 'agreement among raft nodes before linearized reading' (duration: 207.067146ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:30.018346Z","caller":"traceutil/trace.go:171","msg":"trace[645342912] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"277.240501ms","start":"2026-07-31T00:39:29.741091Z","end":"2026-07-31T00:39:30.018332Z","steps":["trace[645342912] 'process raft request' (duration: 276.258022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:30.454529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.647264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:30.454758Z","caller":"traceutil/trace.go:171","msg":"trace[1886522770] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"403.149666ms","start":"2026-07-31T00:39:30.051573Z","end":"2026-07-31T00:39:30.454723Z","steps":["trace[1886522770] 'process raft request' (duration: 218.211086ms)","trace[1886522770] 'compare' (duration: 184.269417ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:30.454808Z","caller":"traceutil/trace.go:171","msg":"trace[1096828165] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"213.449732ms","start":"2026-07-31T00:39:30.241331Z","end":"2026-07-31T00:39:30.45478Z","steps":["trace[1096828165] 'process raft request' (duration: 213.388671ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:30.454789Z","caller":"traceutil/trace.go:171","msg":"trace[550234330] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"402.089985ms","start":"2026-07-31T00:39:30.052647Z","end":"2026-07-31T00:39:30.454737Z","steps":["trace[550234330] 'process raft request' (duration: 401.994373ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:30.454842Z","caller":"traceutil/trace.go:171","msg":"trace[38423862] linearizableReadLoop","detail":"{readStateIndex:26878; appliedIndex:26875; }","duration":"361.054305ms","start":"2026-07-31T00:39:30.09375Z","end":"2026-07-31T00:39:30.454804Z","steps":["trace[38423862] 'read index received' (duration: 17.923413ms)","trace[38423862] 'applied index is now lower than readState.Index' (duration: 343.127512ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:30.454958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.051547Z","time spent":"403.293649ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:30.454994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.052638Z","time spent":"402.244399ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:30.455082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:30.455126Z","caller":"traceutil/trace.go:171","msg":"trace[2039419296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26018; }","duration":"361.418012ms","start":"2026-07-31T00:39:30.093696Z","end":"2026-07-31T00:39:30.455114Z","steps":["trace[2039419296] 'agreement among raft nodes before linearized reading' (duration: 361.29197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:30.455147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.336001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-07-31T00:39:30.455174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.093682Z","time spent":"361.483473ms","remote":"127.0.0.1:50838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T00:39:30.455221Z","caller":"traceutil/trace.go:171","msg":"trace[1980449135] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26018; }","duration":"169.491894ms","start":"2026-07-31T00:39:30.285709Z","end":"2026-07-31T00:39:30.455201Z","steps":["trace[1980449135] 'agreement among raft nodes before linearized reading' (duration: 169.30562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:30.707576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.763296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:30.707763Z","caller":"traceutil/trace.go:171","msg":"trace[1956163883] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"247.78635ms","start":"2026-07-31T00:39:30.459936Z","end":"2026-07-31T00:39:30.707722Z","steps":["trace[1956163883] 'process raft request' (duration: 138.029694ms)","trace[1956163883] 'compare' (duration: 109.074232ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:30.707903Z","caller":"traceutil/trace.go:171","msg":"trace[1491128684] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"220.215675ms","start":"2026-07-31T00:39:30.487664Z","end":"2026-07-31T00:39:30.70788Z","steps":["trace[1491128684] 'process raft request' (duration: 220.081372ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:30.708066Z","caller":"traceutil/trace.go:171","msg":"trace[1806428391] linearizableReadLoop","detail":"{readStateIndex:26880; appliedIndex:26879; }","duration":"247.994083ms","start":"2026-07-31T00:39:30.460019Z","end":"2026-07-31T00:39:30.708013Z","steps":["trace[1806428391] 'read index received' (duration: 137.931002ms)","trace[1806428391] 'applied index is now lower than readState.Index' (duration: 110.058801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:30.70846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.409821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-07-31T00:39:30.708803Z","caller":"traceutil/trace.go:171","msg":"trace[105553677] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26020; }","duration":"248.762729ms","start":"2026-07-31T00:39:30.460001Z","end":"2026-07-31T00:39:30.708764Z","steps":["trace[105553677] 'agreement among raft nodes before linearized reading' (duration: 248.171036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.024677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.531714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:31.024928Z","caller":"traceutil/trace.go:171","msg":"trace[1306046900] linearizableReadLoop","detail":"{readStateIndex:26882; appliedIndex:26881; }","duration":"316.719669ms","start":"2026-07-31T00:39:30.708185Z","end":"2026-07-31T00:39:31.024905Z","steps":["trace[1306046900] 'read index received' (duration: 178.853679ms)","trace[1306046900] 'applied index is now lower than readState.Index' (duration: 137.86295ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:31.025187Z","caller":"traceutil/trace.go:171","msg":"trace[85334474] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"415.899918ms","start":"2026-07-31T00:39:30.609221Z","end":"2026-07-31T00:39:31.025121Z","steps":["trace[85334474] 'process raft request' (duration: 277.814682ms)","trace[85334474] 'compare' (duration: 137.001634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:31.025353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.782836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-31T00:39:31.025419Z","caller":"traceutil/trace.go:171","msg":"trace[1062288486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26021; }","duration":"524.916989ms","start":"2026-07-31T00:39:30.500487Z","end":"2026-07-31T00:39:31.025404Z","steps":["trace[1062288486] 'agreement among raft nodes before linearized reading' (duration: 524.573521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.025412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.19673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-07-31T00:39:31.025483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.609193Z","time spent":"416.108931ms","remote":"127.0.0.1:50900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T00:39:31.025553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.500437Z","time spent":"525.063712ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T00:39:31.02556Z","caller":"traceutil/trace.go:171","msg":"trace[292716627] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426; range_end:; response_count:1; response_revision:26021; }","duration":"352.401294ms","start":"2026-07-31T00:39:30.673126Z","end":"2026-07-31T00:39:31.025528Z","steps":["trace[292716627] 'agreement among raft nodes before linearized reading' (duration: 351.910954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.025307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.931703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-31T00:39:31.025669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.673109Z","time spent":"352.538817ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426\" "} {"level":"info","ts":"2026-07-31T00:39:31.025768Z","caller":"traceutil/trace.go:171","msg":"trace[2078986280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26021; }","duration":"514.429882ms","start":"2026-07-31T00:39:30.511293Z","end":"2026-07-31T00:39:31.025723Z","steps":["trace[2078986280] 'agreement among raft nodes before linearized reading' (duration: 513.771509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.025909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.511259Z","time spent":"514.600235ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T00:39:31.0265Z","caller":"traceutil/trace.go:171","msg":"trace[1746190289] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"262.402038ms","start":"2026-07-31T00:39:30.764081Z","end":"2026-07-31T00:39:31.026483Z","steps":["trace[1746190289] 'process raft request' (duration: 262.230895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.026555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.811719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-31T00:39:31.026559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.765844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:31.026622Z","caller":"traceutil/trace.go:171","msg":"trace[819844189] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26022; }","duration":"103.92636ms","start":"2026-07-31T00:39:30.922681Z","end":"2026-07-31T00:39:31.026608Z","steps":["trace[819844189] 'agreement among raft nodes before linearized reading' (duration: 103.760397ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:31.026651Z","caller":"traceutil/trace.go:171","msg":"trace[1183409779] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:26022; }","duration":"227.899997ms","start":"2026-07-31T00:39:30.798731Z","end":"2026-07-31T00:39:31.026631Z","steps":["trace[1183409779] 'agreement among raft nodes before linearized reading' (duration: 227.736394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.026561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.179122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:39:31.026793Z","caller":"traceutil/trace.go:171","msg":"trace[58679199] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26022; }","duration":"120.425997ms","start":"2026-07-31T00:39:30.906309Z","end":"2026-07-31T00:39:31.026735Z","steps":["trace[58679199] 'agreement among raft nodes before linearized reading' (duration: 120.151161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.026811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.20851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-07-31T00:39:31.02694Z","caller":"traceutil/trace.go:171","msg":"trace[826962421] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:26022; }","duration":"312.346433ms","start":"2026-07-31T00:39:30.714551Z","end":"2026-07-31T00:39:31.026898Z","steps":["trace[826962421] 'agreement among raft nodes before linearized reading' (duration: 311.877264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.027001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:30.714529Z","time spent":"312.444685ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1281,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"warn","ts":"2026-07-31T00:39:31.293703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.088076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-07-31T00:39:31.293805Z","caller":"traceutil/trace.go:171","msg":"trace[205856482] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26026; }","duration":"167.25615ms","start":"2026-07-31T00:39:31.126526Z","end":"2026-07-31T00:39:31.293783Z","steps":["trace[205856482] 'range keys from in-memory index tree' (duration: 166.836171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.293864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.667938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18c738ed1c662824\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-07-31T00:39:31.293992Z","caller":"traceutil/trace.go:171","msg":"trace[799285279] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18c738ed1c662824; range_end:; response_count:1; response_revision:26026; }","duration":"166.846391ms","start":"2026-07-31T00:39:31.127117Z","end":"2026-07-31T00:39:31.293963Z","steps":["trace[799285279] 'range keys from in-memory index tree' (duration: 166.464544ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:31.486764Z","caller":"traceutil/trace.go:171","msg":"trace[1084121250] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"189.947459ms","start":"2026-07-31T00:39:31.296786Z","end":"2026-07-31T00:39:31.486734Z","steps":["trace[1084121250] 'process raft request' (duration: 189.735395ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:31.487167Z","caller":"traceutil/trace.go:171","msg":"trace[765896201] linearizableReadLoop","detail":"{readStateIndex:26888; appliedIndex:26888; }","duration":"179.424331ms","start":"2026-07-31T00:39:31.307699Z","end":"2026-07-31T00:39:31.487123Z","steps":["trace[765896201] 'read index received' (duration: 179.40631ms)","trace[765896201] 'applied index is now lower than readState.Index' (duration: 15.461µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:31.487436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.723677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9604"} {"level":"info","ts":"2026-07-31T00:39:31.487475Z","caller":"traceutil/trace.go:171","msg":"trace[1935679487] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26027; }","duration":"179.825029ms","start":"2026-07-31T00:39:31.307641Z","end":"2026-07-31T00:39:31.487466Z","steps":["trace[1935679487] 'agreement among raft nodes before linearized reading' (duration: 179.660186ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:31.513607Z","caller":"traceutil/trace.go:171","msg":"trace[1430371482] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"139.236138ms","start":"2026-07-31T00:39:31.374308Z","end":"2026-07-31T00:39:31.513544Z","steps":["trace[1430371482] 'process raft request' (duration: 139.091865ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:31.513613Z","caller":"traceutil/trace.go:171","msg":"trace[937181789] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"143.559563ms","start":"2026-07-31T00:39:31.370012Z","end":"2026-07-31T00:39:31.513572Z","steps":["trace[937181789] 'process raft request' (duration: 140.785538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:31.513681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.229725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-31T00:39:31.513738Z","caller":"traceutil/trace.go:171","msg":"trace[325859001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26029; }","duration":"146.307737ms","start":"2026-07-31T00:39:31.367415Z","end":"2026-07-31T00:39:31.513722Z","steps":["trace[325859001] 'agreement among raft nodes before linearized reading' (duration: 146.113523ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:31.687506Z","caller":"traceutil/trace.go:171","msg":"trace[1792541974] transaction","detail":"{read_only:false; response_revision:26031; number_of_response:1; }","duration":"168.297701ms","start":"2026-07-31T00:39:31.519163Z","end":"2026-07-31T00:39:31.687461Z","steps":["trace[1792541974] 'process raft request' (duration: 154.58263ms)","trace[1792541974] 'compare' (duration: 13.457096ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:31.996437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.278641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:31.996579Z","caller":"traceutil/trace.go:171","msg":"trace[828542100] transaction","detail":"{read_only:false; response_revision:26033; number_of_response:1; }","duration":"298.196084ms","start":"2026-07-31T00:39:31.698358Z","end":"2026-07-31T00:39:31.996554Z","steps":["trace[828542100] 'process raft request' (duration: 166.709149ms)","trace[828542100] 'compare' (duration: 130.955525ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:32.386451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.257808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:32.386568Z","caller":"traceutil/trace.go:171","msg":"trace[490302254] linearizableReadLoop","detail":"{readStateIndex:26895; appliedIndex:26894; }","duration":"288.728049ms","start":"2026-07-31T00:39:32.09782Z","end":"2026-07-31T00:39:32.386548Z","steps":["trace[490302254] 'read index received' (duration: 115.304146ms)","trace[490302254] 'applied index is now lower than readState.Index' (duration: 173.422423ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:32.386654Z","caller":"traceutil/trace.go:171","msg":"trace[1980412475] transaction","detail":"{read_only:false; response_revision:26034; number_of_response:1; }","duration":"288.857371ms","start":"2026-07-31T00:39:32.097785Z","end":"2026-07-31T00:39:32.386642Z","steps":["trace[1980412475] 'process raft request' (duration: 115.319317ms)","trace[1980412475] 'compare' (duration: 172.976883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:32.386895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.078845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:32.386926Z","caller":"traceutil/trace.go:171","msg":"trace[1537844289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26034; }","duration":"289.167387ms","start":"2026-07-31T00:39:32.09775Z","end":"2026-07-31T00:39:32.386917Z","steps":["trace[1537844289] 'agreement among raft nodes before linearized reading' (duration: 289.072775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:32.472152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.032034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:32.472226Z","caller":"traceutil/trace.go:171","msg":"trace[847613782] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"348.049847ms","start":"2026-07-31T00:39:32.124106Z","end":"2026-07-31T00:39:32.472156Z","steps":["trace[847613782] 'process raft request' (duration: 347.67717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:32.472297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.552004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-31T00:39:32.472262Z","caller":"traceutil/trace.go:171","msg":"trace[516223381] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26035; }","duration":"139.212637ms","start":"2026-07-31T00:39:32.333029Z","end":"2026-07-31T00:39:32.472242Z","steps":["trace[516223381] 'agreement among raft nodes before linearized reading' (duration: 139.006473ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:32.472384Z","caller":"traceutil/trace.go:171","msg":"trace[495887290] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426; range_end:; response_count:1; response_revision:26035; }","duration":"363.694396ms","start":"2026-07-31T00:39:32.108667Z","end":"2026-07-31T00:39:32.472361Z","steps":["trace[495887290] 'agreement among raft nodes before linearized reading' (duration: 363.38265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:32.472418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:32.124073Z","time spent":"348.241302ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:32.472432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:32.108646Z","time spent":"363.775239ms","remote":"127.0.0.1:51012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e46d8ce-a0fb-4e0e-a361-f50be1ea1426\" "} {"level":"info","ts":"2026-07-31T00:39:32.572212Z","caller":"traceutil/trace.go:171","msg":"trace[1760015417] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"109.885778ms","start":"2026-07-31T00:39:32.462282Z","end":"2026-07-31T00:39:32.572167Z","steps":["trace[1760015417] 'process raft request' (duration: 109.773926ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:32.572215Z","caller":"traceutil/trace.go:171","msg":"trace[540229858] transaction","detail":"{read_only:false; response_revision:26036; number_of_response:1; }","duration":"111.335677ms","start":"2026-07-31T00:39:32.460837Z","end":"2026-07-31T00:39:32.572173Z","steps":["trace[540229858] 'process raft request' (duration: 110.96457ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:32.572402Z","caller":"traceutil/trace.go:171","msg":"trace[1616716546] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"108.892369ms","start":"2026-07-31T00:39:32.463496Z","end":"2026-07-31T00:39:32.572388Z","steps":["trace[1616716546] 'process raft request' (duration: 108.625773ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:32.572375Z","caller":"traceutil/trace.go:171","msg":"trace[913902520] linearizableReadLoop","detail":"{readStateIndex:26899; appliedIndex:26896; }","duration":"100.394932ms","start":"2026-07-31T00:39:32.471942Z","end":"2026-07-31T00:39:32.572337Z","steps":["trace[913902520] 'read index received' (duration: 100.011924ms)","trace[913902520] 'applied index is now lower than readState.Index' (duration: 380.178µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:32.572634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.116524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:32.572698Z","caller":"traceutil/trace.go:171","msg":"trace[172387884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26038; }","duration":"184.279796ms","start":"2026-07-31T00:39:32.388403Z","end":"2026-07-31T00:39:32.572682Z","steps":["trace[172387884] 'agreement among raft nodes before linearized reading' (duration: 184.126243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:32.579271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.726908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:32.579344Z","caller":"traceutil/trace.go:171","msg":"trace[709080559] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26039; }","duration":"101.850439ms","start":"2026-07-31T00:39:32.477473Z","end":"2026-07-31T00:39:32.579323Z","steps":["trace[709080559] 'agreement among raft nodes before linearized reading' (duration: 101.739747ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:38.905184Z","caller":"traceutil/trace.go:171","msg":"trace[1723127078] transaction","detail":"{read_only:false; response_revision:26107; number_of_response:1; }","duration":"312.872263ms","start":"2026-07-31T00:39:38.592282Z","end":"2026-07-31T00:39:38.905155Z","steps":["trace[1723127078] 'process raft request' (duration: 312.781302ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:38.905234Z","caller":"traceutil/trace.go:171","msg":"trace[428179803] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"340.126541ms","start":"2026-07-31T00:39:38.56507Z","end":"2026-07-31T00:39:38.905197Z","steps":["trace[428179803] 'process raft request' (duration: 334.792967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:38.905364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.565056Z","time spent":"340.226083ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:38.905351Z","caller":"traceutil/trace.go:171","msg":"trace[714139878] transaction","detail":"{read_only:false; response_revision:26108; number_of_response:1; }","duration":"312.618769ms","start":"2026-07-31T00:39:38.592694Z","end":"2026-07-31T00:39:38.905313Z","steps":["trace[714139878] 'process raft request' (duration: 312.412764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:38.905553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.592678Z","time spent":"312.771472ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:38.905408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.59227Z","time spent":"313.003816ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:38.905208Z","caller":"traceutil/trace.go:171","msg":"trace[169760140] transaction","detail":"{read_only:false; response_revision:26106; number_of_response:1; }","duration":"314.089908ms","start":"2026-07-31T00:39:38.591091Z","end":"2026-07-31T00:39:38.905181Z","steps":["trace[169760140] 'process raft request' (duration: 313.870224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:38.905893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.591052Z","time spent":"314.780722ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:39.166491Z","caller":"traceutil/trace.go:171","msg":"trace[1571796135] linearizableReadLoop","detail":"{readStateIndex:26973; appliedIndex:26968; }","duration":"422.927085ms","start":"2026-07-31T00:39:38.743538Z","end":"2026-07-31T00:39:39.166465Z","steps":["trace[1571796135] 'read index received' (duration: 156.344545ms)","trace[1571796135] 'applied index is now lower than readState.Index' (duration: 266.58117ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:39.166655Z","caller":"traceutil/trace.go:171","msg":"trace[677174408] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"428.998355ms","start":"2026-07-31T00:39:38.737642Z","end":"2026-07-31T00:39:39.166641Z","steps":["trace[677174408] 'process raft request' (duration: 428.212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:39.166791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.737607Z","time spent":"429.081377ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:39.166987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.096603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:39.167036Z","caller":"traceutil/trace.go:171","msg":"trace[1771527706] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:26110; }","duration":"331.187815ms","start":"2026-07-31T00:39:38.835834Z","end":"2026-07-31T00:39:39.167022Z","steps":["trace[1771527706] 'agreement among raft nodes before linearized reading' (duration: 331.107084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:39.16701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.450686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-31T00:39:39.16714Z","caller":"traceutil/trace.go:171","msg":"trace[1494566876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26110; }","duration":"423.637089ms","start":"2026-07-31T00:39:38.743469Z","end":"2026-07-31T00:39:39.167106Z","steps":["trace[1494566876] 'agreement among raft nodes before linearized reading' (duration: 423.272862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:39.167211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.74342Z","time spent":"423.775992ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T00:39:39.167324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.318626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:39:39.167371Z","caller":"traceutil/trace.go:171","msg":"trace[479842032] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26110; }","duration":"225.411768ms","start":"2026-07-31T00:39:38.941948Z","end":"2026-07-31T00:39:39.16736Z","steps":["trace[479842032] 'agreement among raft nodes before linearized reading' (duration: 225.297386ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:39.167406Z","caller":"traceutil/trace.go:171","msg":"trace[1446917841] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"423.730511ms","start":"2026-07-31T00:39:38.743655Z","end":"2026-07-31T00:39:39.167385Z","steps":["trace[1446917841] 'process raft request' (duration: 422.682211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:39.167593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.743641Z","time spent":"423.844703ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:39.167631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.003728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:39.167669Z","caller":"traceutil/trace.go:171","msg":"trace[1416089555] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:26110; }","duration":"271.08965ms","start":"2026-07-31T00:39:38.896569Z","end":"2026-07-31T00:39:39.167659Z","steps":["trace[1416089555] 'agreement among raft nodes before linearized reading' (duration: 271.007038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:39.167069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:38.835808Z","time spent":"331.252856ms","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"warn","ts":"2026-07-31T00:39:39.167866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.313377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:39:39.167905Z","caller":"traceutil/trace.go:171","msg":"trace[1461541795] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26110; }","duration":"293.4101ms","start":"2026-07-31T00:39:38.874485Z","end":"2026-07-31T00:39:39.167895Z","steps":["trace[1461541795] 'agreement among raft nodes before linearized reading' (duration: 293.338339ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:39.370394Z","caller":"traceutil/trace.go:171","msg":"trace[1526701763] transaction","detail":"{read_only:false; response_revision:26112; number_of_response:1; }","duration":"197.194702ms","start":"2026-07-31T00:39:39.173151Z","end":"2026-07-31T00:39:39.370346Z","steps":["trace[1526701763] 'process raft request' (duration: 174.391991ms)","trace[1526701763] 'compare' (duration: 22.165708ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:39.370424Z","caller":"traceutil/trace.go:171","msg":"trace[1825847040] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"196.966198ms","start":"2026-07-31T00:39:39.173417Z","end":"2026-07-31T00:39:39.370383Z","steps":["trace[1825847040] 'process raft request' (duration: 196.63074ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:39.370521Z","caller":"traceutil/trace.go:171","msg":"trace[103811169] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"196.556499ms","start":"2026-07-31T00:39:39.173915Z","end":"2026-07-31T00:39:39.370472Z","steps":["trace[103811169] 'process raft request' (duration: 196.261953ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:47.300567Z","caller":"traceutil/trace.go:171","msg":"trace[95830845] transaction","detail":"{read_only:false; response_revision:26173; number_of_response:1; }","duration":"104.515995ms","start":"2026-07-31T00:39:47.196011Z","end":"2026-07-31T00:39:47.300527Z","steps":["trace[95830845] 'process raft request' (duration: 104.194499ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:47.565461Z","caller":"traceutil/trace.go:171","msg":"trace[289519537] linearizableReadLoop","detail":"{readStateIndex:27039; appliedIndex:27038; }","duration":"156.443792ms","start":"2026-07-31T00:39:47.408985Z","end":"2026-07-31T00:39:47.565428Z","steps":["trace[289519537] 'read index received' (duration: 142.80287ms)","trace[289519537] 'applied index is now lower than readState.Index' (duration: 13.639162ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:47.565532Z","caller":"traceutil/trace.go:171","msg":"trace[999484660] transaction","detail":"{read_only:false; response_revision:26174; number_of_response:1; }","duration":"260.371076ms","start":"2026-07-31T00:39:47.305096Z","end":"2026-07-31T00:39:47.565467Z","steps":["trace[999484660] 'process raft request' (duration: 246.761934ms)","trace[999484660] 'compare' (duration: 13.412508ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:47.565695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.688066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-31T00:39:47.565767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.878661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-31T00:39:47.565778Z","caller":"traceutil/trace.go:171","msg":"trace[1876379678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26174; }","duration":"156.834909ms","start":"2026-07-31T00:39:47.408925Z","end":"2026-07-31T00:39:47.565759Z","steps":["trace[1876379678] 'agreement among raft nodes before linearized reading' (duration: 156.619565ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:47.565814Z","caller":"traceutil/trace.go:171","msg":"trace[134844956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26174; }","duration":"155.956312ms","start":"2026-07-31T00:39:47.409841Z","end":"2026-07-31T00:39:47.565798Z","steps":["trace[134844956] 'agreement among raft nodes before linearized reading' (duration: 155.772458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:47.565849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.616878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-31T00:39:47.565725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.104234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-31T00:39:47.565886Z","caller":"traceutil/trace.go:171","msg":"trace[1385027137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26174; }","duration":"153.700549ms","start":"2026-07-31T00:39:47.412173Z","end":"2026-07-31T00:39:47.565873Z","steps":["trace[1385027137] 'agreement among raft nodes before linearized reading' (duration: 153.613668ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:47.565916Z","caller":"traceutil/trace.go:171","msg":"trace[196922643] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:26174; }","duration":"145.309128ms","start":"2026-07-31T00:39:47.420593Z","end":"2026-07-31T00:39:47.565902Z","steps":["trace[196922643] 'agreement among raft nodes before linearized reading' (duration: 145.041873ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:47.972519Z","caller":"traceutil/trace.go:171","msg":"trace[1743214586] linearizableReadLoop","detail":"{readStateIndex:27045; appliedIndex:27039; }","duration":"347.253792ms","start":"2026-07-31T00:39:47.625239Z","end":"2026-07-31T00:39:47.972493Z","steps":["trace[1743214586] 'read index received' (duration: 80.347151ms)","trace[1743214586] 'applied index is now lower than readState.Index' (duration: 266.905451ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:47.97277Z","caller":"traceutil/trace.go:171","msg":"trace[968480950] transaction","detail":"{read_only:false; response_revision:26175; number_of_response:1; }","duration":"402.802298ms","start":"2026-07-31T00:39:47.569957Z","end":"2026-07-31T00:39:47.972759Z","steps":["trace[968480950] 'process raft request' (duration: 402.125215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:47.972878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.569926Z","time spent":"402.874299ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:47.973049Z","caller":"traceutil/trace.go:171","msg":"trace[2585090] transaction","detail":"{read_only:false; response_revision:26176; number_of_response:1; }","duration":"402.153875ms","start":"2026-07-31T00:39:47.570887Z","end":"2026-07-31T00:39:47.973041Z","steps":["trace[2585090] 'process raft request' (duration: 401.418642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:47.973091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.570875Z","time spent":"402.194916ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:47.973157Z","caller":"traceutil/trace.go:171","msg":"trace[1751175437] transaction","detail":"{read_only:false; response_revision:26177; number_of_response:1; }","duration":"402.155365ms","start":"2026-07-31T00:39:47.57099Z","end":"2026-07-31T00:39:47.973146Z","steps":["trace[1751175437] 'process raft request' (duration: 401.36241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:47.973396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.149041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:47.973435Z","caller":"traceutil/trace.go:171","msg":"trace[331949937] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:26179; }","duration":"348.236482ms","start":"2026-07-31T00:39:47.625189Z","end":"2026-07-31T00:39:47.973425Z","steps":["trace[331949937] 'agreement among raft nodes before linearized reading' (duration: 348.15646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:47.973462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.625171Z","time spent":"348.283673ms","remote":"127.0.0.1:32772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"info","ts":"2026-07-31T00:39:47.973459Z","caller":"traceutil/trace.go:171","msg":"trace[803312965] transaction","detail":"{read_only:false; response_revision:26179; number_of_response:1; }","duration":"398.040118ms","start":"2026-07-31T00:39:47.575391Z","end":"2026-07-31T00:39:47.973431Z","steps":["trace[803312965] 'process raft request' (duration: 397.066608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:47.973551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.575376Z","time spent":"398.12975ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:47.973714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.570968Z","time spent":"402.207137ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:47.973749Z","caller":"traceutil/trace.go:171","msg":"trace[700273431] transaction","detail":"{read_only:false; response_revision:26178; number_of_response:1; }","duration":"402.220617ms","start":"2026-07-31T00:39:47.57151Z","end":"2026-07-31T00:39:47.97373Z","steps":["trace[700273431] 'process raft request' (duration: 400.875791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:47.973882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.571499Z","time spent":"402.32299ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T00:39:48.150634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.335745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:48.150928Z","caller":"traceutil/trace.go:171","msg":"trace[738704231] linearizableReadLoop","detail":"{readStateIndex:27047; appliedIndex:27045; }","duration":"178.316471ms","start":"2026-07-31T00:39:47.97259Z","end":"2026-07-31T00:39:48.150907Z","steps":["trace[738704231] 'read index received' (duration: 39.515768ms)","trace[738704231] 'applied index is now lower than readState.Index' (duration: 138.799173ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:48.151041Z","caller":"traceutil/trace.go:171","msg":"trace[59863784] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"351.366531ms","start":"2026-07-31T00:39:47.799663Z","end":"2026-07-31T00:39:48.15103Z","steps":["trace[59863784] 'process raft request' (duration: 351.108136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:48.151112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.799634Z","time spent":"351.437353ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:48.151164Z","caller":"traceutil/trace.go:171","msg":"trace[226087933] transaction","detail":"{read_only:false; response_revision:26180; number_of_response:1; }","duration":"440.712375ms","start":"2026-07-31T00:39:47.710409Z","end":"2026-07-31T00:39:48.151122Z","steps":["trace[226087933] 'process raft request' (duration: 301.759119ms)","trace[226087933] 'compare' (duration: 137.930247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:48.151265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.233784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-31T00:39:48.151316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.516995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-31T00:39:48.15134Z","caller":"traceutil/trace.go:171","msg":"trace[1308786214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26181; }","duration":"369.351336ms","start":"2026-07-31T00:39:47.781972Z","end":"2026-07-31T00:39:48.151323Z","steps":["trace[1308786214] 'agreement among raft nodes before linearized reading' (duration: 369.175083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:48.151343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.710383Z","time spent":"440.878248ms","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:39:48.151364Z","caller":"traceutil/trace.go:171","msg":"trace[52288885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26181; }","duration":"388.617437ms","start":"2026-07-31T00:39:47.76273Z","end":"2026-07-31T00:39:48.151348Z","steps":["trace[52288885] 'agreement among raft nodes before linearized reading' (duration: 388.380832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:48.151379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.781965Z","time spent":"369.405818ms","remote":"127.0.0.1:50838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T00:39:48.151394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:39:47.762713Z","time spent":"388.671857ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T00:39:48.413919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.820718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:48.414064Z","caller":"traceutil/trace.go:171","msg":"trace[1488665262] linearizableReadLoop","detail":"{readStateIndex:27048; appliedIndex:27047; }","duration":"257.319328ms","start":"2026-07-31T00:39:48.156721Z","end":"2026-07-31T00:39:48.41404Z","steps":["trace[1488665262] 'read index received' (duration: 135.206164ms)","trace[1488665262] 'applied index is now lower than readState.Index' (duration: 122.111084ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:48.414176Z","caller":"traceutil/trace.go:171","msg":"trace[953984165] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"258.348077ms","start":"2026-07-31T00:39:48.155793Z","end":"2026-07-31T00:39:48.414141Z","steps":["trace[953984165] 'process raft request' (duration: 136.152472ms)","trace[953984165] 'compare' (duration: 121.533383ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:48.414378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.655234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-31T00:39:48.414422Z","caller":"traceutil/trace.go:171","msg":"trace[1585427306] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26182; }","duration":"257.737915ms","start":"2026-07-31T00:39:48.156668Z","end":"2026-07-31T00:39:48.414406Z","steps":["trace[1585427306] 'agreement among raft nodes before linearized reading' (duration: 257.488521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:48.414741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.298771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:48.414791Z","caller":"traceutil/trace.go:171","msg":"trace[98149578] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26182; }","duration":"179.433063ms","start":"2026-07-31T00:39:48.235341Z","end":"2026-07-31T00:39:48.414775Z","steps":["trace[98149578] 'agreement among raft nodes before linearized reading' (duration: 179.317461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:48.415297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.978959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:48.415415Z","caller":"traceutil/trace.go:171","msg":"trace[1095926089] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26182; }","duration":"235.138352ms","start":"2026-07-31T00:39:48.180247Z","end":"2026-07-31T00:39:48.415385Z","steps":["trace[1095926089] 'agreement among raft nodes before linearized reading' (duration: 234.895277ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:54.54931Z","caller":"traceutil/trace.go:171","msg":"trace[492465290] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"105.155678ms","start":"2026-07-31T00:39:54.444122Z","end":"2026-07-31T00:39:54.549278Z","steps":["trace[492465290] 'process raft request' (duration: 104.973145ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:55.274159Z","caller":"traceutil/trace.go:171","msg":"trace[1409080058] linearizableReadLoop","detail":"{readStateIndex:27098; appliedIndex:27094; }","duration":"264.774611ms","start":"2026-07-31T00:39:55.00927Z","end":"2026-07-31T00:39:55.274045Z","steps":["trace[1409080058] 'read index received' (duration: 64.63253ms)","trace[1409080058] 'applied index is now lower than readState.Index' (duration: 200.140191ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:55.274361Z","caller":"traceutil/trace.go:171","msg":"trace[1511169796] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"277.136168ms","start":"2026-07-31T00:39:54.997162Z","end":"2026-07-31T00:39:55.274298Z","steps":["trace[1511169796] 'process raft request' (duration: 276.767481ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:55.274457Z","caller":"traceutil/trace.go:171","msg":"trace[1292709145] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"279.505473ms","start":"2026-07-31T00:39:54.994904Z","end":"2026-07-31T00:39:55.274409Z","steps":["trace[1292709145] 'process raft request' (duration: 206.575834ms)","trace[1292709145] 'compare' (duration: 72.258046ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:55.27448Z","caller":"traceutil/trace.go:171","msg":"trace[489804592] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"276.707779ms","start":"2026-07-31T00:39:54.99772Z","end":"2026-07-31T00:39:55.274428Z","steps":["trace[489804592] 'process raft request' (duration: 276.266841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:55.27459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.26406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:39:55.274971Z","caller":"traceutil/trace.go:171","msg":"trace[1493146255] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:26231; }","duration":"265.695747ms","start":"2026-07-31T00:39:55.009226Z","end":"2026-07-31T00:39:55.274922Z","steps":["trace[1493146255] 'agreement among raft nodes before linearized reading' (duration: 265.200508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:39:55.539774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.849781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:39:55.539892Z","caller":"traceutil/trace.go:171","msg":"trace[386637565] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"209.561131ms","start":"2026-07-31T00:39:55.330309Z","end":"2026-07-31T00:39:55.53987Z","steps":["trace[386637565] 'process raft request' (duration: 92.467694ms)","trace[386637565] 'compare' (duration: 116.733399ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:55.768664Z","caller":"traceutil/trace.go:171","msg":"trace[205870429] linearizableReadLoop","detail":"{readStateIndex:27100; appliedIndex:27099; }","duration":"157.352329ms","start":"2026-07-31T00:39:55.611281Z","end":"2026-07-31T00:39:55.768633Z","steps":["trace[205870429] 'read index received' (duration: 121.164344ms)","trace[205870429] 'applied index is now lower than readState.Index' (duration: 36.186715ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:39:55.7688Z","caller":"traceutil/trace.go:171","msg":"trace[183243564] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"166.872101ms","start":"2026-07-31T00:39:55.601882Z","end":"2026-07-31T00:39:55.768754Z","steps":["trace[183243564] 'process raft request' (duration: 130.571604ms)","trace[183243564] 'compare' (duration: 36.015312ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:39:55.768875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.559403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T00:39:55.768916Z","caller":"traceutil/trace.go:171","msg":"trace[1252255490] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:26233; }","duration":"157.635504ms","start":"2026-07-31T00:39:55.611266Z","end":"2026-07-31T00:39:55.768902Z","steps":["trace[1252255490] 'agreement among raft nodes before linearized reading' (duration: 157.474161ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:56.283392Z","caller":"traceutil/trace.go:171","msg":"trace[893435393] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"100.967997ms","start":"2026-07-31T00:39:56.18239Z","end":"2026-07-31T00:39:56.283358Z","steps":["trace[893435393] 'process raft request' (duration: 100.779624ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:39:57.852695Z","caller":"traceutil/trace.go:171","msg":"trace[1736432226] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"144.221587ms","start":"2026-07-31T00:39:57.708435Z","end":"2026-07-31T00:39:57.852657Z","steps":["trace[1736432226] 'process raft request' (duration: 143.951052ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:00.705203Z","caller":"traceutil/trace.go:171","msg":"trace[1112626356] linearizableReadLoop","detail":"{readStateIndex:27159; appliedIndex:27158; }","duration":"118.028705ms","start":"2026-07-31T00:40:00.587147Z","end":"2026-07-31T00:40:00.705176Z","steps":["trace[1112626356] 'read index received' (duration: 117.807891ms)","trace[1112626356] 'applied index is now lower than readState.Index' (duration: 219.234µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:00.705321Z","caller":"traceutil/trace.go:171","msg":"trace[1615448369] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"120.3701ms","start":"2026-07-31T00:40:00.584907Z","end":"2026-07-31T00:40:00.705277Z","steps":["trace[1615448369] 'process raft request' (duration: 120.068824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:00.705508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.34034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-31T00:40:00.705546Z","caller":"traceutil/trace.go:171","msg":"trace[1429324931] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26291; }","duration":"118.404013ms","start":"2026-07-31T00:40:00.587129Z","end":"2026-07-31T00:40:00.705533Z","steps":["trace[1429324931] 'agreement among raft nodes before linearized reading' (duration: 118.202278ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:00.915846Z","caller":"traceutil/trace.go:171","msg":"trace[903048778] transaction","detail":"{read_only:false; response_revision:26293; number_of_response:1; }","duration":"199.013529ms","start":"2026-07-31T00:40:00.716796Z","end":"2026-07-31T00:40:00.91581Z","steps":["trace[903048778] 'process raft request' (duration: 198.746663ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:00.915735Z","caller":"traceutil/trace.go:171","msg":"trace[58149446] transaction","detail":"{read_only:false; response_revision:26292; number_of_response:1; }","duration":"204.453713ms","start":"2026-07-31T00:40:00.711245Z","end":"2026-07-31T00:40:00.915699Z","steps":["trace[58149446] 'process raft request' (duration: 124.093641ms)","trace[58149446] 'compare' (duration: 79.942043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:40:06.039207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.96988ms","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-07-31T00:40:06.039306Z","caller":"traceutil/trace.go:171","msg":"trace[911397650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26338; }","duration":"145.129314ms","start":"2026-07-31T00:40:05.894152Z","end":"2026-07-31T00:40:06.039282Z","steps":["trace[911397650] 'range keys from in-memory index tree' (duration: 144.708156ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:10.258465Z","caller":"traceutil/trace.go:171","msg":"trace[1526346721] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"107.151837ms","start":"2026-07-31T00:40:10.15128Z","end":"2026-07-31T00:40:10.258432Z","steps":["trace[1526346721] 'process raft request' (duration: 106.915583ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:10.539547Z","caller":"traceutil/trace.go:171","msg":"trace[2141062529] linearizableReadLoop","detail":"{readStateIndex:27244; appliedIndex:27240; }","duration":"294.242366ms","start":"2026-07-31T00:40:10.245275Z","end":"2026-07-31T00:40:10.539517Z","steps":["trace[2141062529] 'read index received' (duration: 13.058201ms)","trace[2141062529] 'applied index is now lower than readState.Index' (duration: 281.182855ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:10.539654Z","caller":"traceutil/trace.go:171","msg":"trace[175945862] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"296.231194ms","start":"2026-07-31T00:40:10.243377Z","end":"2026-07-31T00:40:10.539608Z","steps":["trace[175945862] 'process raft request' (duration: 296.086992ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:10.539631Z","caller":"traceutil/trace.go:171","msg":"trace[1621390117] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"374.1802ms","start":"2026-07-31T00:40:10.165393Z","end":"2026-07-31T00:40:10.539574Z","steps":["trace[1621390117] 'process raft request' (duration: 304.35322ms)","trace[1621390117] 'compare' (duration: 69.463053ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:10.539822Z","caller":"traceutil/trace.go:171","msg":"trace[1333494810] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"296.321976ms","start":"2026-07-31T00:40:10.243296Z","end":"2026-07-31T00:40:10.539618Z","steps":["trace[1333494810] 'process raft request' (duration: 296.101741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:10.539908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.607132ms","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-07-31T00:40:10.539953Z","caller":"traceutil/trace.go:171","msg":"trace[222720887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26374; }","duration":"294.721975ms","start":"2026-07-31T00:40:10.245217Z","end":"2026-07-31T00:40:10.539939Z","steps":["trace[222720887] 'agreement among raft nodes before linearized reading' (duration: 294.47076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:10.539926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:40:10.165366Z","time spent":"374.422284ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T00:40:10.546488Z","caller":"traceutil/trace.go:171","msg":"trace[691428286] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"213.041827ms","start":"2026-07-31T00:40:10.333412Z","end":"2026-07-31T00:40:10.546454Z","steps":["trace[691428286] 'process raft request' (duration: 212.766762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:10.546578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.957974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-31T00:40:10.546649Z","caller":"traceutil/trace.go:171","msg":"trace[1111111036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26375; }","duration":"301.075197ms","start":"2026-07-31T00:40:10.245553Z","end":"2026-07-31T00:40:10.546628Z","steps":["trace[1111111036] 'agreement among raft nodes before linearized reading' (duration: 300.834382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:10.546668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.287988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-31T00:40:10.546699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T00:40:10.245547Z","time spent":"301.138828ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T00:40:10.546766Z","caller":"traceutil/trace.go:171","msg":"trace[39787256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26375; }","duration":"292.403241ms","start":"2026-07-31T00:40:10.254309Z","end":"2026-07-31T00:40:10.546712Z","steps":["trace[39787256] 'agreement among raft nodes before linearized reading' (duration: 292.157786ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:10.761075Z","caller":"traceutil/trace.go:171","msg":"trace[1269638354] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"209.579791ms","start":"2026-07-31T00:40:10.551462Z","end":"2026-07-31T00:40:10.761042Z","steps":["trace[1269638354] 'process raft request' (duration: 209.319736ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:10.77619Z","caller":"traceutil/trace.go:171","msg":"trace[1880257014] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"223.856785ms","start":"2026-07-31T00:40:10.552302Z","end":"2026-07-31T00:40:10.776159Z","steps":["trace[1880257014] 'process raft request' (duration: 223.489678ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:11.14344Z","caller":"traceutil/trace.go:171","msg":"trace[1138067884] linearizableReadLoop","detail":"{readStateIndex:27249; appliedIndex:27248; }","duration":"158.217126ms","start":"2026-07-31T00:40:10.985186Z","end":"2026-07-31T00:40:11.143403Z","steps":["trace[1138067884] 'read index received' (duration: 157.880528ms)","trace[1138067884] 'applied index is now lower than readState.Index' (duration: 334.918µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:11.143594Z","caller":"traceutil/trace.go:171","msg":"trace[20796666] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"163.269483ms","start":"2026-07-31T00:40:10.980288Z","end":"2026-07-31T00:40:11.143558Z","steps":["trace[20796666] 'process raft request' (duration: 162.895245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:11.143708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.499351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/heat-engine-cleaner-29757640-hg55f\" ","response":"range_response_count:1 size:2897"} {"level":"info","ts":"2026-07-31T00:40:11.14375Z","caller":"traceutil/trace.go:171","msg":"trace[982735911] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/heat-engine-cleaner-29757640-hg55f; range_end:; response_count:1; response_revision:26379; }","duration":"158.604013ms","start":"2026-07-31T00:40:10.98513Z","end":"2026-07-31T00:40:11.143734Z","steps":["trace[982735911] 'agreement among raft nodes before linearized reading' (duration: 158.393829ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:12.683935Z","caller":"traceutil/trace.go:171","msg":"trace[932871293] transaction","detail":"{read_only:false; response_revision:26388; number_of_response:1; }","duration":"138.572829ms","start":"2026-07-31T00:40:12.545336Z","end":"2026-07-31T00:40:12.683909Z","steps":["trace[932871293] 'process raft request' (duration: 138.384725ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:12.720214Z","caller":"traceutil/trace.go:171","msg":"trace[1370783028] transaction","detail":"{read_only:false; response_revision:26389; number_of_response:1; }","duration":"173.752904ms","start":"2026-07-31T00:40:12.546447Z","end":"2026-07-31T00:40:12.7202Z","steps":["trace[1370783028] 'process raft request' (duration: 173.614101ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:12.720545Z","caller":"traceutil/trace.go:171","msg":"trace[200250904] transaction","detail":"{read_only:false; response_revision:26390; number_of_response:1; }","duration":"166.768209ms","start":"2026-07-31T00:40:12.553765Z","end":"2026-07-31T00:40:12.720533Z","steps":["trace[200250904] 'process raft request' (duration: 166.397822ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:12.721839Z","caller":"traceutil/trace.go:171","msg":"trace[1813155809] linearizableReadLoop","detail":"{readStateIndex:27259; appliedIndex:27258; }","duration":"170.632423ms","start":"2026-07-31T00:40:12.551194Z","end":"2026-07-31T00:40:12.721826Z","steps":["trace[1813155809] 'read index received' (duration: 132.773577ms)","trace[1813155809] 'applied index is now lower than readState.Index' (duration: 37.857836ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:40:12.721991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.778947ms","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-07-31T00:40:12.722039Z","caller":"traceutil/trace.go:171","msg":"trace[811052169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26390; }","duration":"170.844268ms","start":"2026-07-31T00:40:12.551182Z","end":"2026-07-31T00:40:12.722027Z","steps":["trace[811052169] 'agreement among raft nodes before linearized reading' (duration: 170.747006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:13.914425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.244142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:40:13.914557Z","caller":"traceutil/trace.go:171","msg":"trace[1248898224] linearizableReadLoop","detail":"{readStateIndex:27278; appliedIndex:27277; }","duration":"106.579635ms","start":"2026-07-31T00:40:13.807955Z","end":"2026-07-31T00:40:13.914535Z","steps":["trace[1248898224] 'read index received' (duration: 172.643µs)","trace[1248898224] 'applied index is now lower than readState.Index' (duration: 106.405382ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:40:13.914772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.820129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-07-31T00:40:13.914834Z","caller":"traceutil/trace.go:171","msg":"trace[16500499] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:26407; }","duration":"107.079124ms","start":"2026-07-31T00:40:13.807736Z","end":"2026-07-31T00:40:13.914815Z","steps":["trace[16500499] 'agreement among raft nodes before linearized reading' (duration: 106.85742ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:13.914794Z","caller":"traceutil/trace.go:171","msg":"trace[1514859614] transaction","detail":"{read_only:false; response_revision:26407; number_of_response:1; }","duration":"249.536747ms","start":"2026-07-31T00:40:13.665185Z","end":"2026-07-31T00:40:13.914721Z","steps":["trace[1514859614] 'process raft request' (duration: 96.881838ms)","trace[1514859614] 'compare' (duration: 152.044218ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:40:34.961588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.059501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T00:40:34.96172Z","caller":"traceutil/trace.go:171","msg":"trace[1251713334] transaction","detail":"{read_only:false; response_revision:26569; number_of_response:1; }","duration":"195.766007ms","start":"2026-07-31T00:40:34.765927Z","end":"2026-07-31T00:40:34.961693Z","steps":["trace[1251713334] 'process raft request' (duration: 72.473791ms)","trace[1251713334] 'compare' (duration: 122.683754ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:34.980834Z","caller":"traceutil/trace.go:171","msg":"trace[1511154638] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"159.367058ms","start":"2026-07-31T00:40:34.82144Z","end":"2026-07-31T00:40:34.980807Z","steps":["trace[1511154638] 'process raft request' (duration: 159.124643ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:34.980823Z","caller":"traceutil/trace.go:171","msg":"trace[1103724359] linearizableReadLoop","detail":"{readStateIndex:27446; appliedIndex:27443; }","duration":"147.872737ms","start":"2026-07-31T00:40:34.832919Z","end":"2026-07-31T00:40:34.980792Z","steps":["trace[1103724359] 'read index received' (duration: 5.469115ms)","trace[1103724359] 'applied index is now lower than readState.Index' (duration: 142.402082ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:34.980945Z","caller":"traceutil/trace.go:171","msg":"trace[587636114] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"155.022675ms","start":"2026-07-31T00:40:34.825849Z","end":"2026-07-31T00:40:34.980871Z","steps":["trace[587636114] 'process raft request' (duration: 154.883942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T00:40:34.98112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.191143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-31T00:40:34.981179Z","caller":"traceutil/trace.go:171","msg":"trace[156276446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26571; }","duration":"148.328575ms","start":"2026-07-31T00:40:34.832838Z","end":"2026-07-31T00:40:34.981167Z","steps":["trace[156276446] 'agreement among raft nodes before linearized reading' (duration: 148.04359ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:35.554931Z","caller":"traceutil/trace.go:171","msg":"trace[1754162206] linearizableReadLoop","detail":"{readStateIndex:27451; appliedIndex:27450; }","duration":"127.822603ms","start":"2026-07-31T00:40:35.427078Z","end":"2026-07-31T00:40:35.554901Z","steps":["trace[1754162206] 'read index received' (duration: 56.84199ms)","trace[1754162206] 'applied index is now lower than readState.Index' (duration: 70.979003ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:35.55498Z","caller":"traceutil/trace.go:171","msg":"trace[226926392] transaction","detail":"{read_only:false; response_revision:26576; number_of_response:1; }","duration":"184.816816ms","start":"2026-07-31T00:40:35.370141Z","end":"2026-07-31T00:40:35.554958Z","steps":["trace[226926392] 'process raft request' (duration: 113.862804ms)","trace[226926392] 'compare' (duration: 70.488994ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T00:40:35.55511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.998826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T00:40:35.555232Z","caller":"traceutil/trace.go:171","msg":"trace[872185103] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26576; }","duration":"128.18952ms","start":"2026-07-31T00:40:35.427021Z","end":"2026-07-31T00:40:35.555211Z","steps":["trace[872185103] 'agreement among raft nodes before linearized reading' (duration: 127.966286ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:35.715796Z","caller":"traceutil/trace.go:171","msg":"trace[836506774] transaction","detail":"{read_only:false; response_revision:26578; number_of_response:1; }","duration":"220.491581ms","start":"2026-07-31T00:40:35.495274Z","end":"2026-07-31T00:40:35.715766Z","steps":["trace[836506774] 'process raft request' (duration: 176.310773ms)","trace[836506774] 'compare' (duration: 43.950814ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T00:40:35.715962Z","caller":"traceutil/trace.go:171","msg":"trace[293558778] transaction","detail":"{read_only:false; response_revision:26579; number_of_response:1; }","duration":"219.43897ms","start":"2026-07-31T00:40:35.496497Z","end":"2026-07-31T00:40:35.715936Z","steps":["trace[293558778] 'process raft request' (duration: 219.201486ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:40:36.544091Z","caller":"traceutil/trace.go:171","msg":"trace[1082128280] transaction","detail":"{read_only:false; response_revision:26581; number_of_response:1; }","duration":"126.553938ms","start":"2026-07-31T00:40:36.417505Z","end":"2026-07-31T00:40:36.544059Z","steps":["trace[1082128280] 'process raft request' (duration: 126.330524ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T00:43:14.412661Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25259} {"level":"info","ts":"2026-07-31T00:43:14.5615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25259,"took":"143.26544ms","hash":1785016391,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":37732352,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-31T00:43:14.561589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1785016391,"revision":25259,"compact-revision":22049} {"level":"info","ts":"2026-07-31T00:44:49.194022Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T00:44:49.201231Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-31T00:44:49.201479Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-31T00:48:14.421329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28110} {"level":"info","ts":"2026-07-31T00:48:14.564878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28110,"took":"138.782898ms","hash":1965979636,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":32751616,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-31T00:48:14.564978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1965979636,"revision":28110,"compact-revision":25259} {"level":"info","ts":"2026-07-31T00:53:14.428972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30606} {"level":"info","ts":"2026-07-31T00:53:14.569089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30606,"took":"134.277943ms","hash":2156019864,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":30601216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T00:53:14.569177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2156019864,"revision":30606,"compact-revision":28110} {"level":"info","ts":"2026-07-31T00:58:14.441059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32858} {"level":"info","ts":"2026-07-31T00:58:14.58456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32858,"took":"138.723238ms","hash":1311850159,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":30322688,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-31T00:58:14.584692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1311850159,"revision":32858,"compact-revision":30606} {"level":"info","ts":"2026-07-31T01:03:14.450558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35090} {"level":"info","ts":"2026-07-31T01:03:14.591298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35090,"took":"134.992678ms","hash":100237413,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":31985664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-31T01:03:14.591393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":100237413,"revision":35090,"compact-revision":32858} {"level":"info","ts":"2026-07-31T01:06:02.582159Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T01:06:02.587801Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-31T01:06:02.587918Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-31T01:08:14.457594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37445} {"level":"info","ts":"2026-07-31T01:08:14.587255Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37445,"took":"124.028812ms","hash":160232476,"current-db-size-bytes":60522496,"current-db-size":"60 MB","current-db-size-in-use-bytes":33079296,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-31T01:08:14.587346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":160232476,"revision":37445,"compact-revision":35090} {"level":"info","ts":"2026-07-31T01:13:14.46738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39761} {"level":"info","ts":"2026-07-31T01:13:14.59259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39761,"took":"121.3474ms","hash":2876855431,"current-db-size-bytes":60522496,"current-db-size":"60 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T01:13:14.592649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2876855431,"revision":39761,"compact-revision":37445}