{"level":"warn","ts":"2026-08-10T22:25:51.659599Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:25:51.659678Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-08-10T22:25:51.659741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-10T22:25:51.659754Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-10T22:25:51.65977Z","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-08-10T22:25:51.660364Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-08-10T22:25:51.660439Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-08-10T22:25:51.66288Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.227019ms"} {"level":"info","ts":"2026-08-10T22:25:51.668113Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-08-10T22:25:51.668193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-08-10T22:25:51.668216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-08-10T22:25:51.668233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-10T22:25:51.668245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-08-10T22:25:51.668283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-08-10T22:25:51.678128Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-10T22:25:51.680217Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-10T22:25:51.681404Z","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-08-10T22:25:51.683309Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-10T22:25:51.683462Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-10T22:25:51.684053Z","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-08-10T22:25:51.684173Z","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-08-10T22:25:51.684187Z","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-08-10T22:25:51.684524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-08-10T22:25:51.684753Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-10T22:25:51.687273Z","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-08-10T22:25:51.687294Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-10T22:25:51.687433Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-10T22:25:51.688273Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-10T22:25:51.688363Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-10T22:25:52.668762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-08-10T22:25:52.668824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-10T22:25:52.668869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-08-10T22:25:52.668893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-08-10T22:25:52.668907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-10T22:25:52.668927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-08-10T22:25:52.668943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-10T22:25:52.670006Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:52.670064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:25:52.670079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-10T22:25:52.670029Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-08-10T22:25:52.670357Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-10T22:25:52.67043Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-10T22:25:52.670974Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:52.671101Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:52.671138Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-10T22:25:52.675619Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-10T22:25:52.676342Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-08-10T22:26:05.249925Z","caller":"traceutil/trace.go:171","msg":"trace[1259012915] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"147.400409ms","start":"2026-08-10T22:26:05.102497Z","end":"2026-08-10T22:26:05.249898Z","steps":["trace[1259012915] 'process raft request' (duration: 147.240406ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:27.461953Z","caller":"traceutil/trace.go:171","msg":"trace[472350519] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"175.240864ms","start":"2026-08-10T22:26:27.28668Z","end":"2026-08-10T22:26:27.461921Z","steps":["trace[472350519] 'read index received' (duration: 101.696463ms)","trace[472350519] 'applied index is now lower than readState.Index' (duration: 73.542821ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:27.46203Z","caller":"traceutil/trace.go:171","msg":"trace[12207790] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"230.365249ms","start":"2026-08-10T22:26:27.231622Z","end":"2026-08-10T22:26:27.461987Z","steps":["trace[12207790] 'process raft request' (duration: 156.796418ms)","trace[12207790] 'compare' (duration: 73.365736ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:26:27.462525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.796788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-08-10T22:26:27.462607Z","caller":"traceutil/trace.go:171","msg":"trace[637564854] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:474; }","duration":"175.927452ms","start":"2026-08-10T22:26:27.286661Z","end":"2026-08-10T22:26:27.462588Z","steps":["trace[637564854] 'agreement among raft nodes before linearized reading' (duration: 175.426309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:27.462647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.097872ms","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-08-10T22:26:27.462749Z","caller":"traceutil/trace.go:171","msg":"trace[1257775829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"115.250586ms","start":"2026-08-10T22:26:27.347482Z","end":"2026-08-10T22:26:27.462732Z","steps":["trace[1257775829] 'agreement among raft nodes before linearized reading' (duration: 114.653011ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:29.005009Z","caller":"traceutil/trace.go:171","msg":"trace[825632768] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"109.585803ms","start":"2026-08-10T22:26:28.895386Z","end":"2026-08-10T22:26:29.004972Z","steps":["trace[825632768] 'process raft request' (duration: 109.353168ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:29.012684Z","caller":"traceutil/trace.go:171","msg":"trace[745101489] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"107.274255ms","start":"2026-08-10T22:26:28.905385Z","end":"2026-08-10T22:26:29.012659Z","steps":["trace[745101489] 'process raft request' (duration: 107.07026ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:29.012798Z","caller":"traceutil/trace.go:171","msg":"trace[1479806622] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"101.373725ms","start":"2026-08-10T22:26:28.911366Z","end":"2026-08-10T22:26:29.012739Z","steps":["trace[1479806622] 'process raft request' (duration: 101.214191ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:34.619383Z","caller":"traceutil/trace.go:171","msg":"trace[1567518689] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"187.845994ms","start":"2026-08-10T22:26:34.431505Z","end":"2026-08-10T22:26:34.619351Z","steps":["trace[1567518689] 'process raft request' (duration: 187.605188ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:26:35.368731Z","caller":"traceutil/trace.go:171","msg":"trace[488592855] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"339.369328ms","start":"2026-08-10T22:26:35.029331Z","end":"2026-08-10T22:26:35.3687Z","steps":["trace[488592855] 'process raft request' (duration: 337.641854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:26:35.370315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:26:35.029301Z","time spent":"339.509791ms","remote":"127.0.0.1:43074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036273,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:26:35.613955Z","caller":"traceutil/trace.go:171","msg":"trace[133164571] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"192.698437ms","start":"2026-08-10T22:26:35.42121Z","end":"2026-08-10T22:26:35.613907Z","steps":["trace[133164571] 'process raft request' (duration: 176.032895ms)","trace[133164571] 'compare' (duration: 16.401915ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:26:51.160325Z","caller":"traceutil/trace.go:171","msg":"trace[241912819] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"175.190914ms","start":"2026-08-10T22:26:50.985112Z","end":"2026-08-10T22:26:51.160303Z","steps":["trace[241912819] 'process raft request' (duration: 175.02181ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:27:18.753862Z","caller":"traceutil/trace.go:171","msg":"trace[2104695189] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"218.246114ms","start":"2026-08-10T22:27:18.535587Z","end":"2026-08-10T22:27:18.753833Z","steps":["trace[2104695189] 'process raft request' (duration: 218.053439ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:27:18.756181Z","caller":"traceutil/trace.go:171","msg":"trace[1951479323] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1178; }","duration":"181.289348ms","start":"2026-08-10T22:27:18.574874Z","end":"2026-08-10T22:27:18.756163Z","steps":["trace[1951479323] 'read index received' (duration: 178.782086ms)","trace[1951479323] 'applied index is now lower than readState.Index' (duration: 2.506502ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:27:18.7563Z","caller":"traceutil/trace.go:171","msg":"trace[1014244062] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"220.50028ms","start":"2026-08-10T22:27:18.535753Z","end":"2026-08-10T22:27:18.756254Z","steps":["trace[1014244062] 'process raft request' (duration: 220.311326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:27:18.756389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.508433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-10T22:27:18.756746Z","caller":"traceutil/trace.go:171","msg":"trace[544928977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1145; }","duration":"181.899723ms","start":"2026-08-10T22:27:18.574829Z","end":"2026-08-10T22:27:18.756729Z","steps":["trace[544928977] 'agreement among raft nodes before linearized reading' (duration: 181.449212ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:07.703704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.059811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:28:07.703993Z","caller":"traceutil/trace.go:171","msg":"trace[508035140] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1905; }","duration":"236.015706ms","start":"2026-08-10T22:28:07.467962Z","end":"2026-08-10T22:28:07.703978Z","steps":["trace[508035140] 'read index received' (duration: 162.993214ms)","trace[508035140] 'applied index is now lower than readState.Index' (duration: 73.020882ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:28:07.704038Z","caller":"traceutil/trace.go:171","msg":"trace[1578835592] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"220.281355ms","start":"2026-08-10T22:28:07.483735Z","end":"2026-08-10T22:28:07.704016Z","steps":["trace[1578835592] 'process raft request' (duration: 220.194612ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:07.70405Z","caller":"traceutil/trace.go:171","msg":"trace[281694380] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"472.85906ms","start":"2026-08-10T22:28:07.231143Z","end":"2026-08-10T22:28:07.704002Z","steps":["trace[281694380] 'process raft request' (duration: 158.245665ms)","trace[281694380] 'compare' (duration: 313.830336ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:07.704245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:07.231132Z","time spent":"473.010864ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:28:07.704215Z","caller":"traceutil/trace.go:171","msg":"trace[208214065] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"332.798875ms","start":"2026-08-10T22:28:07.37137Z","end":"2026-08-10T22:28:07.704169Z","steps":["trace[208214065] 'process raft request' (duration: 332.432776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:07.704309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.352244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-10T22:28:07.704343Z","caller":"traceutil/trace.go:171","msg":"trace[1047245438] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1836; }","duration":"236.431095ms","start":"2026-08-10T22:28:07.467901Z","end":"2026-08-10T22:28:07.704332Z","steps":["trace[1047245438] 'agreement among raft nodes before linearized reading' (duration: 236.22763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:07.704338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.431833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-10T22:28:07.704444Z","caller":"traceutil/trace.go:171","msg":"trace[252618942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1836; }","duration":"142.590157ms","start":"2026-08-10T22:28:07.561833Z","end":"2026-08-10T22:28:07.704423Z","steps":["trace[252618942] 'agreement among raft nodes before linearized reading' (duration: 142.33475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:07.704437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:07.371336Z","time spent":"332.98137ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:28:08.144726Z","caller":"traceutil/trace.go:171","msg":"trace[335857117] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"276.88905ms","start":"2026-08-10T22:28:07.867798Z","end":"2026-08-10T22:28:08.144687Z","steps":["trace[335857117] 'process raft request' (duration: 276.608893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:20.195666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.509998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-10T22:28:20.19574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.534097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-10T22:28:20.195864Z","caller":"traceutil/trace.go:171","msg":"trace[173755818] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1935; }","duration":"394.68992ms","start":"2026-08-10T22:28:19.801149Z","end":"2026-08-10T22:28:20.195839Z","steps":["trace[173755818] 'range keys from in-memory index tree' (duration: 394.387243ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:20.195753Z","caller":"traceutil/trace.go:171","msg":"trace[650162523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1935; }","duration":"396.60198ms","start":"2026-08-10T22:28:19.799125Z","end":"2026-08-10T22:28:20.195727Z","steps":["trace[650162523] 'range keys from in-memory index tree' (duration: 396.297002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:20.195931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:19.801143Z","time spent":"394.773353ms","remote":"127.0.0.1:44758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-10T22:28:20.195961Z","caller":"traceutil/trace.go:171","msg":"trace[416668534] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"292.834475ms","start":"2026-08-10T22:28:19.903114Z","end":"2026-08-10T22:28:20.195948Z","steps":["trace[416668534] 'process raft request' (duration: 291.126421ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:20.195949Z","caller":"traceutil/trace.go:171","msg":"trace[1936940221] linearizableReadLoop","detail":"{readStateIndex:2010; appliedIndex:2009; }","duration":"275.595932ms","start":"2026-08-10T22:28:19.92033Z","end":"2026-08-10T22:28:20.195925Z","steps":["trace[1936940221] 'read index received' (duration: 273.915479ms)","trace[1936940221] 'applied index is now lower than readState.Index' (duration: 1.679273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:28:20.196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:28:19.799076Z","time spent":"396.890728ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T22:28:20.196121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.784997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-08-10T22:28:20.196173Z","caller":"traceutil/trace.go:171","msg":"trace[1420407783] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1936; }","duration":"275.865068ms","start":"2026-08-10T22:28:19.920295Z","end":"2026-08-10T22:28:20.19616Z","steps":["trace[1420407783] 'agreement among raft nodes before linearized reading' (duration: 275.719265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:58.791095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.522827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-10T22:28:58.791188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.187863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-10T22:28:58.791251Z","caller":"traceutil/trace.go:171","msg":"trace[569852761] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2159; }","duration":"213.725032ms","start":"2026-08-10T22:28:58.577499Z","end":"2026-08-10T22:28:58.791224Z","steps":["trace[569852761] 'count revisions from in-memory index tree' (duration: 213.410904ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:58.791284Z","caller":"traceutil/trace.go:171","msg":"trace[1886262863] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2159; }","duration":"249.339487ms","start":"2026-08-10T22:28:58.541919Z","end":"2026-08-10T22:28:58.791259Z","steps":["trace[1886262863] 'range keys from in-memory index tree' (duration: 248.997278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:58.791371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.518816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-10T22:28:58.791451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.298241ms","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-08-10T22:28:58.791539Z","caller":"traceutil/trace.go:171","msg":"trace[139954418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2159; }","duration":"243.426905ms","start":"2026-08-10T22:28:58.548093Z","end":"2026-08-10T22:28:58.79152Z","steps":["trace[139954418] 'range keys from in-memory index tree' (duration: 243.113227ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:28:58.791472Z","caller":"traceutil/trace.go:171","msg":"trace[1176316170] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2159; }","duration":"211.68745ms","start":"2026-08-10T22:28:58.579766Z","end":"2026-08-10T22:28:58.791454Z","steps":["trace[1176316170] 'count revisions from in-memory index tree' (duration: 211.410452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:28:59.281918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.427703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:28:59.282098Z","caller":"traceutil/trace.go:171","msg":"trace[1994833819] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"258.183854ms","start":"2026-08-10T22:28:59.023886Z","end":"2026-08-10T22:28:59.28207Z","steps":["trace[1994833819] 'process raft request' (duration: 132.501655ms)","trace[1994833819] 'compare' (duration: 125.283489ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:29:47.954691Z","caller":"traceutil/trace.go:171","msg":"trace[662858029] linearizableReadLoop","detail":"{readStateIndex:2700; appliedIndex:2699; }","duration":"255.536805ms","start":"2026-08-10T22:29:47.699121Z","end":"2026-08-10T22:29:47.954658Z","steps":["trace[662858029] 'read index received' (duration: 255.224727ms)","trace[662858029] 'applied index is now lower than readState.Index' (duration: 310.828µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:29:47.954705Z","caller":"traceutil/trace.go:171","msg":"trace[657931567] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"257.012943ms","start":"2026-08-10T22:29:47.697653Z","end":"2026-08-10T22:29:47.954666Z","steps":["trace[657931567] 'process raft request' (duration: 256.790897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:29:47.954831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.69569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:29:47.954921Z","caller":"traceutil/trace.go:171","msg":"trace[948134110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2599; }","duration":"255.836294ms","start":"2026-08-10T22:29:47.699065Z","end":"2026-08-10T22:29:47.954902Z","steps":["trace[948134110] 'agreement among raft nodes before linearized reading' (duration: 255.681029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:22.080179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.585864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:30:22.08031Z","caller":"traceutil/trace.go:171","msg":"trace[1020081048] linearizableReadLoop","detail":"{readStateIndex:2913; appliedIndex:2912; }","duration":"165.854661ms","start":"2026-08-10T22:30:21.914439Z","end":"2026-08-10T22:30:22.080293Z","steps":["trace[1020081048] 'read index received' (duration: 26.97059ms)","trace[1020081048] 'applied index is now lower than readState.Index' (duration: 138.882651ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:30:22.080381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.933153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:30:22.080419Z","caller":"traceutil/trace.go:171","msg":"trace[45564320] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2804; }","duration":"165.999635ms","start":"2026-08-10T22:30:21.914405Z","end":"2026-08-10T22:30:22.080404Z","steps":["trace[45564320] 'agreement among raft nodes before linearized reading' (duration: 165.936994ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:22.080471Z","caller":"traceutil/trace.go:171","msg":"trace[649936142] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"195.514981ms","start":"2026-08-10T22:30:21.884839Z","end":"2026-08-10T22:30:22.080354Z","steps":["trace[649936142] 'process raft request' (duration: 56.65689ms)","trace[649936142] 'compare' (duration: 138.44931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:30:22.330773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.984182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:30:22.331038Z","caller":"traceutil/trace.go:171","msg":"trace[480048557] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"199.420883ms","start":"2026-08-10T22:30:22.131587Z","end":"2026-08-10T22:30:22.331008Z","steps":["trace[480048557] 'process raft request' (duration: 96.069633ms)","trace[480048557] 'compare' (duration: 102.687912ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:30:22.331067Z","caller":"traceutil/trace.go:171","msg":"trace[1501676620] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"105.334932ms","start":"2026-08-10T22:30:22.225716Z","end":"2026-08-10T22:30:22.331051Z","steps":["trace[1501676620] 'process raft request' (duration: 105.201389ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:30:22.331323Z","caller":"traceutil/trace.go:171","msg":"trace[2025536425] transaction","detail":"{read_only:false; response_revision:2807; number_of_response:1; }","duration":"105.445265ms","start":"2026-08-10T22:30:22.225853Z","end":"2026-08-10T22:30:22.331299Z","steps":["trace[2025536425] 'process raft request' (duration: 105.152467ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:30:22.624977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.782294ms","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-08-10T22:30:22.625051Z","caller":"traceutil/trace.go:171","msg":"trace[99406949] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2807; }","duration":"120.916827ms","start":"2026-08-10T22:30:22.504119Z","end":"2026-08-10T22:30:22.625036Z","steps":["trace[99406949] 'count revisions from in-memory index tree' (duration: 120.69555ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:32:48.316707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.605781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:32:48.316831Z","caller":"traceutil/trace.go:171","msg":"trace[184842355] transaction","detail":"{read_only:false; response_revision:3779; number_of_response:1; }","duration":"112.3441ms","start":"2026-08-10T22:32:48.204465Z","end":"2026-08-10T22:32:48.316809Z","steps":["trace[184842355] 'process raft request' (duration: 10.548604ms)","trace[184842355] 'compare' (duration: 101.505909ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:32:48.316866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.955665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qlxhv\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-10T22:32:48.31703Z","caller":"traceutil/trace.go:171","msg":"trace[188184702] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qlxhv; range_end:; response_count:1; response_revision:3778; }","duration":"180.213271ms","start":"2026-08-10T22:32:48.136789Z","end":"2026-08-10T22:32:48.317002Z","steps":["trace[188184702] 'range keys from in-memory index tree' (duration: 179.601964ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:09.018295Z","caller":"traceutil/trace.go:171","msg":"trace[1997812995] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"143.614715ms","start":"2026-08-10T22:34:08.874547Z","end":"2026-08-10T22:34:09.018161Z","steps":["trace[1997812995] 'process raft request' (duration: 143.497512ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:09.024083Z","caller":"traceutil/trace.go:171","msg":"trace[458050124] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4901; }","duration":"125.911805ms","start":"2026-08-10T22:34:08.898127Z","end":"2026-08-10T22:34:09.024039Z","steps":["trace[458050124] 'read index received' (duration: 120.015821ms)","trace[458050124] 'applied index is now lower than readState.Index' (duration: 5.894894ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:09.024229Z","caller":"traceutil/trace.go:171","msg":"trace[1062284689] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"149.504679ms","start":"2026-08-10T22:34:08.874713Z","end":"2026-08-10T22:34:09.024217Z","steps":["trace[1062284689] 'process raft request' (duration: 148.998185ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:09.024362Z","caller":"traceutil/trace.go:171","msg":"trace[1548422627] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"145.945806ms","start":"2026-08-10T22:34:08.878387Z","end":"2026-08-10T22:34:09.024333Z","steps":["trace[1548422627] 'process raft request' (duration: 145.481323ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:09.024345Z","caller":"traceutil/trace.go:171","msg":"trace[334624068] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"133.823541ms","start":"2026-08-10T22:34:08.890473Z","end":"2026-08-10T22:34:09.024297Z","steps":["trace[334624068] 'process raft request' (duration: 133.509123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:09.024565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.419868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-10T22:34:09.024614Z","caller":"traceutil/trace.go:171","msg":"trace[994114216] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4721; }","duration":"126.50575ms","start":"2026-08-10T22:34:08.898098Z","end":"2026-08-10T22:34:09.024604Z","steps":["trace[994114216] 'agreement among raft nodes before linearized reading' (duration: 126.348206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.752311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.808476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:18.752592Z","caller":"traceutil/trace.go:171","msg":"trace[126209271] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"432.300333ms","start":"2026-08-10T22:34:18.32025Z","end":"2026-08-10T22:34:18.75255Z","steps":["trace[126209271] 'process raft request' (duration: 222.119917ms)","trace[126209271] 'compare' (duration: 209.610092ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:18.752687Z","caller":"traceutil/trace.go:171","msg":"trace[1434823623] linearizableReadLoop","detail":"{readStateIndex:5185; appliedIndex:5183; }","duration":"432.17906ms","start":"2026-08-10T22:34:18.320483Z","end":"2026-08-10T22:34:18.752662Z","steps":["trace[1434823623] 'read index received' (duration: 16.722175ms)","trace[1434823623] 'applied index is now lower than readState.Index' (duration: 415.454895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:18.752785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:18.320222Z","time spent":"432.459018ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4934,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:18.752922Z","caller":"traceutil/trace.go:171","msg":"trace[1978509045] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"431.500163ms","start":"2026-08-10T22:34:18.32139Z","end":"2026-08-10T22:34:18.75289Z","steps":["trace[1978509045] 'process raft request' (duration: 431.106893ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:18.7528Z","caller":"traceutil/trace.go:171","msg":"trace[1945673416] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"429.272214ms","start":"2026-08-10T22:34:18.323515Z","end":"2026-08-10T22:34:18.752787Z","steps":["trace[1945673416] 'process raft request' (duration: 429.076729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.752974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.486638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-08-10T22:34:18.753024Z","caller":"traceutil/trace.go:171","msg":"trace[705339335] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:4993; }","duration":"432.57093ms","start":"2026-08-10T22:34:18.32044Z","end":"2026-08-10T22:34:18.753011Z","steps":["trace[705339335] 'agreement among raft nodes before linearized reading' (duration: 432.384505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.753038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:18.32349Z","time spent":"429.518151ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5068,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:18.753056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:18.321375Z","time spent":"431.624636ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-10T22:34:18.75307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:18.320427Z","time spent":"432.630292ms","remote":"127.0.0.1:43130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-08-10T22:34:18.770847Z","caller":"traceutil/trace.go:171","msg":"trace[117643875] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"117.111466ms","start":"2026-08-10T22:34:18.653701Z","end":"2026-08-10T22:34:18.770812Z","steps":["trace[117643875] 'process raft request' (duration: 116.90828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.771051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.851011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-7xdmj-tls\" ","response":"range_response_count:1 size:2183"} {"level":"warn","ts":"2026-08-10T22:34:18.771039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.119064ms","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-08-10T22:34:18.771113Z","caller":"traceutil/trace.go:171","msg":"trace[1424621878] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-7xdmj-tls; range_end:; response_count:1; response_revision:4994; }","duration":"386.957094ms","start":"2026-08-10T22:34:18.38414Z","end":"2026-08-10T22:34:18.771097Z","steps":["trace[1424621878] 'agreement among raft nodes before linearized reading' (duration: 386.771059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.771144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:18.384114Z","time spent":"387.024066ms","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":2205,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-7xdmj-tls\" "} {"level":"info","ts":"2026-08-10T22:34:18.771137Z","caller":"traceutil/trace.go:171","msg":"trace[1006160310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4994; }","duration":"205.279308ms","start":"2026-08-10T22:34:18.565835Z","end":"2026-08-10T22:34:18.771115Z","steps":["trace[1006160310] 'agreement among raft nodes before linearized reading' (duration: 204.992371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.771368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.739442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-955fda81-7310-4d19-9814-d574f94c3b09\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-08-10T22:34:18.771469Z","caller":"traceutil/trace.go:171","msg":"trace[1419442111] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-955fda81-7310-4d19-9814-d574f94c3b09; range_end:; response_count:1; response_revision:4994; }","duration":"270.887336ms","start":"2026-08-10T22:34:18.500559Z","end":"2026-08-10T22:34:18.771446Z","steps":["trace[1419442111] 'agreement among raft nodes before linearized reading' (duration: 270.595548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.771667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.280252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787121"} {"level":"info","ts":"2026-08-10T22:34:18.771817Z","caller":"traceutil/trace.go:171","msg":"trace[577875977] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4994; }","duration":"372.468418ms","start":"2026-08-10T22:34:18.399319Z","end":"2026-08-10T22:34:18.771787Z","steps":["trace[577875977] 'agreement among raft nodes before linearized reading' (duration: 371.550944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:18.771897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:18.399311Z","time spent":"372.56986ms","remote":"127.0.0.1:43074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":787143,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-08-10T22:34:19.11271Z","caller":"traceutil/trace.go:171","msg":"trace[573549419] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5192; }","duration":"117.65636ms","start":"2026-08-10T22:34:18.995021Z","end":"2026-08-10T22:34:19.112677Z","steps":["trace[573549419] 'read index received' (duration: 117.389413ms)","trace[573549419] 'applied index is now lower than readState.Index' (duration: 265.407µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:19.112836Z","caller":"traceutil/trace.go:171","msg":"trace[74133698] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"158.76575ms","start":"2026-08-10T22:34:18.954021Z","end":"2026-08-10T22:34:19.112787Z","steps":["trace[74133698] 'process raft request' (duration: 158.439092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:19.113454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.410059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787121"} {"level":"info","ts":"2026-08-10T22:34:19.11351Z","caller":"traceutil/trace.go:171","msg":"trace[439419812] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4999; }","duration":"118.547163ms","start":"2026-08-10T22:34:18.994947Z","end":"2026-08-10T22:34:19.113494Z","steps":["trace[439419812] 'agreement among raft nodes before linearized reading' (duration: 117.912037ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:29.916355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.982891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:29.916467Z","caller":"traceutil/trace.go:171","msg":"trace[2055675224] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:5193; }","duration":"115.180005ms","start":"2026-08-10T22:34:29.801265Z","end":"2026-08-10T22:34:29.916445Z","steps":["trace[2055675224] 'count revisions from in-memory index tree' (duration: 114.855537ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:33.02148Z","caller":"traceutil/trace.go:171","msg":"trace[621708859] linearizableReadLoop","detail":"{readStateIndex:5453; appliedIndex:5452; }","duration":"101.50387ms","start":"2026-08-10T22:34:32.919943Z","end":"2026-08-10T22:34:33.021446Z","steps":["trace[621708859] 'read index received' (duration: 85.587126ms)","trace[621708859] 'applied index is now lower than readState.Index' (duration: 15.915504ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:33.021693Z","caller":"traceutil/trace.go:171","msg":"trace[1006541259] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"102.782783ms","start":"2026-08-10T22:34:32.91887Z","end":"2026-08-10T22:34:33.021653Z","steps":["trace[1006541259] 'process raft request' (duration: 86.837548ms)","trace[1006541259] 'compare' (duration: 15.593806ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:33.021843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.888519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:71021"} {"level":"info","ts":"2026-08-10T22:34:33.021907Z","caller":"traceutil/trace.go:171","msg":"trace[1178757428] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5250; }","duration":"101.999563ms","start":"2026-08-10T22:34:32.919893Z","end":"2026-08-10T22:34:33.021893Z","steps":["trace[1178757428] 'agreement among raft nodes before linearized reading' (duration: 101.664104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:41.792019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.94926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:34:41.792218Z","caller":"traceutil/trace.go:171","msg":"trace[1953278356] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"364.251587ms","start":"2026-08-10T22:34:41.427949Z","end":"2026-08-10T22:34:41.7922Z","steps":["trace[1953278356] 'process raft request' (duration: 364.193915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:41.792291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.427911Z","time spent":"364.349279ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:41.792335Z","caller":"traceutil/trace.go:171","msg":"trace[433609294] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"407.646294ms","start":"2026-08-10T22:34:41.384666Z","end":"2026-08-10T22:34:41.792313Z","steps":["trace[433609294] 'process raft request' (duration: 90.323565ms)","trace[433609294] 'compare' (duration: 316.842467ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:34:41.792433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.384642Z","time spent":"407.743956ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:34:42.054291Z","caller":"traceutil/trace.go:171","msg":"trace[102152821] linearizableReadLoop","detail":"{readStateIndex:5653; appliedIndex:5652; }","duration":"455.633099ms","start":"2026-08-10T22:34:41.598613Z","end":"2026-08-10T22:34:42.054246Z","steps":["trace[102152821] 'read index received' (duration: 455.364702ms)","trace[102152821] 'applied index is now lower than readState.Index' (duration: 266.437µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:42.054451Z","caller":"traceutil/trace.go:171","msg":"trace[1705214630] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"544.187759ms","start":"2026-08-10T22:34:41.510246Z","end":"2026-08-10T22:34:42.054434Z","steps":["trace[1705214630] 'process raft request' (duration: 543.746917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.054603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.510213Z","time spent":"544.274581ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:42.054916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.254534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee8ff039-cf0f-4d1c-9b46-59bc8ca2968c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-10T22:34:42.055036Z","caller":"traceutil/trace.go:171","msg":"trace[1299639664] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee8ff039-cf0f-4d1c-9b46-59bc8ca2968c; range_end:; response_count:1; response_revision:5445; }","duration":"456.456049ms","start":"2026-08-10T22:34:41.598554Z","end":"2026-08-10T22:34:42.05501Z","steps":["trace[1299639664] 'agreement among raft nodes before linearized reading' (duration: 455.986227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.055087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.598532Z","time spent":"456.546433ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee8ff039-cf0f-4d1c-9b46-59bc8ca2968c\" "} {"level":"warn","ts":"2026-08-10T22:34:42.070452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.333829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-10T22:34:42.070523Z","caller":"traceutil/trace.go:171","msg":"trace[1534088853] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"302.534205ms","start":"2026-08-10T22:34:41.767969Z","end":"2026-08-10T22:34:42.070503Z","steps":["trace[1534088853] 'process raft request' (duration: 302.083413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.070517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.139384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:42.070555Z","caller":"traceutil/trace.go:171","msg":"trace[1779269749] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:5446; }","duration":"114.539054ms","start":"2026-08-10T22:34:41.955991Z","end":"2026-08-10T22:34:42.07053Z","steps":["trace[1779269749] 'agreement among raft nodes before linearized reading' (duration: 114.352889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.070596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.785546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-10T22:34:42.070615Z","caller":"traceutil/trace.go:171","msg":"trace[1168339679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5446; }","duration":"369.271367ms","start":"2026-08-10T22:34:41.701317Z","end":"2026-08-10T22:34:42.070589Z","steps":["trace[1168339679] 'agreement among raft nodes before linearized reading' (duration: 369.113113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.070641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.767941Z","time spent":"302.639737ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:34:42.070675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.701297Z","time spent":"369.365099ms","remote":"127.0.0.1:42958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-10T22:34:42.070545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.313147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2318"} {"level":"info","ts":"2026-08-10T22:34:42.070817Z","caller":"traceutil/trace.go:171","msg":"trace[92691544] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5446; }","duration":"394.632555ms","start":"2026-08-10T22:34:41.676155Z","end":"2026-08-10T22:34:42.070787Z","steps":["trace[92691544] 'agreement among raft nodes before linearized reading' (duration: 394.220755ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:42.070663Z","caller":"traceutil/trace.go:171","msg":"trace[693285067] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5446; }","duration":"417.864969ms","start":"2026-08-10T22:34:41.652777Z","end":"2026-08-10T22:34:42.070642Z","steps":["trace[693285067] 'agreement among raft nodes before linearized reading' (duration: 417.743535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.070899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.652759Z","time spent":"418.124375ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-10T22:34:42.070907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.676132Z","time spent":"394.749399ms","remote":"127.0.0.1:55850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2340,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-08-10T22:34:42.070449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.370184ms","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-08-10T22:34:42.071046Z","caller":"traceutil/trace.go:171","msg":"trace[1053179108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5446; }","duration":"426.00019ms","start":"2026-08-10T22:34:41.645012Z","end":"2026-08-10T22:34:42.071012Z","steps":["trace[1053179108] 'agreement among raft nodes before linearized reading' (duration: 425.256331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.071127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:34:41.644992Z","time spent":"426.111592ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-10T22:34:42.311695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.856962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:34:42.311763Z","caller":"traceutil/trace.go:171","msg":"trace[2074582392] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5447; }","duration":"146.980445ms","start":"2026-08-10T22:34:42.164768Z","end":"2026-08-10T22:34:42.311748Z","steps":["trace[2074582392] 'range keys from in-memory index tree' (duration: 146.759849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.311681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.613534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee8ff039-cf0f-4d1c-9b46-59bc8ca2968c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-10T22:34:42.311913Z","caller":"traceutil/trace.go:171","msg":"trace[177134489] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee8ff039-cf0f-4d1c-9b46-59bc8ca2968c; range_end:; response_count:1; response_revision:5447; }","duration":"102.883271ms","start":"2026-08-10T22:34:42.208976Z","end":"2026-08-10T22:34:42.31186Z","steps":["trace[177134489] 'range keys from in-memory index tree' (duration: 102.376858ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:34:42.43923Z","caller":"traceutil/trace.go:171","msg":"trace[1052657080] linearizableReadLoop","detail":"{readStateIndex:5656; appliedIndex:5655; }","duration":"118.869946ms","start":"2026-08-10T22:34:42.320333Z","end":"2026-08-10T22:34:42.439203Z","steps":["trace[1052657080] 'read index received' (duration: 118.694022ms)","trace[1052657080] 'applied index is now lower than readState.Index' (duration: 174.514µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:34:42.439349Z","caller":"traceutil/trace.go:171","msg":"trace[1536643810] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"119.628556ms","start":"2026-08-10T22:34:42.319672Z","end":"2026-08-10T22:34:42.4393Z","steps":["trace[1536643810] 'process raft request' (duration: 119.229596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:34:42.440426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.867383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18309"} {"level":"info","ts":"2026-08-10T22:34:42.440493Z","caller":"traceutil/trace.go:171","msg":"trace[282060733] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5448; }","duration":"120.17616ms","start":"2026-08-10T22:34:42.320302Z","end":"2026-08-10T22:34:42.440478Z","steps":["trace[282060733] 'agreement among raft nodes before linearized reading' (duration: 119.079342ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:35:52.70674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2973} {"level":"info","ts":"2026-08-10T22:35:52.788672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2973,"took":"79.434632ms","hash":139673216,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":34013184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-10T22:35:52.788731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":139673216,"revision":2973,"compact-revision":-1} {"level":"info","ts":"2026-08-10T22:40:52.716943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6074} {"level":"info","ts":"2026-08-10T22:40:52.811753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6074,"took":"93.173573ms","hash":1722195128,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":21852160,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-10T22:40:52.811823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1722195128,"revision":6074,"compact-revision":2973} {"level":"warn","ts":"2026-08-10T22:41:01.776865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.861749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qlxhv\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-08-10T22:41:01.776935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.215075ms","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-08-10T22:41:01.77703Z","caller":"traceutil/trace.go:171","msg":"trace[225229575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8316; }","duration":"480.336089ms","start":"2026-08-10T22:41:01.296672Z","end":"2026-08-10T22:41:01.777008Z","steps":["trace[225229575] 'range keys from in-memory index tree' (duration: 480.06217ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:01.776973Z","caller":"traceutil/trace.go:171","msg":"trace[323984245] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qlxhv; range_end:; response_count:1; response_revision:8316; }","duration":"448.017603ms","start":"2026-08-10T22:41:01.328933Z","end":"2026-08-10T22:41:01.776951Z","steps":["trace[323984245] 'range keys from in-memory index tree' (duration: 447.628742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:01.777075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:01.296665Z","time spent":"480.40079ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-10T22:41:01.777098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:01.328898Z","time spent":"448.190978ms","remote":"127.0.0.1:43166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-qlxhv\" "} {"level":"warn","ts":"2026-08-10T22:41:01.776893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.016906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-10T22:41:01.777158Z","caller":"traceutil/trace.go:171","msg":"trace[1774300076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8316; }","duration":"481.333304ms","start":"2026-08-10T22:41:01.295803Z","end":"2026-08-10T22:41:01.777136Z","steps":["trace[1774300076] 'range keys from in-memory index tree' (duration: 480.869981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:01.777193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:01.295782Z","time spent":"481.399315ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-10T22:41:01.777243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.556812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-10T22:41:01.777416Z","caller":"traceutil/trace.go:171","msg":"trace[301634184] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"215.367536ms","start":"2026-08-10T22:41:01.562018Z","end":"2026-08-10T22:41:01.777385Z","steps":["trace[301634184] 'process raft request' (duration: 87.516836ms)","trace[301634184] 'compare' (duration: 126.955206ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:01.851268Z","caller":"traceutil/trace.go:171","msg":"trace[1408379344] linearizableReadLoop","detail":"{readStateIndex:8619; appliedIndex:8618; }","duration":"149.862904ms","start":"2026-08-10T22:41:01.701375Z","end":"2026-08-10T22:41:01.851237Z","steps":["trace[1408379344] 'read index received' (duration: 149.72572ms)","trace[1408379344] 'applied index is now lower than readState.Index' (duration: 135.674µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:01.851369Z","caller":"traceutil/trace.go:171","msg":"trace[63200312] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"236.809265ms","start":"2026-08-10T22:41:01.614517Z","end":"2026-08-10T22:41:01.851326Z","steps":["trace[63200312] 'process raft request' (duration: 236.509687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:01.851733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.349687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:41:01.851802Z","caller":"traceutil/trace.go:171","msg":"trace[907019294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8318; }","duration":"150.46966ms","start":"2026-08-10T22:41:01.701316Z","end":"2026-08-10T22:41:01.851785Z","steps":["trace[907019294] 'agreement among raft nodes before linearized reading' (duration: 150.260584ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:01.897842Z","caller":"traceutil/trace.go:171","msg":"trace[2069630833] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"115.439897ms","start":"2026-08-10T22:41:01.782358Z","end":"2026-08-10T22:41:01.897798Z","steps":["trace[2069630833] 'process raft request' (duration: 115.18875ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:01.898192Z","caller":"traceutil/trace.go:171","msg":"trace[550311859] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"115.21778ms","start":"2026-08-10T22:41:01.782929Z","end":"2026-08-10T22:41:01.898147Z","steps":["trace[550311859] 'process raft request' (duration: 114.816319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:01.898355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.146442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:41:01.898434Z","caller":"traceutil/trace.go:171","msg":"trace[235289049] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8320; }","duration":"127.289855ms","start":"2026-08-10T22:41:01.771122Z","end":"2026-08-10T22:41:01.898411Z","steps":["trace[235289049] 'agreement among raft nodes before linearized reading' (duration: 127.132471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:01.899489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.000479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224103"} {"level":"info","ts":"2026-08-10T22:41:01.899557Z","caller":"traceutil/trace.go:171","msg":"trace[621006292] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8320; }","duration":"116.129483ms","start":"2026-08-10T22:41:01.783408Z","end":"2026-08-10T22:41:01.899537Z","steps":["trace[621006292] 'agreement among raft nodes before linearized reading' (duration: 115.065186ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:13.594592Z","caller":"traceutil/trace.go:171","msg":"trace[439044194] linearizableReadLoop","detail":"{readStateIndex:8680; appliedIndex:8679; }","duration":"366.036809ms","start":"2026-08-10T22:41:13.228527Z","end":"2026-08-10T22:41:13.594564Z","steps":["trace[439044194] 'read index received' (duration: 365.893995ms)","trace[439044194] 'applied index is now lower than readState.Index' (duration: 142.074µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:13.594648Z","caller":"traceutil/trace.go:171","msg":"trace[1953234547] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"397.670612ms","start":"2026-08-10T22:41:13.196947Z","end":"2026-08-10T22:41:13.594617Z","steps":["trace[1953234547] 'process raft request' (duration: 397.445096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:13.594739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.186573ms","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:7"} {"level":"info","ts":"2026-08-10T22:41:13.594775Z","caller":"traceutil/trace.go:171","msg":"trace[754880134] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8377; }","duration":"366.243354ms","start":"2026-08-10T22:41:13.22852Z","end":"2026-08-10T22:41:13.594763Z","steps":["trace[754880134] 'agreement among raft nodes before linearized reading' (duration: 366.158902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:13.594778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:13.196917Z","time spent":"397.783476ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:41:13.594815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:13.228463Z","time spent":"366.344286ms","remote":"127.0.0.1:56992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-08-10T22:41:13.606529Z","caller":"traceutil/trace.go:171","msg":"trace[1335152091] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"353.967381ms","start":"2026-08-10T22:41:13.252527Z","end":"2026-08-10T22:41:13.606495Z","steps":["trace[1335152091] 'process raft request' (duration: 353.790786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:13.606718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:41:13.252499Z","time spent":"354.123025ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:41:14.749083Z","caller":"traceutil/trace.go:171","msg":"trace[120755218] linearizableReadLoop","detail":"{readStateIndex:8688; appliedIndex:8687; }","duration":"158.110408ms","start":"2026-08-10T22:41:14.590947Z","end":"2026-08-10T22:41:14.749058Z","steps":["trace[120755218] 'read index received' (duration: 157.934453ms)","trace[120755218] 'applied index is now lower than readState.Index' (duration: 174.725µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:41:14.749305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.344275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:41:14.749358Z","caller":"traceutil/trace.go:171","msg":"trace[880729741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8385; }","duration":"158.461098ms","start":"2026-08-10T22:41:14.590885Z","end":"2026-08-10T22:41:14.749346Z","steps":["trace[880729741] 'agreement among raft nodes before linearized reading' (duration: 158.294663ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:14.749331Z","caller":"traceutil/trace.go:171","msg":"trace[1260998996] transaction","detail":"{read_only:false; response_revision:8385; number_of_response:1; }","duration":"212.828807ms","start":"2026-08-10T22:41:14.536347Z","end":"2026-08-10T22:41:14.749176Z","steps":["trace[1260998996] 'process raft request' (duration: 212.535559ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:15.717627Z","caller":"traceutil/trace.go:171","msg":"trace[633658305] transaction","detail":"{read_only:false; response_revision:8388; number_of_response:1; }","duration":"113.641186ms","start":"2026-08-10T22:41:15.603959Z","end":"2026-08-10T22:41:15.7176Z","steps":["trace[633658305] 'process raft request' (duration: 113.475101ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:15.736142Z","caller":"traceutil/trace.go:171","msg":"trace[1371395084] linearizableReadLoop","detail":"{readStateIndex:8692; appliedIndex:8690; }","duration":"117.04497ms","start":"2026-08-10T22:41:15.619056Z","end":"2026-08-10T22:41:15.736101Z","steps":["trace[1371395084] 'read index received' (duration: 98.468492ms)","trace[1371395084] 'applied index is now lower than readState.Index' (duration: 18.573728ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:15.736284Z","caller":"traceutil/trace.go:171","msg":"trace[796182924] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"124.360736ms","start":"2026-08-10T22:41:15.611878Z","end":"2026-08-10T22:41:15.736239Z","steps":["trace[796182924] 'process raft request' (duration: 123.992445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:15.736468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.400661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-10T22:41:15.736546Z","caller":"traceutil/trace.go:171","msg":"trace[904134623] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:8389; }","duration":"117.518684ms","start":"2026-08-10T22:41:15.619011Z","end":"2026-08-10T22:41:15.736529Z","steps":["trace[904134623] 'agreement among raft nodes before linearized reading' (duration: 117.240426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:16.178904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.067872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224103"} {"level":"info","ts":"2026-08-10T22:41:16.178979Z","caller":"traceutil/trace.go:171","msg":"trace[923805949] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8393; }","duration":"107.185446ms","start":"2026-08-10T22:41:16.071778Z","end":"2026-08-10T22:41:16.178963Z","steps":["trace[923805949] 'range keys from in-memory index tree' (duration: 106.739812ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:16.225509Z","caller":"traceutil/trace.go:171","msg":"trace[1242811435] transaction","detail":"{read_only:false; response_revision:8394; number_of_response:1; }","duration":"153.321224ms","start":"2026-08-10T22:41:16.071906Z","end":"2026-08-10T22:41:16.225228Z","steps":["trace[1242811435] 'process raft request' (duration: 152.768889ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:41.008419Z","caller":"traceutil/trace.go:171","msg":"trace[817703199] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"100.131118ms","start":"2026-08-10T22:41:40.908252Z","end":"2026-08-10T22:41:41.008384Z","steps":["trace[817703199] 'process raft request' (duration: 99.198192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:44.801111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.032493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-10T22:41:44.801088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.391211ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:41:44.801219Z","caller":"traceutil/trace.go:171","msg":"trace[1405303703] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8599; }","duration":"150.257889ms","start":"2026-08-10T22:41:44.65094Z","end":"2026-08-10T22:41:44.801198Z","steps":["trace[1405303703] 'count revisions from in-memory index tree' (duration: 149.882759ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:44.801249Z","caller":"traceutil/trace.go:171","msg":"trace[663512513] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8599; }","duration":"214.566096ms","start":"2026-08-10T22:41:44.586655Z","end":"2026-08-10T22:41:44.801221Z","steps":["trace[663512513] 'range keys from in-memory index tree' (duration: 214.36903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:44.801407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.962869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:41:44.801463Z","caller":"traceutil/trace.go:171","msg":"trace[1355999882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8599; }","duration":"102.059472ms","start":"2026-08-10T22:41:44.69939Z","end":"2026-08-10T22:41:44.801449Z","steps":["trace[1355999882] 'range keys from in-memory index tree' (duration: 101.875048ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:45.096576Z","caller":"traceutil/trace.go:171","msg":"trace[890123899] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"236.073696ms","start":"2026-08-10T22:41:44.860463Z","end":"2026-08-10T22:41:45.096537Z","steps":["trace[890123899] 'process raft request' (duration: 235.739117ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:48.038812Z","caller":"traceutil/trace.go:171","msg":"trace[1812880524] linearizableReadLoop","detail":"{readStateIndex:8927; appliedIndex:8926; }","duration":"108.347067ms","start":"2026-08-10T22:41:47.930437Z","end":"2026-08-10T22:41:48.038784Z","steps":["trace[1812880524] 'read index received' (duration: 108.266444ms)","trace[1812880524] 'applied index is now lower than readState.Index' (duration: 79.472µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:48.038916Z","caller":"traceutil/trace.go:171","msg":"trace[1608273629] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"127.924113ms","start":"2026-08-10T22:41:47.910961Z","end":"2026-08-10T22:41:48.038885Z","steps":["trace[1608273629] 'process raft request' (duration: 127.665646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:41:48.039136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.675507ms","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-08-10T22:41:48.039205Z","caller":"traceutil/trace.go:171","msg":"trace[146565007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8614; }","duration":"108.79848ms","start":"2026-08-10T22:41:47.930391Z","end":"2026-08-10T22:41:48.03919Z","steps":["trace[146565007] 'agreement among raft nodes before linearized reading' (duration: 108.509701ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:48.117067Z","caller":"traceutil/trace.go:171","msg":"trace[38298906] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"125.345942ms","start":"2026-08-10T22:41:47.991688Z","end":"2026-08-10T22:41:48.117034Z","steps":["trace[38298906] 'process raft request' (duration: 109.5046ms)","trace[38298906] 'compare' (duration: 15.698038ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:41:48.158548Z","caller":"traceutil/trace.go:171","msg":"trace[934144039] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"114.779487ms","start":"2026-08-10T22:41:48.043731Z","end":"2026-08-10T22:41:48.158511Z","steps":["trace[934144039] 'process raft request' (duration: 114.52268ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:41:57.19684Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002342.wal"} {"level":"info","ts":"2026-08-10T22:41:58.905632Z","caller":"traceutil/trace.go:171","msg":"trace[153555206] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"147.200424ms","start":"2026-08-10T22:41:58.758403Z","end":"2026-08-10T22:41:58.905603Z","steps":["trace[153555206] 'process raft request' (duration: 147.016248ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:02.882658Z","caller":"traceutil/trace.go:171","msg":"trace[498008409] transaction","detail":"{read_only:false; response_revision:9394; number_of_response:1; }","duration":"116.011221ms","start":"2026-08-10T22:43:02.76661Z","end":"2026-08-10T22:43:02.882621Z","steps":["trace[498008409] 'process raft request' (duration: 115.823926ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:02.901714Z","caller":"traceutil/trace.go:171","msg":"trace[1414087763] linearizableReadLoop","detail":"{readStateIndex:9731; appliedIndex:9729; }","duration":"115.152057ms","start":"2026-08-10T22:43:02.786533Z","end":"2026-08-10T22:43:02.901685Z","steps":["trace[1414087763] 'read index received' (duration: 95.93923ms)","trace[1414087763] 'applied index is now lower than readState.Index' (duration: 19.211697ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:43:02.90185Z","caller":"traceutil/trace.go:171","msg":"trace[544968757] transaction","detail":"{read_only:false; response_revision:9395; number_of_response:1; }","duration":"134.886849ms","start":"2026-08-10T22:43:02.766947Z","end":"2026-08-10T22:43:02.901834Z","steps":["trace[544968757] 'process raft request' (duration: 134.565291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:43:02.902225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.623841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-08-10T22:43:02.902336Z","caller":"traceutil/trace.go:171","msg":"trace[910298055] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:9395; }","duration":"115.807686ms","start":"2026-08-10T22:43:02.786504Z","end":"2026-08-10T22:43:02.902311Z","steps":["trace[910298055] 'agreement among raft nodes before linearized reading' (duration: 115.421045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:43:15.791901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.494267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:43:15.792062Z","caller":"traceutil/trace.go:171","msg":"trace[600848303] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9467; }","duration":"144.702682ms","start":"2026-08-10T22:43:15.647329Z","end":"2026-08-10T22:43:15.792031Z","steps":["trace[600848303] 'range keys from in-memory index tree' (duration: 144.215059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:43:15.792098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.617514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-10T22:43:15.79209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.524333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-10T22:43:15.792218Z","caller":"traceutil/trace.go:171","msg":"trace[326742746] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:9467; }","duration":"113.742858ms","start":"2026-08-10T22:43:15.678444Z","end":"2026-08-10T22:43:15.792187Z","steps":["trace[326742746] 'count revisions from in-memory index tree' (duration: 113.531802ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:15.79224Z","caller":"traceutil/trace.go:171","msg":"trace[1596831972] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:9467; }","duration":"166.730439ms","start":"2026-08-10T22:43:15.625464Z","end":"2026-08-10T22:43:15.792194Z","steps":["trace[1596831972] 'range keys from in-memory index tree' (duration: 166.274786ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:43:41.082857Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T22:43:41.089393Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-10T22:43:41.089503Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-08-10T22:45:19.825536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.757142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19239"} {"level":"info","ts":"2026-08-10T22:45:19.825687Z","caller":"traceutil/trace.go:171","msg":"trace[948729309] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10328; }","duration":"154.972488ms","start":"2026-08-10T22:45:19.670687Z","end":"2026-08-10T22:45:19.825659Z","steps":["trace[948729309] 'range keys from in-memory index tree' (duration: 154.535006ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:45:52.723432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8133} {"level":"info","ts":"2026-08-10T22:45:52.786987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8133,"took":"61.577881ms","hash":277400351,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":28098560,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-10T22:45:52.787094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":277400351,"revision":8133,"compact-revision":6074} {"level":"info","ts":"2026-08-10T22:48:51.969441Z","caller":"traceutil/trace.go:171","msg":"trace[423860676] transaction","detail":"{read_only:false; response_revision:11889; number_of_response:1; }","duration":"218.383744ms","start":"2026-08-10T22:48:51.751019Z","end":"2026-08-10T22:48:51.969403Z","steps":["trace[423860676] 'process raft request' (duration: 207.581222ms)","trace[423860676] 'compare' (duration: 10.371871ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:48:52.004602Z","caller":"traceutil/trace.go:171","msg":"trace[1683075817] transaction","detail":"{read_only:false; response_revision:11890; number_of_response:1; }","duration":"236.040617ms","start":"2026-08-10T22:48:51.768512Z","end":"2026-08-10T22:48:52.004552Z","steps":["trace[1683075817] 'process raft request' (duration: 235.812622ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:50:52.729378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10495} {"level":"info","ts":"2026-08-10T22:50:52.81686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10495,"took":"85.000724ms","hash":689058165,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":26243072,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-10T22:50:52.816927Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":689058165,"revision":10495,"compact-revision":8133} {"level":"info","ts":"2026-08-10T22:55:17.813478Z","caller":"traceutil/trace.go:171","msg":"trace[423499104] linearizableReadLoop","detail":"{readStateIndex:14980; appliedIndex:14979; }","duration":"113.813127ms","start":"2026-08-10T22:55:17.699629Z","end":"2026-08-10T22:55:17.813442Z","steps":["trace[423499104] 'read index received' (duration: 113.424457ms)","trace[423499104] 'applied index is now lower than readState.Index' (duration: 387.18µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:55:17.813626Z","caller":"traceutil/trace.go:171","msg":"trace[1139442202] transaction","detail":"{read_only:false; response_revision:14471; number_of_response:1; }","duration":"134.225111ms","start":"2026-08-10T22:55:17.679373Z","end":"2026-08-10T22:55:17.813598Z","steps":["trace[1139442202] 'process raft request' (duration: 133.82807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:17.813702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.046333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:55:17.813757Z","caller":"traceutil/trace.go:171","msg":"trace[1223467411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14471; }","duration":"114.170066ms","start":"2026-08-10T22:55:17.699574Z","end":"2026-08-10T22:55:17.813744Z","steps":["trace[1223467411] 'agreement among raft nodes before linearized reading' (duration: 114.025933ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:34.402296Z","caller":"traceutil/trace.go:171","msg":"trace[314523609] transaction","detail":"{read_only:false; response_revision:14568; number_of_response:1; }","duration":"244.583938ms","start":"2026-08-10T22:55:34.15768Z","end":"2026-08-10T22:55:34.402264Z","steps":["trace[314523609] 'process raft request' (duration: 244.350051ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:34.609814Z","caller":"traceutil/trace.go:171","msg":"trace[1888092491] linearizableReadLoop","detail":"{readStateIndex:15082; appliedIndex:15081; }","duration":"139.902199ms","start":"2026-08-10T22:55:34.469888Z","end":"2026-08-10T22:55:34.60979Z","steps":["trace[1888092491] 'read index received' (duration: 139.692174ms)","trace[1888092491] 'applied index is now lower than readState.Index' (duration: 208.025µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:55:34.609863Z","caller":"traceutil/trace.go:171","msg":"trace[378963476] transaction","detail":"{read_only:false; response_revision:14569; number_of_response:1; }","duration":"341.526832ms","start":"2026-08-10T22:55:34.26831Z","end":"2026-08-10T22:55:34.609837Z","steps":["trace[378963476] 'process raft request' (duration: 341.274476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:34.610004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.092654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-10T22:55:34.610068Z","caller":"traceutil/trace.go:171","msg":"trace[2056412583] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:14569; }","duration":"140.215927ms","start":"2026-08-10T22:55:34.469836Z","end":"2026-08-10T22:55:34.610052Z","steps":["trace[2056412583] 'agreement among raft nodes before linearized reading' (duration: 140.084794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:34.610017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:34.268278Z","time spent":"341.656326ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T22:55:44.05875Z","caller":"traceutil/trace.go:171","msg":"trace[1739551843] linearizableReadLoop","detail":"{readStateIndex:15255; appliedIndex:15254; }","duration":"116.709902ms","start":"2026-08-10T22:55:43.941993Z","end":"2026-08-10T22:55:44.058703Z","steps":["trace[1739551843] 'read index received' (duration: 75.798202ms)","trace[1739551843] 'applied index is now lower than readState.Index' (duration: 40.90973ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:55:44.058781Z","caller":"traceutil/trace.go:171","msg":"trace[697243604] transaction","detail":"{read_only:false; response_revision:14738; number_of_response:1; }","duration":"170.249183ms","start":"2026-08-10T22:55:43.888479Z","end":"2026-08-10T22:55:44.058729Z","steps":["trace[697243604] 'process raft request' (duration: 129.280231ms)","trace[697243604] 'compare' (duration: 40.714635ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:55:44.059074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.054821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:55:44.059128Z","caller":"traceutil/trace.go:171","msg":"trace[1610487365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14738; }","duration":"117.166404ms","start":"2026-08-10T22:55:43.941948Z","end":"2026-08-10T22:55:44.059115Z","steps":["trace[1610487365] 'agreement among raft nodes before linearized reading' (duration: 116.918277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:44.059168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.745082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-08-10T22:55:44.059249Z","caller":"traceutil/trace.go:171","msg":"trace[1911730250] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:14738; }","duration":"111.870896ms","start":"2026-08-10T22:55:43.947359Z","end":"2026-08-10T22:55:44.05923Z","steps":["trace[1911730250] 'agreement among raft nodes before linearized reading' (duration: 111.63687ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:44.482304Z","caller":"traceutil/trace.go:171","msg":"trace[2080723933] transaction","detail":"{read_only:false; response_revision:14739; number_of_response:1; }","duration":"418.210019ms","start":"2026-08-10T22:55:44.064055Z","end":"2026-08-10T22:55:44.482265Z","steps":["trace[2080723933] 'process raft request' (duration: 324.045686ms)","trace[2080723933] 'compare' (duration: 93.969798ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T22:55:44.482358Z","caller":"traceutil/trace.go:171","msg":"trace[1004211905] transaction","detail":"{read_only:false; response_revision:14740; number_of_response:1; }","duration":"356.828803ms","start":"2026-08-10T22:55:44.125504Z","end":"2026-08-10T22:55:44.482333Z","steps":["trace[1004211905] 'process raft request' (duration: 356.762411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:44.482453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:44.06403Z","time spent":"418.345322ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T22:55:44.482505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:44.125468Z","time spent":"356.961706ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-10T22:55:44.482573Z","caller":"traceutil/trace.go:171","msg":"trace[415152506] linearizableReadLoop","detail":"{readStateIndex:15256; appliedIndex:15255; }","duration":"405.826534ms","start":"2026-08-10T22:55:44.076718Z","end":"2026-08-10T22:55:44.482544Z","steps":["trace[415152506] 'read index received' (duration: 311.395564ms)","trace[415152506] 'applied index is now lower than readState.Index' (duration: 94.42864ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:55:44.482794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.871547ms","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:7"} {"level":"info","ts":"2026-08-10T22:55:44.482853Z","caller":"traceutil/trace.go:171","msg":"trace[271473798] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:14740; }","duration":"194.97933ms","start":"2026-08-10T22:55:44.28786Z","end":"2026-08-10T22:55:44.482839Z","steps":["trace[271473798] 'agreement among raft nodes before linearized reading' (duration: 194.874617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:44.482861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.134162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/placement\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-10T22:55:44.482902Z","caller":"traceutil/trace.go:171","msg":"trace[12631094] range","detail":"{range_begin:/registry/ingress/openstack/placement; range_end:; response_count:1; response_revision:14740; }","duration":"406.229124ms","start":"2026-08-10T22:55:44.07666Z","end":"2026-08-10T22:55:44.482889Z","steps":["trace[12631094] 'agreement among raft nodes before linearized reading' (duration: 406.040539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:55:44.48294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T22:55:44.076639Z","time spent":"406.292936ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":721,"request content":"key:\"/registry/ingress/openstack/placement\" "} {"level":"warn","ts":"2026-08-10T22:55:44.483141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.320929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3f81be64-e10e-4960-8eac-9f013e982590\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-08-10T22:55:44.483251Z","caller":"traceutil/trace.go:171","msg":"trace[1595615087] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3f81be64-e10e-4960-8eac-9f013e982590; range_end:; response_count:1; response_revision:14740; }","duration":"187.489784ms","start":"2026-08-10T22:55:44.295742Z","end":"2026-08-10T22:55:44.483231Z","steps":["trace[1595615087] 'agreement among raft nodes before linearized reading' (duration: 187.090884ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:55:52.73565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12689} {"level":"info","ts":"2026-08-10T22:55:52.809089Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12689,"took":"70.976796ms","hash":4197188363,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":28659712,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-10T22:55:52.809163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4197188363,"revision":12689,"compact-revision":10495} {"level":"info","ts":"2026-08-10T22:57:09.075199Z","caller":"traceutil/trace.go:171","msg":"trace[463420548] linearizableReadLoop","detail":"{readStateIndex:16303; appliedIndex:16302; }","duration":"114.534736ms","start":"2026-08-10T22:57:08.96062Z","end":"2026-08-10T22:57:09.075155Z","steps":["trace[463420548] 'read index received' (duration: 114.30178ms)","trace[463420548] 'applied index is now lower than readState.Index' (duration: 231.996µs)"],"step_count":2} {"level":"warn","ts":"2026-08-10T22:57:09.075527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.898766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-10T22:57:09.075471Z","caller":"traceutil/trace.go:171","msg":"trace[2039260409] transaction","detail":"{read_only:false; response_revision:15754; number_of_response:1; }","duration":"119.507866ms","start":"2026-08-10T22:57:08.95589Z","end":"2026-08-10T22:57:09.075398Z","steps":["trace[2039260409] 'process raft request' (duration: 119.108816ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T22:57:09.07558Z","caller":"traceutil/trace.go:171","msg":"trace[1536710456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15754; }","duration":"115.024198ms","start":"2026-08-10T22:57:08.96054Z","end":"2026-08-10T22:57:09.075565Z","steps":["trace[1536710456] 'agreement among raft nodes before linearized reading' (duration: 114.787422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T22:57:10.128634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.967145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-10T22:57:10.128729Z","caller":"traceutil/trace.go:171","msg":"trace[286622875] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:15762; }","duration":"105.13664ms","start":"2026-08-10T22:57:10.023572Z","end":"2026-08-10T22:57:10.128708Z","steps":["trace[286622875] 'count revisions from in-memory index tree' (duration: 104.790061ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:00:52.741885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14926} {"level":"info","ts":"2026-08-10T23:00:52.834112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14926,"took":"89.370781ms","hash":1815631343,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:00:52.834162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1815631343,"revision":14926,"compact-revision":12689} {"level":"info","ts":"2026-08-10T23:03:10.694585Z","caller":"traceutil/trace.go:171","msg":"trace[1541978060] transaction","detail":"{read_only:false; response_revision:18413; number_of_response:1; }","duration":"214.309632ms","start":"2026-08-10T23:03:10.480236Z","end":"2026-08-10T23:03:10.694545Z","steps":["trace[1541978060] 'process raft request' (duration: 213.398888ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:10.995574Z","caller":"traceutil/trace.go:171","msg":"trace[1951726640] linearizableReadLoop","detail":"{readStateIndex:19049; appliedIndex:19048; }","duration":"294.574908ms","start":"2026-08-10T23:03:10.700976Z","end":"2026-08-10T23:03:10.995551Z","steps":["trace[1951726640] 'read index received' (duration: 247.583637ms)","trace[1951726640] 'applied index is now lower than readState.Index' (duration: 46.990261ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:03:10.99564Z","caller":"traceutil/trace.go:171","msg":"trace[271876620] transaction","detail":"{read_only:false; response_revision:18414; number_of_response:1; }","duration":"492.452972ms","start":"2026-08-10T23:03:10.503138Z","end":"2026-08-10T23:03:10.995591Z","steps":["trace[271876620] 'process raft request' (duration: 445.42716ms)","trace[271876620] 'compare' (duration: 46.52202ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:03:10.996039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.03123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:03:10.996132Z","caller":"traceutil/trace.go:171","msg":"trace[1362896307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18414; }","duration":"295.176664ms","start":"2026-08-10T23:03:10.700937Z","end":"2026-08-10T23:03:10.996113Z","steps":["trace[1362896307] 'agreement among raft nodes before linearized reading' (duration: 294.743993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:10.996055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:03:10.503111Z","time spent":"492.620847ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:03:19.42073Z","caller":"traceutil/trace.go:171","msg":"trace[568723351] transaction","detail":"{read_only:false; response_revision:18554; number_of_response:1; }","duration":"202.157777ms","start":"2026-08-10T23:03:19.218536Z","end":"2026-08-10T23:03:19.420694Z","steps":["trace[568723351] 'process raft request' (duration: 201.687034ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:03:19.420849Z","caller":"traceutil/trace.go:171","msg":"trace[1742880898] linearizableReadLoop","detail":"{readStateIndex:19192; appliedIndex:19190; }","duration":"147.981827ms","start":"2026-08-10T23:03:19.272841Z","end":"2026-08-10T23:03:19.420823Z","steps":["trace[1742880898] 'read index received' (duration: 147.284688ms)","trace[1742880898] 'applied index is now lower than readState.Index' (duration: 696.109µs)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:03:19.421028Z","caller":"traceutil/trace.go:171","msg":"trace[720539741] transaction","detail":"{read_only:false; response_revision:18555; number_of_response:1; }","duration":"181.439197ms","start":"2026-08-10T23:03:19.239558Z","end":"2026-08-10T23:03:19.420997Z","steps":["trace[720539741] 'process raft request' (duration: 180.935895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:19.42146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.597353ms","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-08-10T23:03:19.421538Z","caller":"traceutil/trace.go:171","msg":"trace[456006972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18555; }","duration":"148.725506ms","start":"2026-08-10T23:03:19.272793Z","end":"2026-08-10T23:03:19.421519Z","steps":["trace[456006972] 'agreement among raft nodes before linearized reading' (duration: 148.462179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:19.47929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.751698ms","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-08-10T23:03:19.479388Z","caller":"traceutil/trace.go:171","msg":"trace[1202906141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18557; }","duration":"103.866161ms","start":"2026-08-10T23:03:19.375497Z","end":"2026-08-10T23:03:19.479363Z","steps":["trace[1202906141] 'agreement among raft nodes before linearized reading' (duration: 103.646105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:03:19.479396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.622734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-407c3667-be10-4eab-b55f-ca1aaf9bab93\" ","response":"range_response_count:1 size:3639"} {"level":"info","ts":"2026-08-10T23:03:19.479482Z","caller":"traceutil/trace.go:171","msg":"trace[747960576] transaction","detail":"{read_only:false; response_revision:18556; number_of_response:1; }","duration":"110.038831ms","start":"2026-08-10T23:03:19.369406Z","end":"2026-08-10T23:03:19.479445Z","steps":["trace[747960576] 'process raft request' (duration: 83.193693ms)","trace[747960576] 'compare' (duration: 26.235902ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:03:19.479485Z","caller":"traceutil/trace.go:171","msg":"trace[303584506] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-407c3667-be10-4eab-b55f-ca1aaf9bab93; range_end:; response_count:1; response_revision:18557; }","duration":"108.83662ms","start":"2026-08-10T23:03:19.370635Z","end":"2026-08-10T23:03:19.479471Z","steps":["trace[303584506] 'agreement among raft nodes before linearized reading' (duration: 108.453449ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:04:37.523749Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-10T23:04:37.529934Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-10T23:04:37.530082Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-10T23:04:52.371276Z","caller":"traceutil/trace.go:171","msg":"trace[96105789] transaction","detail":"{read_only:false; response_revision:19445; number_of_response:1; }","duration":"106.645243ms","start":"2026-08-10T23:04:52.264596Z","end":"2026-08-10T23:04:52.371241Z","steps":["trace[96105789] 'process raft request' (duration: 106.397686ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:05:52.751422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17487} {"level":"info","ts":"2026-08-10T23:05:52.849752Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17487,"took":"94.851696ms","hash":1699975239,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":34652160,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-10T23:05:52.849804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1699975239,"revision":17487,"compact-revision":14926} {"level":"info","ts":"2026-08-10T23:09:15.172232Z","caller":"traceutil/trace.go:171","msg":"trace[1993645537] transaction","detail":"{read_only:false; response_revision:21262; number_of_response:1; }","duration":"119.900717ms","start":"2026-08-10T23:09:15.052305Z","end":"2026-08-10T23:09:15.172206Z","steps":["trace[1993645537] 'process raft request' (duration: 119.740723ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:20.552711Z","caller":"traceutil/trace.go:171","msg":"trace[117507395] transaction","detail":"{read_only:false; response_revision:21288; number_of_response:1; }","duration":"133.952173ms","start":"2026-08-10T23:09:20.418717Z","end":"2026-08-10T23:09:20.552669Z","steps":["trace[117507395] 'process raft request' (duration: 133.708176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:20.950688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.360101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:09:20.950799Z","caller":"traceutil/trace.go:171","msg":"trace[966090657] linearizableReadLoop","detail":"{readStateIndex:22018; appliedIndex:22017; }","duration":"249.190299ms","start":"2026-08-10T23:09:20.701592Z","end":"2026-08-10T23:09:20.950782Z","steps":["trace[966090657] 'read index received' (duration: 51.521019ms)","trace[966090657] 'applied index is now lower than readState.Index' (duration: 197.66807ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:20.950899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.305841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:20.950908Z","caller":"traceutil/trace.go:171","msg":"trace[602482552] transaction","detail":"{read_only:false; response_revision:21289; number_of_response:1; }","duration":"349.182589ms","start":"2026-08-10T23:09:20.601648Z","end":"2026-08-10T23:09:20.950831Z","steps":["trace[602482552] 'process raft request' (duration: 151.448898ms)","trace[602482552] 'compare' (duration: 196.834828ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:09:20.951039Z","caller":"traceutil/trace.go:171","msg":"trace[511374665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21289; }","duration":"249.452955ms","start":"2026-08-10T23:09:20.70152Z","end":"2026-08-10T23:09:20.950973Z","steps":["trace[511374665] 'agreement among raft nodes before linearized reading' (duration: 249.311141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:20.95127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:20.601621Z","time spent":"349.453315ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:09:21.045871Z","caller":"traceutil/trace.go:171","msg":"trace[1435295890] transaction","detail":"{read_only:false; response_revision:21290; number_of_response:1; }","duration":"296.244932ms","start":"2026-08-10T23:09:20.749592Z","end":"2026-08-10T23:09:21.045837Z","steps":["trace[1435295890] 'process raft request' (duration: 296.048997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.326373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.181591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:09:21.32692Z","caller":"traceutil/trace.go:171","msg":"trace[1180434255] transaction","detail":"{read_only:false; response_revision:21291; number_of_response:1; }","duration":"493.88177ms","start":"2026-08-10T23:09:20.832786Z","end":"2026-08-10T23:09:21.326668Z","steps":["trace[1180434255] 'process raft request' (duration: 312.247858ms)","trace[1180434255] 'compare' (duration: 180.964875ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:21.327192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:20.832747Z","time spent":"494.2981ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:09:21.534613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.088739ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-10T23:09:21.534718Z","caller":"traceutil/trace.go:171","msg":"trace[899627347] linearizableReadLoop","detail":"{readStateIndex:22021; appliedIndex:22018; }","duration":"583.867459ms","start":"2026-08-10T23:09:20.950832Z","end":"2026-08-10T23:09:21.534699Z","steps":["trace[899627347] 'read index received' (duration: 94.846596ms)","trace[899627347] 'applied index is now lower than readState.Index' (duration: 489.019363ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:09:21.534861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.308709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:09:21.534855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.636471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:21.534894Z","caller":"traceutil/trace.go:171","msg":"trace[864322303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21291; }","duration":"582.382651ms","start":"2026-08-10T23:09:20.952502Z","end":"2026-08-10T23:09:21.534885Z","steps":["trace[864322303] 'agreement among raft nodes before linearized reading' (duration: 582.297608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.534934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:20.952487Z","time spent":"582.439713ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-10T23:09:21.53494Z","caller":"traceutil/trace.go:171","msg":"trace[965162871] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:21291; }","duration":"711.789064ms","start":"2026-08-10T23:09:20.823124Z","end":"2026-08-10T23:09:21.534913Z","steps":["trace[965162871] 'agreement among raft nodes before linearized reading' (duration: 711.63785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.534985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:20.823092Z","time spent":"711.883117ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-10T23:09:21.535034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.930956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-08-10T23:09:21.535147Z","caller":"traceutil/trace.go:171","msg":"trace[1086593752] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:21291; }","duration":"235.137994ms","start":"2026-08-10T23:09:21.299981Z","end":"2026-08-10T23:09:21.535119Z","steps":["trace[1086593752] 'agreement among raft nodes before linearized reading' (duration: 234.839136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.535302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.153145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-10T23:09:21.535355Z","caller":"traceutil/trace.go:171","msg":"trace[1464706025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21291; }","duration":"332.252747ms","start":"2026-08-10T23:09:21.203089Z","end":"2026-08-10T23:09:21.535341Z","steps":["trace[1464706025] 'agreement among raft nodes before linearized reading' (duration: 332.080653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.535391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:21.203062Z","time spent":"332.32247ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-10T23:09:21.535431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.745005ms","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-08-10T23:09:21.535532Z","caller":"traceutil/trace.go:171","msg":"trace[889565360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21291; }","duration":"206.875368ms","start":"2026-08-10T23:09:21.328625Z","end":"2026-08-10T23:09:21.5355Z","steps":["trace[889565360] 'agreement among raft nodes before linearized reading' (duration: 206.676002ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:21.724598Z","caller":"traceutil/trace.go:171","msg":"trace[1592581883] transaction","detail":"{read_only:false; response_revision:21292; number_of_response:1; }","duration":"184.916698ms","start":"2026-08-10T23:09:21.539654Z","end":"2026-08-10T23:09:21.724571Z","steps":["trace[1592581883] 'process raft request' (duration: 184.684922ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:21.930914Z","caller":"traceutil/trace.go:171","msg":"trace[2024978981] transaction","detail":"{read_only:false; response_revision:21293; number_of_response:1; }","duration":"391.012557ms","start":"2026-08-10T23:09:21.539868Z","end":"2026-08-10T23:09:21.93088Z","steps":["trace[2024978981] 'process raft request' (duration: 390.75568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.931071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:21.53986Z","time spent":"391.127839ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:09:21.931232Z","caller":"traceutil/trace.go:171","msg":"trace[1170597488] transaction","detail":"{read_only:false; response_revision:21294; number_of_response:1; }","duration":"391.144049ms","start":"2026-08-10T23:09:21.54003Z","end":"2026-08-10T23:09:21.931174Z","steps":["trace[1170597488] 'process raft request' (duration: 390.78281ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:09:21.931426Z","caller":"traceutil/trace.go:171","msg":"trace[743549931] transaction","detail":"{read_only:false; response_revision:21295; number_of_response:1; }","duration":"391.303674ms","start":"2026-08-10T23:09:21.540061Z","end":"2026-08-10T23:09:21.931364Z","steps":["trace[743549931] 'process raft request' (duration: 391.014697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.931456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:21.539993Z","time spent":"391.353114ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-10T23:09:21.931535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:21.540041Z","time spent":"391.442367ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:09:21.931538Z","caller":"traceutil/trace.go:171","msg":"trace[118001756] transaction","detail":"{read_only:false; response_revision:21296; number_of_response:1; }","duration":"391.339414ms","start":"2026-08-10T23:09:21.540154Z","end":"2026-08-10T23:09:21.931493Z","steps":["trace[118001756] 'process raft request' (duration: 391.112158ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:21.931792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:09:21.540137Z","time spent":"391.498549ms","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:09:22.916361Z","caller":"traceutil/trace.go:171","msg":"trace[708325168] transaction","detail":"{read_only:false; response_revision:21298; number_of_response:1; }","duration":"103.678486ms","start":"2026-08-10T23:09:22.812657Z","end":"2026-08-10T23:09:22.916335Z","steps":["trace[708325168] 'process raft request' (duration: 102.625409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:09:22.916355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.330514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:09:22.916621Z","caller":"traceutil/trace.go:171","msg":"trace[917789684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21297; }","duration":"216.646454ms","start":"2026-08-10T23:09:22.699966Z","end":"2026-08-10T23:09:22.916612Z","steps":["trace[917789684] 'range keys from in-memory index tree' (duration: 216.14027ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:10:52.758311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19889} {"level":"info","ts":"2026-08-10T23:10:52.878561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19889,"took":"116.794597ms","hash":3054020094,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":30457856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-10T23:10:52.878643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3054020094,"revision":19889,"compact-revision":17487} {"level":"warn","ts":"2026-08-10T23:13:32.172028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.634372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-10T23:13:32.172222Z","caller":"traceutil/trace.go:171","msg":"trace[682673438] transaction","detail":"{read_only:false; response_revision:23833; number_of_response:1; }","duration":"260.237131ms","start":"2026-08-10T23:13:31.911946Z","end":"2026-08-10T23:13:32.172183Z","steps":["trace[682673438] 'process raft request' (duration: 76.333382ms)","trace[682673438] 'compare' (duration: 183.217032ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:13:32.17228Z","caller":"traceutil/trace.go:171","msg":"trace[800221745] transaction","detail":"{read_only:false; response_revision:23834; number_of_response:1; }","duration":"184.999329ms","start":"2026-08-10T23:13:31.987256Z","end":"2026-08-10T23:13:32.172255Z","steps":["trace[800221745] 'process raft request' (duration: 184.915496ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:32.1724Z","caller":"traceutil/trace.go:171","msg":"trace[2119373296] linearizableReadLoop","detail":"{readStateIndex:24639; appliedIndex:24638; }","duration":"236.654878ms","start":"2026-08-10T23:13:31.935689Z","end":"2026-08-10T23:13:32.172344Z","steps":["trace[2119373296] 'read index received' (duration: 52.543832ms)","trace[2119373296] 'applied index is now lower than readState.Index' (duration: 184.104255ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:13:32.172515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.811891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:13:32.172554Z","caller":"traceutil/trace.go:171","msg":"trace[103605757] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:23834; }","duration":"236.935905ms","start":"2026-08-10T23:13:31.935606Z","end":"2026-08-10T23:13:32.172542Z","steps":["trace[103605757] 'agreement among raft nodes before linearized reading' (duration: 236.849002ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:32.553309Z","caller":"traceutil/trace.go:171","msg":"trace[510419465] transaction","detail":"{read_only:false; response_revision:23835; number_of_response:1; }","duration":"310.227628ms","start":"2026-08-10T23:13:32.243039Z","end":"2026-08-10T23:13:32.553267Z","steps":["trace[510419465] 'process raft request' (duration: 309.831737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:13:32.553574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-10T23:13:32.243009Z","time spent":"310.423532ms","remote":"127.0.0.1:43024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":44837,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-10T23:13:32.556556Z","caller":"traceutil/trace.go:171","msg":"trace[2113115367] transaction","detail":"{read_only:false; response_revision:23836; number_of_response:1; }","duration":"276.786134ms","start":"2026-08-10T23:13:32.279751Z","end":"2026-08-10T23:13:32.556537Z","steps":["trace[2113115367] 'process raft request' (duration: 274.049393ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:32.556742Z","caller":"traceutil/trace.go:171","msg":"trace[1518238312] linearizableReadLoop","detail":"{readStateIndex:24642; appliedIndex:24640; }","duration":"251.079203ms","start":"2026-08-10T23:13:32.305631Z","end":"2026-08-10T23:13:32.55671Z","steps":["trace[1518238312] 'read index received' (duration: 247.254673ms)","trace[1518238312] 'applied index is now lower than readState.Index' (duration: 3.82304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:13:32.55701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.882055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager\" ","response":"range_response_count:1 size:5167"} {"level":"warn","ts":"2026-08-10T23:13:32.557008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.36377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-08-10T23:13:32.557052Z","caller":"traceutil/trace.go:171","msg":"trace[956868611] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager; range_end:; response_count:1; response_revision:23837; }","duration":"161.978538ms","start":"2026-08-10T23:13:32.395063Z","end":"2026-08-10T23:13:32.557041Z","steps":["trace[956868611] 'agreement among raft nodes before linearized reading' (duration: 161.795763ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:13:32.557087Z","caller":"traceutil/trace.go:171","msg":"trace[451257313] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:23837; }","duration":"251.475063ms","start":"2026-08-10T23:13:32.305577Z","end":"2026-08-10T23:13:32.557052Z","steps":["trace[451257313] 'agreement among raft nodes before linearized reading' (duration: 251.265818ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:15:52.765553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22114} {"level":"info","ts":"2026-08-10T23:15:52.876636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22114,"took":"106.717995ms","hash":25227929,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":44544000,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-10T23:15:52.876751Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":25227929,"revision":22114,"compact-revision":19889} {"level":"info","ts":"2026-08-10T23:18:10.970771Z","caller":"traceutil/trace.go:171","msg":"trace[124448372] linearizableReadLoop","detail":"{readStateIndex:27091; appliedIndex:27090; }","duration":"156.983387ms","start":"2026-08-10T23:18:10.813756Z","end":"2026-08-10T23:18:10.970739Z","steps":["trace[124448372] 'read index received' (duration: 155.770236ms)","trace[124448372] 'applied index is now lower than readState.Index' (duration: 1.207141ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:18:10.970852Z","caller":"traceutil/trace.go:171","msg":"trace[534453572] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"161.214187ms","start":"2026-08-10T23:18:10.809595Z","end":"2026-08-10T23:18:10.970809Z","steps":["trace[534453572] 'process raft request' (duration: 160.848428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:18:10.971201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.418499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-10T23:18:10.971335Z","caller":"traceutil/trace.go:171","msg":"trace[1642071891] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26223; }","duration":"157.553112ms","start":"2026-08-10T23:18:10.813728Z","end":"2026-08-10T23:18:10.971281Z","steps":["trace[1642071891] 'agreement among raft nodes before linearized reading' (duration: 157.307826ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:18:10.971669Z","caller":"traceutil/trace.go:171","msg":"trace[305514531] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26223; }","duration":"100.0044ms","start":"2026-08-10T23:18:10.871631Z","end":"2026-08-10T23:18:10.971635Z","steps":["trace[305514531] 'agreement among raft nodes before linearized reading' (duration: 99.98922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:18:10.971832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.122864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:18:10.971812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.540977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:18:10.97187Z","caller":"traceutil/trace.go:171","msg":"trace[59054220] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26223; }","duration":"100.174255ms","start":"2026-08-10T23:18:10.871686Z","end":"2026-08-10T23:18:10.97186Z","steps":["trace[59054220] 'agreement among raft nodes before linearized reading' (duration: 100.108463ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:18:10.9719Z","caller":"traceutil/trace.go:171","msg":"trace[662473524] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26223; }","duration":"102.66788ms","start":"2026-08-10T23:18:10.869217Z","end":"2026-08-10T23:18:10.971885Z","steps":["trace[662473524] 'agreement among raft nodes before linearized reading' (duration: 102.486075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:18:10.97194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.617038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-10T23:18:10.971961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.879344ms","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-08-10T23:18:10.971998Z","caller":"traceutil/trace.go:171","msg":"trace[1720980625] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26223; }","duration":"102.729862ms","start":"2026-08-10T23:18:10.869248Z","end":"2026-08-10T23:18:10.971978Z","steps":["trace[1720980625] 'agreement among raft nodes before linearized reading' (duration: 102.63298ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:18:10.972057Z","caller":"traceutil/trace.go:171","msg":"trace[1239606805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26223; }","duration":"119.043718ms","start":"2026-08-10T23:18:10.852991Z","end":"2026-08-10T23:18:10.972035Z","steps":["trace[1239606805] 'agreement among raft nodes before linearized reading' (duration: 118.7337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:18:10.972541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.064947ms","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-08-10T23:18:10.972614Z","caller":"traceutil/trace.go:171","msg":"trace[1333085468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26223; }","duration":"155.210051ms","start":"2026-08-10T23:18:10.817383Z","end":"2026-08-10T23:18:10.972593Z","steps":["trace[1333085468] 'agreement among raft nodes before linearized reading' (duration: 153.786523ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:18:11.111406Z","caller":"traceutil/trace.go:171","msg":"trace[1551887614] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"132.424536ms","start":"2026-08-10T23:18:10.978913Z","end":"2026-08-10T23:18:11.111337Z","steps":["trace[1551887614] 'process raft request' (duration: 132.320993ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:18:11.111474Z","caller":"traceutil/trace.go:171","msg":"trace[1999196515] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"134.067868ms","start":"2026-08-10T23:18:10.977355Z","end":"2026-08-10T23:18:11.111423Z","steps":["trace[1999196515] 'process raft request' (duration: 96.250321ms)","trace[1999196515] 'compare' (duration: 37.438467ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:18:11.111589Z","caller":"traceutil/trace.go:171","msg":"trace[1572456086] linearizableReadLoop","detail":"{readStateIndex:27093; appliedIndex:27091; }","duration":"123.490634ms","start":"2026-08-10T23:18:10.988061Z","end":"2026-08-10T23:18:11.111551Z","steps":["trace[1572456086] 'read index received' (duration: 85.81194ms)","trace[1572456086] 'applied index is now lower than readState.Index' (duration: 37.675834ms)"],"step_count":2} {"level":"info","ts":"2026-08-10T23:18:11.111718Z","caller":"traceutil/trace.go:171","msg":"trace[1287971095] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"114.069968ms","start":"2026-08-10T23:18:10.997636Z","end":"2026-08-10T23:18:11.111706Z","steps":["trace[1287971095] 'process raft request' (duration: 113.654126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:18:11.111762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.662948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:18:11.111806Z","caller":"traceutil/trace.go:171","msg":"trace[706637753] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:26226; }","duration":"123.764261ms","start":"2026-08-10T23:18:10.988032Z","end":"2026-08-10T23:18:11.111796Z","steps":["trace[706637753] 'agreement among raft nodes before linearized reading' (duration: 123.645198ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:18:21.361991Z","caller":"traceutil/trace.go:171","msg":"trace[2136872103] linearizableReadLoop","detail":"{readStateIndex:27169; appliedIndex:27168; }","duration":"143.866646ms","start":"2026-08-10T23:18:21.218097Z","end":"2026-08-10T23:18:21.361963Z","steps":["trace[2136872103] 'read index received' (duration: 65.735247ms)","trace[2136872103] 'applied index is now lower than readState.Index' (duration: 78.130039ms)"],"step_count":2} {"level":"warn","ts":"2026-08-10T23:18:21.362306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.291332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-10T23:18:21.362401Z","caller":"traceutil/trace.go:171","msg":"trace[222667378] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26298; }","duration":"140.425435ms","start":"2026-08-10T23:18:21.221952Z","end":"2026-08-10T23:18:21.362377Z","steps":["trace[222667378] 'agreement among raft nodes before linearized reading' (duration: 140.264381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-10T23:18:21.362422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.630868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-10T23:18:21.362483Z","caller":"traceutil/trace.go:171","msg":"trace[213305934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26298; }","duration":"169.72735ms","start":"2026-08-10T23:18:21.192739Z","end":"2026-08-10T23:18:21.362466Z","steps":["trace[213305934] 'agreement among raft nodes before linearized reading' (duration: 169.383141ms)"],"step_count":1} {"level":"info","ts":"2026-08-10T23:20:47.50768Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006e95.wal"} {"level":"info","ts":"2026-08-10T23:20:52.775137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25210} {"level":"info","ts":"2026-08-10T23:20:52.919334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25210,"took":"140.475467ms","hash":4272393504,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":30883840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-10T23:20:52.91944Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4272393504,"revision":25210,"compact-revision":22114}