{"level":"warn","ts":"2026-09-01T13:23:34.619519Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T13:23:34.619698Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-01T13:23:34.619864Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T13:23:34.619899Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-09-01T13:23:34.619952Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T13:23:34.621714Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-09-01T13:23:34.621952Z","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.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-01T13:23:34.63262Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.133533ms"} {"level":"info","ts":"2026-09-01T13:23:34.64618Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-09-01T13:23:34.646347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T13:23:34.646386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-09-01T13:23:34.646447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T13:23:34.646469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-09-01T13:23:34.646546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-09-01T13:23:34.654667Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T13:23:34.660557Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T13:23:34.66234Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-01T13:23:34.665667Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T13:23:34.666014Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T13:23:34.666077Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T13:23:34.666378Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T13:23:34.666434Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-01T13:23:34.66673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-09-01T13:23:34.666905Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-09-01T13:23:34.669488Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-01T13:23:34.669631Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-09-01T13:23:34.669739Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-09-01T13:23:34.670041Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T13:23:34.670128Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T13:23:35.346852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T13:23:35.347007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T13:23:35.347078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-09-01T13:23:35.347153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-09-01T13:23:35.347177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-09-01T13:23:35.34726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-09-01T13:23:35.347279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-09-01T13:23:35.34897Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:35.350347Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T13:23:35.350364Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T13:23:35.350441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T13:23:35.350794Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T13:23:35.350941Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T13:23:35.351083Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:35.351277Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:35.351331Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T13:23:35.352998Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T13:23:35.354387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"warn","ts":"2026-09-01T13:24:14.865154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.890857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:24:14.865371Z","caller":"traceutil/trace.go:171","msg":"trace[639849558] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"384.43985ms","start":"2026-09-01T13:24:14.480892Z","end":"2026-09-01T13:24:14.865332Z","steps":["trace[639849558] 'process raft request' (duration: 209.722142ms)","trace[639849558] 'compare' (duration: 173.642619ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:24:14.865542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:14.480865Z","time spent":"384.589744ms","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:24:14.865585Z","caller":"traceutil/trace.go:171","msg":"trace[631727296] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"384.544262ms","start":"2026-09-01T13:24:14.480994Z","end":"2026-09-01T13:24:14.865538Z","steps":["trace[631727296] 'process raft request' (duration: 384.293135ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.865869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:14.480989Z","time spent":"384.695166ms","remote":"127.0.0.1:54310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T13:24:14.86634Z","caller":"traceutil/trace.go:171","msg":"trace[356173939] transaction","detail":"{read_only:false; number_of_response:1; response_revision:539; }","duration":"371.720007ms","start":"2026-09-01T13:24:14.49459Z","end":"2026-09-01T13:24:14.86631Z","steps":["trace[356173939] 'process raft request' (duration: 371.008058ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.866506Z","caller":"traceutil/trace.go:171","msg":"trace[347295507] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:561; }","duration":"247.979743ms","start":"2026-09-01T13:24:14.618503Z","end":"2026-09-01T13:24:14.866483Z","steps":["trace[347295507] 'read index received' (duration: 72.124884ms)","trace[347295507] 'applied index is now lower than readState.Index' (duration: 175.853279ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:24:14.866539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:14.494579Z","time spent":"371.852951ms","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97,"response count":0,"response size":150530,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:24:14.866791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.274631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.866859Z","caller":"traceutil/trace.go:171","msg":"trace[166172282] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:540; }","duration":"248.350803ms","start":"2026-09-01T13:24:14.618471Z","end":"2026-09-01T13:24:14.866821Z","steps":["trace[166172282] 'agreement among raft nodes before linearized reading' (duration: 248.205579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.866815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.179279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.866885Z","caller":"traceutil/trace.go:171","msg":"trace[1742197965] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"315.068322ms","start":"2026-09-01T13:24:14.551805Z","end":"2026-09-01T13:24:14.866873Z","steps":["trace[1742197965] 'process raft request' (duration: 314.551388ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.866936Z","caller":"traceutil/trace.go:171","msg":"trace[355571638] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:540; }","duration":"248.341093ms","start":"2026-09-01T13:24:14.618563Z","end":"2026-09-01T13:24:14.866904Z","steps":["trace[355571638] 'agreement among raft nodes before linearized reading' (duration: 248.151118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.866944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:14.551781Z","time spent":"315.134973ms","remote":"127.0.0.1:53850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T13:24:14.867038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.940497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:24:14.86707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.867758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:24:14.867911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.778309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:24:14.867971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.30984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:24:14.867949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.923582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.868362Z","caller":"traceutil/trace.go:171","msg":"trace[236852362] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:540; }","duration":"112.897982ms","start":"2026-09-01T13:24:14.755091Z","end":"2026-09-01T13:24:14.867989Z","steps":["trace[236852362] 'agreement among raft nodes before linearized reading' (duration: 112.667556ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.868367Z","caller":"traceutil/trace.go:171","msg":"trace[421338696] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:540; }","duration":"113.077517ms","start":"2026-09-01T13:24:14.754964Z","end":"2026-09-01T13:24:14.868041Z","steps":["trace[421338696] 'agreement among raft nodes before linearized reading' (duration: 112.906392ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.868993Z","caller":"traceutil/trace.go:171","msg":"trace[742402007] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:540; }","duration":"134.395132ms","start":"2026-09-01T13:24:14.733624Z","end":"2026-09-01T13:24:14.868019Z","steps":["trace[742402007] 'agreement among raft nodes before linearized reading' (duration: 134.045292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.869765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.321667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.869926Z","caller":"traceutil/trace.go:171","msg":"trace[1242767298] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:540; }","duration":"148.42826ms","start":"2026-09-01T13:24:14.721397Z","end":"2026-09-01T13:24:14.869825Z","steps":["trace[1242767298] 'agreement among raft nodes before linearized reading' (duration: 148.296827ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.869952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.480839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:24:14.869979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.420809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.869999Z","caller":"traceutil/trace.go:171","msg":"trace[1260974845] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:540; }","duration":"136.629973ms","start":"2026-09-01T13:24:14.733352Z","end":"2026-09-01T13:24:14.869982Z","steps":["trace[1260974845] 'agreement among raft nodes before linearized reading' (duration: 136.53417ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.870066Z","caller":"traceutil/trace.go:171","msg":"trace[1456589796] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:540; }","duration":"148.518192ms","start":"2026-09-01T13:24:14.721512Z","end":"2026-09-01T13:24:14.87003Z","steps":["trace[1456589796] 'agreement among raft nodes before linearized reading' (duration: 148.394138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.867948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.664351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.870197Z","caller":"traceutil/trace.go:171","msg":"trace[1467250185] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:540; }","duration":"250.982824ms","start":"2026-09-01T13:24:14.619187Z","end":"2026-09-01T13:24:14.87017Z","steps":["trace[1467250185] 'agreement among raft nodes before linearized reading' (duration: 248.61052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.870352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.9365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.87041Z","caller":"traceutil/trace.go:171","msg":"trace[1887904575] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:540; }","duration":"137.000381ms","start":"2026-09-01T13:24:14.733384Z","end":"2026-09-01T13:24:14.870384Z","steps":["trace[1887904575] 'agreement among raft nodes before linearized reading' (duration: 136.93046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:14.867667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.302319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:24:14.870638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.278979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:14.870894Z","caller":"traceutil/trace.go:171","msg":"trace[13433852] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:540; }","duration":"206.551906ms","start":"2026-09-01T13:24:14.664316Z","end":"2026-09-01T13:24:14.870868Z","steps":["trace[13433852] 'agreement among raft nodes before linearized reading' (duration: 203.73527ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.870753Z","caller":"traceutil/trace.go:171","msg":"trace[9261957] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:540; }","duration":"206.374831ms","start":"2026-09-01T13:24:14.66432Z","end":"2026-09-01T13:24:14.870695Z","steps":["trace[9261957] 'agreement among raft nodes before linearized reading' (duration: 202.724883ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.871962Z","caller":"traceutil/trace.go:171","msg":"trace[2020304551] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:540; }","duration":"112.013069ms","start":"2026-09-01T13:24:14.755059Z","end":"2026-09-01T13:24:14.867072Z","steps":["trace[2020304551] 'agreement among raft nodes before linearized reading' (duration: 111.926467ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:14.872202Z","caller":"traceutil/trace.go:171","msg":"trace[313085785] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:540; }","duration":"143.924039ms","start":"2026-09-01T13:24:14.723168Z","end":"2026-09-01T13:24:14.867092Z","steps":["trace[313085785] 'agreement among raft nodes before linearized reading' (duration: 143.855457ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:21.265179Z","caller":"traceutil/trace.go:171","msg":"trace[840410741] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"280.621093ms","start":"2026-09-01T13:24:20.984524Z","end":"2026-09-01T13:24:21.265146Z","steps":["trace[840410741] 'process raft request' (duration: 278.840705ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:21.32735Z","caller":"traceutil/trace.go:171","msg":"trace[2103273807] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"290.93526ms","start":"2026-09-01T13:24:21.036375Z","end":"2026-09-01T13:24:21.32731Z","steps":["trace[2103273807] 'process raft request' (duration: 290.750145ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:21.512154Z","caller":"traceutil/trace.go:171","msg":"trace[2019809683] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"166.159028ms","start":"2026-09-01T13:24:21.345963Z","end":"2026-09-01T13:24:21.512122Z","steps":["trace[2019809683] 'process raft request' (duration: 165.994113ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:21.523404Z","caller":"traceutil/trace.go:171","msg":"trace[477704436] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"175.692564ms","start":"2026-09-01T13:24:21.347691Z","end":"2026-09-01T13:24:21.523384Z","steps":["trace[477704436] 'process raft request' (duration: 175.567631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:22.148599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.722304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:24:22.149003Z","caller":"traceutil/trace.go:171","msg":"trace[699398315] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"585.145679ms","start":"2026-09-01T13:24:21.563816Z","end":"2026-09-01T13:24:22.148961Z","steps":["trace[699398315] 'process raft request' (duration: 355.956002ms)","trace[699398315] 'compare' (duration: 228.56569ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:24:22.1492Z","caller":"traceutil/trace.go:171","msg":"trace[2074464820] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"484.775293ms","start":"2026-09-01T13:24:21.664381Z","end":"2026-09-01T13:24:22.149156Z","steps":["trace[2074464820] 'read index received' (duration: 255.33932ms)","trace[2074464820] 'applied index is now lower than readState.Index' (duration: 229.429843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:24:22.149232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:21.563783Z","time spent":"585.315853ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:24:22.149384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.996749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lth8j\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-01T13:24:22.149482Z","caller":"traceutil/trace.go:171","msg":"trace[1314123047] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lth8j; range_end:; response_count:1; response_revision:601; }","duration":"485.125473ms","start":"2026-09-01T13:24:21.664329Z","end":"2026-09-01T13:24:22.149454Z","steps":["trace[1314123047] 'agreement among raft nodes before linearized reading' (duration: 484.905117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:22.149536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:21.66431Z","time spent":"485.213286ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-lth8j\" "} {"level":"warn","ts":"2026-09-01T13:24:22.150107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.721595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:24:22.150242Z","caller":"traceutil/trace.go:171","msg":"trace[876292921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"231.89485ms","start":"2026-09-01T13:24:21.918325Z","end":"2026-09-01T13:24:22.15022Z","steps":["trace[876292921] 'agreement among raft nodes before linearized reading' (duration: 231.706464ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:22.150128Z","caller":"traceutil/trace.go:171","msg":"trace[2067218060] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"219.436275ms","start":"2026-09-01T13:24:21.930673Z","end":"2026-09-01T13:24:22.15011Z","steps":["trace[2067218060] 'process raft request' (duration: 218.333454ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:38.192518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.893451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:24:38.192573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.280948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7gfwc\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-09-01T13:24:38.192627Z","caller":"traceutil/trace.go:171","msg":"trace[717881446] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7gfwc; range_end:; response_count:1; response_revision:763; }","duration":"426.36655ms","start":"2026-09-01T13:24:37.766243Z","end":"2026-09-01T13:24:38.192609Z","steps":["trace[717881446] 'range keys from in-memory index tree' (duration: 426.114343ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:24:38.192634Z","caller":"traceutil/trace.go:171","msg":"trace[2091424155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"274.076276ms","start":"2026-09-01T13:24:37.918523Z","end":"2026-09-01T13:24:38.192599Z","steps":["trace[2091424155] 'range keys from in-memory index tree' (duration: 273.785088ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:24:38.192663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:24:37.766223Z","time spent":"426.431412ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7gfwc\" "} {"level":"warn","ts":"2026-09-01T13:26:02.386742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.893657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bebe44d6-4789-4b91-8f1b-ece9a809be95\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T13:26:02.386949Z","caller":"traceutil/trace.go:171","msg":"trace[1658596098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bebe44d6-4789-4b91-8f1b-ece9a809be95; range_end:; response_count:1; response_revision:1862; }","duration":"163.177924ms","start":"2026-09-01T13:26:02.223734Z","end":"2026-09-01T13:26:02.386912Z","steps":["trace[1658596098] 'range keys from in-memory index tree' (duration: 162.513767ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:02.564214Z","caller":"traceutil/trace.go:171","msg":"trace[766221452] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"168.468088ms","start":"2026-09-01T13:26:02.395714Z","end":"2026-09-01T13:26:02.564182Z","steps":["trace[766221452] 'process raft request' (duration: 168.301613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:02.989591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.439691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:26:02.98969Z","caller":"traceutil/trace.go:171","msg":"trace[435702992] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1863; }","duration":"258.600274ms","start":"2026-09-01T13:26:02.731069Z","end":"2026-09-01T13:26:02.989669Z","steps":["trace[435702992] 'count revisions from in-memory index tree' (duration: 258.360788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:02.989902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.59778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T13:26:02.989999Z","caller":"traceutil/trace.go:171","msg":"trace[1760053296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1863; }","duration":"202.806536ms","start":"2026-09-01T13:26:02.78717Z","end":"2026-09-01T13:26:02.989977Z","steps":["trace[1760053296] 'range keys from in-memory index tree' (duration: 202.352163ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:13.836712Z","caller":"traceutil/trace.go:171","msg":"trace[1991091990] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"216.186509ms","start":"2026-09-01T13:26:13.620494Z","end":"2026-09-01T13:26:13.83668Z","steps":["trace[1991091990] 'process raft request' (duration: 216.028264ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:48.147709Z","caller":"traceutil/trace.go:171","msg":"trace[937384836] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"104.810748ms","start":"2026-09-01T13:26:48.042867Z","end":"2026-09-01T13:26:48.147678Z","steps":["trace[937384836] 'process raft request' (duration: 104.639313ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:53.573342Z","caller":"traceutil/trace.go:171","msg":"trace[1868311630] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"159.180137ms","start":"2026-09-01T13:26:53.414135Z","end":"2026-09-01T13:26:53.573315Z","steps":["trace[1868311630] 'process raft request' (duration: 158.982491ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:26:53.731435Z","caller":"traceutil/trace.go:171","msg":"trace[421305380] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"153.581271ms","start":"2026-09-01T13:26:53.577829Z","end":"2026-09-01T13:26:53.73141Z","steps":["trace[421305380] 'process raft request' (duration: 153.370846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:26:54.072884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.752254ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-01T13:26:54.073014Z","caller":"traceutil/trace.go:171","msg":"trace[945767547] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"154.872074ms","start":"2026-09-01T13:26:53.918119Z","end":"2026-09-01T13:26:54.072991Z","steps":["trace[945767547] 'read index received' (duration: 64.262µs)","trace[945767547] 'applied index is now lower than readState.Index' (duration: 154.805422ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:26:54.073143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.015418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:26:54.073179Z","caller":"traceutil/trace.go:171","msg":"trace[1291175981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"155.11405ms","start":"2026-09-01T13:26:53.918053Z","end":"2026-09-01T13:26:54.073168Z","steps":["trace[1291175981] 'agreement among raft nodes before linearized reading' (duration: 155.023778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:31:25.050606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.295905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:31:25.050709Z","caller":"traceutil/trace.go:171","msg":"trace[1219886527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4014; }","duration":"132.442739ms","start":"2026-09-01T13:31:24.918246Z","end":"2026-09-01T13:31:25.050688Z","steps":["trace[1219886527] 'range keys from in-memory index tree' (duration: 132.13483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:31:49.606719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.648792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:31:49.606785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.759497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-01T13:31:49.606818Z","caller":"traceutil/trace.go:171","msg":"trace[1412538600] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4154; }","duration":"269.799416ms","start":"2026-09-01T13:31:49.336999Z","end":"2026-09-01T13:31:49.606799Z","steps":["trace[1412538600] 'range keys from in-memory index tree' (duration: 269.533559ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:31:49.606881Z","caller":"traceutil/trace.go:171","msg":"trace[935750615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4154; }","duration":"304.914291ms","start":"2026-09-01T13:31:49.301941Z","end":"2026-09-01T13:31:49.606855Z","steps":["trace[935750615] 'range keys from in-memory index tree' (duration: 304.538411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:31:49.606944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:31:49.301916Z","time spent":"305.013924ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T13:31:49.607247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.71234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:31:49.607409Z","caller":"traceutil/trace.go:171","msg":"trace[2012627249] transaction","detail":"{read_only:false; response_revision:4155; number_of_response:1; }","duration":"259.979792ms","start":"2026-09-01T13:31:49.347403Z","end":"2026-09-01T13:31:49.607383Z","steps":["trace[2012627249] 'process raft request' (duration: 103.011825ms)","trace[2012627249] 'compare' (duration: 156.136065ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:31:49.7704Z","caller":"traceutil/trace.go:171","msg":"trace[821888894] linearizableReadLoop","detail":"{readStateIndex:4317; appliedIndex:4314; }","duration":"375.624275ms","start":"2026-09-01T13:31:49.394749Z","end":"2026-09-01T13:31:49.770374Z","steps":["trace[821888894] 'read index received' (duration: 55.605297ms)","trace[821888894] 'applied index is now lower than readState.Index' (duration: 320.018168ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:31:49.770421Z","caller":"traceutil/trace.go:171","msg":"trace[1181826436] transaction","detail":"{read_only:false; response_revision:4156; number_of_response:1; }","duration":"416.576228ms","start":"2026-09-01T13:31:49.353822Z","end":"2026-09-01T13:31:49.770399Z","steps":["trace[1181826436] 'process raft request' (duration: 408.639885ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:31:49.770494Z","caller":"traceutil/trace.go:171","msg":"trace[93831152] transaction","detail":"{read_only:false; response_revision:4157; number_of_response:1; }","duration":"413.421993ms","start":"2026-09-01T13:31:49.357064Z","end":"2026-09-01T13:31:49.770486Z","steps":["trace[93831152] 'process raft request' (duration: 413.214347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:31:49.770535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:31:49.353793Z","time spent":"416.67942ms","remote":"127.0.0.1:54032","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-09-01T13:31:49.770574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:31:49.357042Z","time spent":"413.471084ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:31:49.770702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.946443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-01T13:31:49.770753Z","caller":"traceutil/trace.go:171","msg":"trace[116240646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4157; }","duration":"376.028746ms","start":"2026-09-01T13:31:49.394713Z","end":"2026-09-01T13:31:49.770741Z","steps":["trace[116240646] 'agreement among raft nodes before linearized reading' (duration: 375.892582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:31:49.770782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:31:49.394699Z","time spent":"376.075817ms","remote":"127.0.0.1:54032","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-09-01T13:31:49.771013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.024197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:31:49.771011Z","caller":"traceutil/trace.go:171","msg":"trace[874877676] transaction","detail":"{read_only:false; response_revision:4158; number_of_response:1; }","duration":"159.778742ms","start":"2026-09-01T13:31:49.611205Z","end":"2026-09-01T13:31:49.770984Z","steps":["trace[874877676] 'process raft request' (duration: 159.69893ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:31:49.771053Z","caller":"traceutil/trace.go:171","msg":"trace[1803660869] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4158; }","duration":"174.097239ms","start":"2026-09-01T13:31:49.596944Z","end":"2026-09-01T13:31:49.771041Z","steps":["trace[1803660869] 'agreement among raft nodes before linearized reading' (duration: 174.019997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:31:49.771163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.132192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:31:49.771212Z","caller":"traceutil/trace.go:171","msg":"trace[2007919926] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4158; }","duration":"160.303987ms","start":"2026-09-01T13:31:49.610898Z","end":"2026-09-01T13:31:49.771202Z","steps":["trace[2007919926] 'agreement among raft nodes before linearized reading' (duration: 160.129202ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:25.979739Z","caller":"traceutil/trace.go:171","msg":"trace[1466701255] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"111.714741ms","start":"2026-09-01T13:33:25.867984Z","end":"2026-09-01T13:33:25.979699Z","steps":["trace[1466701255] 'process raft request' (duration: 111.549207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:25.979793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.173322ms","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-09-01T13:33:25.979871Z","caller":"traceutil/trace.go:171","msg":"trace[1933519175] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:5256; }","duration":"103.266224ms","start":"2026-09-01T13:33:25.876587Z","end":"2026-09-01T13:33:25.979853Z","steps":["trace[1933519175] 'agreement among raft nodes before linearized reading' (duration: 103.10426ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:26.092452Z","caller":"traceutil/trace.go:171","msg":"trace[87780392] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"105.523797ms","start":"2026-09-01T13:33:25.98689Z","end":"2026-09-01T13:33:26.092413Z","steps":["trace[87780392] 'process raft request' (duration: 97.633386ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:26.092573Z","caller":"traceutil/trace.go:171","msg":"trace[984247002] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"101.961857ms","start":"2026-09-01T13:33:25.990576Z","end":"2026-09-01T13:33:26.092538Z","steps":["trace[984247002] 'process raft request' (duration: 101.756701ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:26.092748Z","caller":"traceutil/trace.go:171","msg":"trace[571722230] linearizableReadLoop","detail":"{readStateIndex:5459; appliedIndex:5457; }","duration":"104.132529ms","start":"2026-09-01T13:33:25.988595Z","end":"2026-09-01T13:33:26.092727Z","steps":["trace[571722230] 'read index received' (duration: 15.626619ms)","trace[571722230] 'applied index is now lower than readState.Index' (duration: 88.50458ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:26.092832Z","caller":"traceutil/trace.go:171","msg":"trace[1856749974] transaction","detail":"{read_only:false; response_revision:5260; number_of_response:1; }","duration":"101.875936ms","start":"2026-09-01T13:33:25.990944Z","end":"2026-09-01T13:33:26.09282Z","steps":["trace[1856749974] 'process raft request' (duration: 101.515876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:26.092899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.275793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/loki\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:33:26.092942Z","caller":"traceutil/trace.go:171","msg":"trace[2022071916] range","detail":"{range_begin:/registry/configmaps/monitoring/loki; range_end:; response_count:0; response_revision:5260; }","duration":"104.352245ms","start":"2026-09-01T13:33:25.988579Z","end":"2026-09-01T13:33:26.092931Z","steps":["trace[2022071916] 'agreement among raft nodes before linearized reading' (duration: 104.211571ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:26.312929Z","caller":"traceutil/trace.go:171","msg":"trace[1771852625] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5263; }","duration":"116.32853ms","start":"2026-09-01T13:33:26.196557Z","end":"2026-09-01T13:33:26.312885Z","steps":["trace[1771852625] 'process raft request' (duration: 116.127605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:26.313696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.241625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-09-01T13:33:26.313828Z","caller":"traceutil/trace.go:171","msg":"trace[365643293] linearizableReadLoop","detail":"{readStateIndex:5465; appliedIndex:5463; }","duration":"102.362598ms","start":"2026-09-01T13:33:26.211443Z","end":"2026-09-01T13:33:26.313806Z","steps":["trace[365643293] 'read index received' (duration: 37.725516ms)","trace[365643293] 'applied index is now lower than readState.Index' (duration: 64.090697ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:26.313802Z","caller":"traceutil/trace.go:171","msg":"trace[635882862] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5265; }","duration":"102.40793ms","start":"2026-09-01T13:33:26.211364Z","end":"2026-09-01T13:33:26.313772Z","steps":["trace[635882862] 'agreement among raft nodes before linearized reading' (duration: 102.155873ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:27.899002Z","caller":"traceutil/trace.go:171","msg":"trace[1708010697] linearizableReadLoop","detail":"{readStateIndex:5569; appliedIndex:5568; }","duration":"427.020418ms","start":"2026-09-01T13:33:27.471947Z","end":"2026-09-01T13:33:27.898967Z","steps":["trace[1708010697] 'read index received' (duration: 426.817743ms)","trace[1708010697] 'applied index is now lower than readState.Index' (duration: 200.995µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:27.899062Z","caller":"traceutil/trace.go:171","msg":"trace[385182456] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"465.532458ms","start":"2026-09-01T13:33:27.433487Z","end":"2026-09-01T13:33:27.899019Z","steps":["trace[385182456] 'process raft request' (duration: 465.26856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:27.899236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:27.433454Z","time spent":"465.714603ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1689,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-01T13:33:27.899472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.485063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:33:27.899568Z","caller":"traceutil/trace.go:171","msg":"trace[584742979] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5365; }","duration":"427.606076ms","start":"2026-09-01T13:33:27.471941Z","end":"2026-09-01T13:33:27.899547Z","steps":["trace[584742979] 'agreement among raft nodes before linearized reading' (duration: 427.209195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:27.899723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:27.471901Z","time spent":"427.805652ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-09-01T13:33:27.899987Z","caller":"traceutil/trace.go:171","msg":"trace[324529628] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"206.685244ms","start":"2026-09-01T13:33:27.693272Z","end":"2026-09-01T13:33:27.899957Z","steps":["trace[324529628] 'process raft request' (duration: 206.520809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:27.900118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.956109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jp4fv\" ","response":"range_response_count:1 size:9784"} {"level":"warn","ts":"2026-09-01T13:33:27.900149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.465153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8dfc5f64-ecae-4240-8a78-51cb5f17fc7c\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-01T13:33:27.900186Z","caller":"traceutil/trace.go:171","msg":"trace[1494339855] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-jp4fv; range_end:; response_count:1; response_revision:5366; }","duration":"253.089363ms","start":"2026-09-01T13:33:27.647077Z","end":"2026-09-01T13:33:27.900166Z","steps":["trace[1494339855] 'agreement among raft nodes before linearized reading' (duration: 252.835396ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:27.900209Z","caller":"traceutil/trace.go:171","msg":"trace[1428202795] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8dfc5f64-ecae-4240-8a78-51cb5f17fc7c; range_end:; response_count:1; response_revision:5366; }","duration":"101.572156ms","start":"2026-09-01T13:33:27.798619Z","end":"2026-09-01T13:33:27.900191Z","steps":["trace[1428202795] 'agreement among raft nodes before linearized reading' (duration: 101.390112ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:27.900581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.428107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-jp4fv.18d135bb8a1d2c49\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-09-01T13:33:27.900692Z","caller":"traceutil/trace.go:171","msg":"trace[300917755] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-jp4fv.18d135bb8a1d2c49; range_end:; response_count:1; response_revision:5366; }","duration":"251.594872ms","start":"2026-09-01T13:33:27.649068Z","end":"2026-09-01T13:33:27.900663Z","steps":["trace[300917755] 'agreement among raft nodes before linearized reading' (duration: 251.056586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:31.022034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.519456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-01T13:33:31.022046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.261764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8dfc5f64-ecae-4240-8a78-51cb5f17fc7c\" ","response":"range_response_count:1 size:3903"} {"level":"info","ts":"2026-09-01T13:33:31.022114Z","caller":"traceutil/trace.go:171","msg":"trace[507123344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5420; }","duration":"222.633079ms","start":"2026-09-01T13:33:30.799463Z","end":"2026-09-01T13:33:31.022096Z","steps":["trace[507123344] 'range keys from in-memory index tree' (duration: 222.373571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:31.022129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.853114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T13:33:31.022139Z","caller":"traceutil/trace.go:171","msg":"trace[1087629296] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8dfc5f64-ecae-4240-8a78-51cb5f17fc7c; range_end:; response_count:1; response_revision:5420; }","duration":"103.398077ms","start":"2026-09-01T13:33:30.918716Z","end":"2026-09-01T13:33:31.022114Z","steps":["trace[1087629296] 'range keys from in-memory index tree' (duration: 103.086139ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:31.022209Z","caller":"traceutil/trace.go:171","msg":"trace[1097684297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5420; }","duration":"228.990528ms","start":"2026-09-01T13:33:30.7932Z","end":"2026-09-01T13:33:31.02219Z","steps":["trace[1097684297] 'range keys from in-memory index tree' (duration: 228.585456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:31.022275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.248014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:33:31.022328Z","caller":"traceutil/trace.go:171","msg":"trace[561876767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5420; }","duration":"103.321116ms","start":"2026-09-01T13:33:30.918995Z","end":"2026-09-01T13:33:31.022316Z","steps":["trace[561876767] 'range keys from in-memory index tree' (duration: 103.198903ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:35.387423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2937} {"level":"info","ts":"2026-09-01T13:33:35.474343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2937,"took":"85.368284ms","hash":1354027683,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":30420992,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T13:33:35.474431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1354027683,"revision":2937,"compact-revision":-1} {"level":"info","ts":"2026-09-01T13:33:44.222715Z","caller":"traceutil/trace.go:171","msg":"trace[380614353] transaction","detail":"{read_only:false; response_revision:5658; number_of_response:1; }","duration":"211.727354ms","start":"2026-09-01T13:33:44.010967Z","end":"2026-09-01T13:33:44.222695Z","steps":["trace[380614353] 'process raft request' (duration: 211.58683ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:44.224352Z","caller":"traceutil/trace.go:171","msg":"trace[1014925423] transaction","detail":"{read_only:false; response_revision:5659; number_of_response:1; }","duration":"212.167937ms","start":"2026-09-01T13:33:44.012151Z","end":"2026-09-01T13:33:44.224319Z","steps":["trace[1014925423] 'process raft request' (duration: 211.969022ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:52.914968Z","caller":"traceutil/trace.go:171","msg":"trace[783319673] transaction","detail":"{read_only:false; response_revision:5804; number_of_response:1; }","duration":"647.587689ms","start":"2026-09-01T13:33:52.267354Z","end":"2026-09-01T13:33:52.914941Z","steps":["trace[783319673] 'process raft request' (duration: 647.424305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.915135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.267332Z","time spent":"647.720194ms","remote":"127.0.0.1:54032","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-09-01T13:33:52.961174Z","caller":"traceutil/trace.go:171","msg":"trace[529135658] transaction","detail":"{read_only:false; response_revision:5805; number_of_response:1; }","duration":"633.520456ms","start":"2026-09-01T13:33:52.327625Z","end":"2026-09-01T13:33:52.961146Z","steps":["trace[529135658] 'process raft request' (duration: 633.227458ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:33:52.961412Z","caller":"traceutil/trace.go:171","msg":"trace[1928371921] transaction","detail":"{read_only:false; response_revision:5807; number_of_response:1; }","duration":"553.101613ms","start":"2026-09-01T13:33:52.408293Z","end":"2026-09-01T13:33:52.961395Z","steps":["trace[1928371921] 'process raft request' (duration: 552.781174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.961432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.327609Z","time spent":"633.654161ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:33:52.961173Z","caller":"traceutil/trace.go:171","msg":"trace[1355738831] transaction","detail":"{read_only:false; response_revision:5806; number_of_response:1; }","duration":"588.369241ms","start":"2026-09-01T13:33:52.372774Z","end":"2026-09-01T13:33:52.961143Z","steps":["trace[1355738831] 'process raft request' (duration: 588.240637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.961513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.408274Z","time spent":"553.184105ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:33:52.961596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.372755Z","time spent":"588.743502ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:33:52.961778Z","caller":"traceutil/trace.go:171","msg":"trace[1292759002] linearizableReadLoop","detail":"{readStateIndex:6024; appliedIndex:6019; }","duration":"440.456526ms","start":"2026-09-01T13:33:52.521201Z","end":"2026-09-01T13:33:52.961658Z","steps":["trace[1292759002] 'read index received' (duration: 393.577901ms)","trace[1292759002] 'applied index is now lower than readState.Index' (duration: 46.877385ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:33:52.961845Z","caller":"traceutil/trace.go:171","msg":"trace[1492744803] transaction","detail":"{read_only:false; response_revision:5808; number_of_response:1; }","duration":"550.291454ms","start":"2026-09-01T13:33:52.411537Z","end":"2026-09-01T13:33:52.961829Z","steps":["trace[1492744803] 'process raft request' (duration: 549.726859ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.961938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.411509Z","time spent":"550.381246ms","remote":"127.0.0.1:54032","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-09-01T13:33:52.962154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.942238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-01T13:33:52.962258Z","caller":"traceutil/trace.go:171","msg":"trace[89705050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5808; }","duration":"441.083482ms","start":"2026-09-01T13:33:52.521156Z","end":"2026-09-01T13:33:52.96224Z","steps":["trace[89705050] 'agreement among raft nodes before linearized reading' (duration: 440.807944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.962349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.521133Z","time spent":"441.199506ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T13:33:52.962431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.646965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-01T13:33:52.962264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.361077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pxbtk\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-01T13:33:52.962526Z","caller":"traceutil/trace.go:171","msg":"trace[1106595522] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:5808; }","duration":"306.8374ms","start":"2026-09-01T13:33:52.655663Z","end":"2026-09-01T13:33:52.962501Z","steps":["trace[1106595522] 'agreement among raft nodes before linearized reading' (duration: 306.607953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.96258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.655638Z","time spent":"306.928683ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-09-01T13:33:52.962571Z","caller":"traceutil/trace.go:171","msg":"trace[1232185559] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pxbtk; range_end:; response_count:1; response_revision:5808; }","duration":"270.691757ms","start":"2026-09-01T13:33:52.691846Z","end":"2026-09-01T13:33:52.962538Z","steps":["trace[1232185559] 'agreement among raft nodes before linearized reading' (duration: 270.256504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.962918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.463815ms","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-09-01T13:33:52.963036Z","caller":"traceutil/trace.go:171","msg":"trace[1747543649] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5808; }","duration":"222.682671ms","start":"2026-09-01T13:33:52.740329Z","end":"2026-09-01T13:33:52.963011Z","steps":["trace[1747543649] 'agreement among raft nodes before linearized reading' (duration: 221.718294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.962446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.102283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T13:33:52.963688Z","caller":"traceutil/trace.go:171","msg":"trace[1999381407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5808; }","duration":"168.337518ms","start":"2026-09-01T13:33:52.795284Z","end":"2026-09-01T13:33:52.963622Z","steps":["trace[1999381407] 'agreement among raft nodes before linearized reading' (duration: 166.903688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.963721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.355708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-01T13:33:52.963874Z","caller":"traceutil/trace.go:171","msg":"trace[635705612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5808; }","duration":"442.589406ms","start":"2026-09-01T13:33:52.521257Z","end":"2026-09-01T13:33:52.963846Z","steps":["trace[635705612] 'agreement among raft nodes before linearized reading' (duration: 440.798555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:33:52.963953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:33:52.521231Z","time spent":"442.705548ms","remote":"127.0.0.1:54032","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":"info","ts":"2026-09-01T13:38:35.396223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5527} {"level":"info","ts":"2026-09-01T13:38:35.480159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5527,"took":"82.053596ms","hash":3977979137,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":28770304,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-01T13:38:35.480243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3977979137,"revision":5527,"compact-revision":2937} {"level":"info","ts":"2026-09-01T13:40:12.56205Z","caller":"traceutil/trace.go:171","msg":"trace[213555788] transaction","detail":"{read_only:false; response_revision:8733; number_of_response:1; }","duration":"158.998146ms","start":"2026-09-01T13:40:12.402992Z","end":"2026-09-01T13:40:12.56199Z","steps":["trace[213555788] 'process raft request' (duration: 158.732279ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:12.852402Z","caller":"traceutil/trace.go:171","msg":"trace[568495535] transaction","detail":"{read_only:false; response_revision:8734; number_of_response:1; }","duration":"320.745255ms","start":"2026-09-01T13:40:12.531619Z","end":"2026-09-01T13:40:12.852364Z","steps":["trace[568495535] 'process raft request' (duration: 225.113117ms)","trace[568495535] 'compare' (duration: 95.213957ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:40:12.852527Z","caller":"traceutil/trace.go:171","msg":"trace[1553690845] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"319.396258ms","start":"2026-09-01T13:40:12.533121Z","end":"2026-09-01T13:40:12.852517Z","steps":["trace[1553690845] 'process raft request' (duration: 319.10845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:12.852647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:40:12.531577Z","time spent":"320.95281ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:40:12.852446Z","caller":"traceutil/trace.go:171","msg":"trace[498851738] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"256.971275ms","start":"2026-09-01T13:40:12.595403Z","end":"2026-09-01T13:40:12.852393Z","steps":["trace[498851738] 'process raft request' (duration: 256.920993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:12.852669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:40:12.533105Z","time spent":"319.45918ms","remote":"127.0.0.1:54032","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-09-01T13:40:23.418099Z","caller":"traceutil/trace.go:171","msg":"trace[1905191351] transaction","detail":"{read_only:false; response_revision:8791; number_of_response:1; }","duration":"138.737687ms","start":"2026-09-01T13:40:23.279339Z","end":"2026-09-01T13:40:23.418076Z","steps":["trace[1905191351] 'process raft request' (duration: 138.516211ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:23.540144Z","caller":"traceutil/trace.go:171","msg":"trace[896989398] linearizableReadLoop","detail":"{readStateIndex:9105; appliedIndex:9104; }","duration":"116.943795ms","start":"2026-09-01T13:40:23.423164Z","end":"2026-09-01T13:40:23.540108Z","steps":["trace[896989398] 'read index received' (duration: 47.127435ms)","trace[896989398] 'applied index is now lower than readState.Index' (duration: 69.81519ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:40:23.540295Z","caller":"traceutil/trace.go:171","msg":"trace[91768510] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"117.11648ms","start":"2026-09-01T13:40:23.423131Z","end":"2026-09-01T13:40:23.540247Z","steps":["trace[91768510] 'process raft request' (duration: 47.113345ms)","trace[91768510] 'compare' (duration: 69.690876ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:40:23.540367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.166285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:40:23.540437Z","caller":"traceutil/trace.go:171","msg":"trace[108092292] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8792; }","duration":"104.268418ms","start":"2026-09-01T13:40:23.436128Z","end":"2026-09-01T13:40:23.540397Z","steps":["trace[108092292] 'agreement among raft nodes before linearized reading' (duration: 104.156465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:40:23.540697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.5145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:222752"} {"level":"warn","ts":"2026-09-01T13:40:23.540709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.735583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T13:40:23.540782Z","caller":"traceutil/trace.go:171","msg":"trace[1475272144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8792; }","duration":"100.833455ms","start":"2026-09-01T13:40:23.439931Z","end":"2026-09-01T13:40:23.540764Z","steps":["trace[1475272144] 'agreement among raft nodes before linearized reading' (duration: 100.578639ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:23.540739Z","caller":"traceutil/trace.go:171","msg":"trace[1579860849] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8792; }","duration":"117.602063ms","start":"2026-09-01T13:40:23.423127Z","end":"2026-09-01T13:40:23.54073Z","steps":["trace[1579860849] 'agreement among raft nodes before linearized reading' (duration: 117.14513ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:24.739151Z","caller":"traceutil/trace.go:171","msg":"trace[846424176] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"110.34087ms","start":"2026-09-01T13:40:24.628773Z","end":"2026-09-01T13:40:24.739114Z","steps":["trace[846424176] 'process raft request' (duration: 110.082113ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:25.0151Z","caller":"traceutil/trace.go:171","msg":"trace[722722456] transaction","detail":"{read_only:false; response_revision:8798; number_of_response:1; }","duration":"107.130463ms","start":"2026-09-01T13:40:24.907938Z","end":"2026-09-01T13:40:25.015068Z","steps":["trace[722722456] 'process raft request' (duration: 106.917457ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:27.571884Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ad.wal"} {"level":"info","ts":"2026-09-01T13:40:55.13602Z","caller":"traceutil/trace.go:171","msg":"trace[566973868] transaction","detail":"{read_only:false; response_revision:9013; number_of_response:1; }","duration":"275.53888ms","start":"2026-09-01T13:40:54.860458Z","end":"2026-09-01T13:40:55.135997Z","steps":["trace[566973868] 'process raft request' (duration: 275.387976ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:40:55.137525Z","caller":"traceutil/trace.go:171","msg":"trace[657287205] linearizableReadLoop","detail":"{readStateIndex:9335; appliedIndex:9335; }","duration":"218.634975ms","start":"2026-09-01T13:40:54.918872Z","end":"2026-09-01T13:40:55.137506Z","steps":["trace[657287205] 'read index received' (duration: 218.627764ms)","trace[657287205] 'applied index is now lower than readState.Index' (duration: 5.74µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:40:55.137704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.776678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:40:55.137775Z","caller":"traceutil/trace.go:171","msg":"trace[1652073782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9013; }","duration":"218.881821ms","start":"2026-09-01T13:40:54.918857Z","end":"2026-09-01T13:40:55.137739Z","steps":["trace[1652073782] 'agreement among raft nodes before linearized reading' (duration: 218.715726ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:41:05.302463Z","caller":"traceutil/trace.go:171","msg":"trace[1990156882] transaction","detail":"{read_only:false; response_revision:9068; number_of_response:1; }","duration":"132.094389ms","start":"2026-09-01T13:41:05.170341Z","end":"2026-09-01T13:41:05.302436Z","steps":["trace[1990156882] 'process raft request' (duration: 131.885363ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:41:05.408215Z","caller":"traceutil/trace.go:171","msg":"trace[1597810902] transaction","detail":"{read_only:false; response_revision:9070; number_of_response:1; }","duration":"228.662141ms","start":"2026-09-01T13:41:05.179533Z","end":"2026-09-01T13:41:05.408195Z","steps":["trace[1597810902] 'process raft request' (duration: 228.535348ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:41:05.408206Z","caller":"traceutil/trace.go:171","msg":"trace[1745096737] transaction","detail":"{read_only:false; response_revision:9071; number_of_response:1; }","duration":"220.304459ms","start":"2026-09-01T13:41:05.187882Z","end":"2026-09-01T13:41:05.408186Z","steps":["trace[1745096737] 'process raft request' (duration: 220.245827ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:41:05.408197Z","caller":"traceutil/trace.go:171","msg":"trace[570436569] transaction","detail":"{read_only:false; response_revision:9069; number_of_response:1; }","duration":"232.562645ms","start":"2026-09-01T13:41:05.175594Z","end":"2026-09-01T13:41:05.408157Z","steps":["trace[570436569] 'process raft request' (duration: 223.574025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:41:05.895012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.301769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-01T13:41:05.895127Z","caller":"traceutil/trace.go:171","msg":"trace[1493864651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9072; }","duration":"104.469273ms","start":"2026-09-01T13:41:05.790635Z","end":"2026-09-01T13:41:05.895105Z","steps":["trace[1493864651] 'range keys from in-memory index tree' (duration: 104.137594ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:41:05.89547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.827699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-01T13:41:05.895518Z","caller":"traceutil/trace.go:171","msg":"trace[1803104242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9072; }","duration":"108.897301ms","start":"2026-09-01T13:41:05.786606Z","end":"2026-09-01T13:41:05.895503Z","steps":["trace[1803104242] 'range keys from in-memory index tree' (duration: 108.695496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:41:05.895522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.887761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T13:41:05.895603Z","caller":"traceutil/trace.go:171","msg":"trace[631796993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9072; }","duration":"109.014244ms","start":"2026-09-01T13:41:05.78657Z","end":"2026-09-01T13:41:05.895584Z","steps":["trace[631796993] 'range keys from in-memory index tree' (duration: 108.692235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:41:08.558341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.047792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:41:08.558455Z","caller":"traceutil/trace.go:171","msg":"trace[790820650] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:9094; }","duration":"122.220426ms","start":"2026-09-01T13:41:08.436215Z","end":"2026-09-01T13:41:08.558435Z","steps":["trace[790820650] 'count revisions from in-memory index tree' (duration: 121.95601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:41:08.558832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.669525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pm2vr\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-09-01T13:41:08.558861Z","caller":"traceutil/trace.go:171","msg":"trace[1816969588] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pm2vr; range_end:; response_count:1; response_revision:9094; }","duration":"132.742076ms","start":"2026-09-01T13:41:08.426112Z","end":"2026-09-01T13:41:08.558854Z","steps":["trace[1816969588] 'range keys from in-memory index tree' (duration: 132.577672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:41:08.558803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.446881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-pm2vr\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-09-01T13:41:08.558925Z","caller":"traceutil/trace.go:171","msg":"trace[969618557] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-pm2vr; range_end:; response_count:1; response_revision:9094; }","duration":"131.578756ms","start":"2026-09-01T13:41:08.427319Z","end":"2026-09-01T13:41:08.558898Z","steps":["trace[969618557] 'range keys from in-memory index tree' (duration: 131.400711ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:41:08.796408Z","caller":"traceutil/trace.go:171","msg":"trace[1796298601] transaction","detail":"{read_only:false; response_revision:9095; number_of_response:1; }","duration":"230.503821ms","start":"2026-09-01T13:41:08.565857Z","end":"2026-09-01T13:41:08.796361Z","steps":["trace[1796298601] 'process raft request' (duration: 230.391948ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:41:08.840631Z","caller":"traceutil/trace.go:171","msg":"trace[1362830889] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9096; }","duration":"274.513862ms","start":"2026-09-01T13:41:08.56609Z","end":"2026-09-01T13:41:08.840604Z","steps":["trace[1362830889] 'process raft request' (duration: 274.333108ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:42:08.176865Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T13:42:08.185534Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T13:42:08.185678Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-01T13:42:36.743757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.870521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T13:42:36.743948Z","caller":"traceutil/trace.go:171","msg":"trace[507337502] linearizableReadLoop","detail":"{readStateIndex:10292; appliedIndex:10290; }","duration":"198.822026ms","start":"2026-09-01T13:42:36.545111Z","end":"2026-09-01T13:42:36.743933Z","steps":["trace[507337502] 'read index received' (duration: 33.111µs)","trace[507337502] 'applied index is now lower than readState.Index' (duration: 198.788125ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:42:36.744071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.964201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pxbtk\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-01T13:42:36.744101Z","caller":"traceutil/trace.go:171","msg":"trace[185092434] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pxbtk; range_end:; response_count:1; response_revision:9941; }","duration":"199.017321ms","start":"2026-09-01T13:42:36.545075Z","end":"2026-09-01T13:42:36.744093Z","steps":["trace[185092434] 'agreement among raft nodes before linearized reading' (duration: 198.906638ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:42:36.744089Z","caller":"traceutil/trace.go:171","msg":"trace[398426504] transaction","detail":"{read_only:false; response_revision:9940; number_of_response:1; }","duration":"301.449389ms","start":"2026-09-01T13:42:36.442604Z","end":"2026-09-01T13:42:36.744053Z","steps":["trace[398426504] 'process raft request' (duration: 97.211239ms)","trace[398426504] 'compare' (duration: 203.751037ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:42:36.744249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:42:36.442587Z","time spent":"301.582494ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:42:36.744269Z","caller":"traceutil/trace.go:171","msg":"trace[2144561136] transaction","detail":"{read_only:false; response_revision:9941; number_of_response:1; }","duration":"299.132039ms","start":"2026-09-01T13:42:36.445126Z","end":"2026-09-01T13:42:36.744258Z","steps":["trace[2144561136] 'process raft request' (duration: 298.750979ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:43:35.403112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7949} {"level":"info","ts":"2026-09-01T13:43:35.493797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7949,"took":"88.035316ms","hash":1357470271,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":25624576,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T13:43:35.493885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1357470271,"revision":7949,"compact-revision":5527} {"level":"info","ts":"2026-09-01T13:47:13.71096Z","caller":"traceutil/trace.go:171","msg":"trace[105113159] transaction","detail":"{read_only:false; response_revision:11821; number_of_response:1; }","duration":"102.255575ms","start":"2026-09-01T13:47:13.608676Z","end":"2026-09-01T13:47:13.710932Z","steps":["trace[105113159] 'process raft request' (duration: 101.975617ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:16.660852Z","caller":"traceutil/trace.go:171","msg":"trace[875376128] transaction","detail":"{read_only:false; response_revision:12375; number_of_response:1; }","duration":"317.427446ms","start":"2026-09-01T13:48:16.343358Z","end":"2026-09-01T13:48:16.660786Z","steps":["trace[875376128] 'process raft request' (duration: 267.976059ms)","trace[875376128] 'compare' (duration: 49.074177ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:48:16.66128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:48:16.343333Z","time spent":"317.715624ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:48:17.235394Z","caller":"traceutil/trace.go:171","msg":"trace[1971183760] transaction","detail":"{read_only:false; response_revision:12380; number_of_response:1; }","duration":"163.247059ms","start":"2026-09-01T13:48:17.072117Z","end":"2026-09-01T13:48:17.235364Z","steps":["trace[1971183760] 'process raft request' (duration: 114.779398ms)","trace[1971183760] 'compare' (duration: 48.320717ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:48:17.257235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.727586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:48:17.257331Z","caller":"traceutil/trace.go:171","msg":"trace[828420927] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:12381; }","duration":"108.85956ms","start":"2026-09-01T13:48:17.148455Z","end":"2026-09-01T13:48:17.257315Z","steps":["trace[828420927] 'agreement among raft nodes before linearized reading' (duration: 108.695396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:17.257383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.117761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:48:17.257443Z","caller":"traceutil/trace.go:171","msg":"trace[576370933] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12381; }","duration":"108.207193ms","start":"2026-09-01T13:48:17.14922Z","end":"2026-09-01T13:48:17.257427Z","steps":["trace[576370933] 'agreement among raft nodes before linearized reading' (duration: 108.041899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:48:17.257618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.51725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T13:48:17.257647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.903441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-09-01T13:48:17.257731Z","caller":"traceutil/trace.go:171","msg":"trace[195129583] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:12381; }","duration":"109.039675ms","start":"2026-09-01T13:48:17.148672Z","end":"2026-09-01T13:48:17.257711Z","steps":["trace[195129583] 'agreement among raft nodes before linearized reading' (duration: 108.792058ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:17.257726Z","caller":"traceutil/trace.go:171","msg":"trace[1063088750] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:12381; }","duration":"108.842739ms","start":"2026-09-01T13:48:17.148857Z","end":"2026-09-01T13:48:17.2577Z","steps":["trace[1063088750] 'agreement among raft nodes before linearized reading' (duration: 108.47961ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:48:35.412373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10361} {"level":"info","ts":"2026-09-01T13:48:35.500841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10361,"took":"86.204716ms","hash":266027216,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":26300416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T13:48:35.500908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":266027216,"revision":10361,"compact-revision":7949} {"level":"info","ts":"2026-09-01T13:53:35.423509Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12530} {"level":"info","ts":"2026-09-01T13:53:35.513443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12530,"took":"86.413182ms","hash":1223223438,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":27844608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T13:53:35.513544Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1223223438,"revision":12530,"compact-revision":10361} {"level":"info","ts":"2026-09-01T13:54:17.075258Z","caller":"traceutil/trace.go:171","msg":"trace[1868824001] transaction","detail":"{read_only:false; response_revision:14821; number_of_response:1; }","duration":"106.21735ms","start":"2026-09-01T13:54:16.96899Z","end":"2026-09-01T13:54:17.075208Z","steps":["trace[1868824001] 'process raft request' (duration: 93.385199ms)","trace[1868824001] 'compare' (duration: 12.715448ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:54:20.518807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.347109ms","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-09-01T13:54:20.518898Z","caller":"traceutil/trace.go:171","msg":"trace[869023613] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14838; }","duration":"149.488013ms","start":"2026-09-01T13:54:20.369393Z","end":"2026-09-01T13:54:20.518881Z","steps":["trace[869023613] 'range keys from in-memory index tree' (duration: 149.276497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:00.814842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.249335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T13:55:00.814976Z","caller":"traceutil/trace.go:171","msg":"trace[399575512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15063; }","duration":"295.495601ms","start":"2026-09-01T13:55:00.519448Z","end":"2026-09-01T13:55:00.814943Z","steps":["trace[399575512] 'range keys from in-memory index tree' (duration: 294.984298ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:00.815091Z","caller":"traceutil/trace.go:171","msg":"trace[1707020978] transaction","detail":"{read_only:false; response_revision:15064; number_of_response:1; }","duration":"128.365739ms","start":"2026-09-01T13:55:00.686694Z","end":"2026-09-01T13:55:00.815059Z","steps":["trace[1707020978] 'process raft request' (duration: 126.208322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:00.815417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.171589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-sfcwt\" ","response":"range_response_count:1 size:11624"} {"level":"info","ts":"2026-09-01T13:55:00.815579Z","caller":"traceutil/trace.go:171","msg":"trace[129523373] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-sfcwt; range_end:; response_count:1; response_revision:15063; }","duration":"317.384125ms","start":"2026-09-01T13:55:00.498163Z","end":"2026-09-01T13:55:00.815548Z","steps":["trace[129523373] 'range keys from in-memory index tree' (duration: 316.536442ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:00.816082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:55:00.498135Z","time spent":"317.92472ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11646,"request content":"key:\"/registry/pods/openstack/openvswitch-sfcwt\" "} {"level":"info","ts":"2026-09-01T13:55:00.965412Z","caller":"traceutil/trace.go:171","msg":"trace[583858960] transaction","detail":"{read_only:false; response_revision:15065; number_of_response:1; }","duration":"145.535946ms","start":"2026-09-01T13:55:00.819824Z","end":"2026-09-01T13:55:00.96536Z","steps":["trace[583858960] 'process raft request' (duration: 145.219338ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:00.970482Z","caller":"traceutil/trace.go:171","msg":"trace[115088747] transaction","detail":"{read_only:false; response_revision:15066; number_of_response:1; }","duration":"139.779164ms","start":"2026-09-01T13:55:00.830663Z","end":"2026-09-01T13:55:00.970442Z","steps":["trace[115088747] 'process raft request' (duration: 139.355592ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:25.324665Z","caller":"traceutil/trace.go:171","msg":"trace[1478568542] transaction","detail":"{read_only:false; response_revision:15384; number_of_response:1; }","duration":"399.727198ms","start":"2026-09-01T13:55:24.924903Z","end":"2026-09-01T13:55:25.324631Z","steps":["trace[1478568542] 'process raft request' (duration: 399.520514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:25.325038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:55:24.924882Z","time spent":"399.877173ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3741,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T13:55:25.342108Z","caller":"traceutil/trace.go:171","msg":"trace[1885372231] transaction","detail":"{read_only:false; response_revision:15386; number_of_response:1; }","duration":"253.944074ms","start":"2026-09-01T13:55:25.088141Z","end":"2026-09-01T13:55:25.342085Z","steps":["trace[1885372231] 'process raft request' (duration: 253.851782ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:25.342204Z","caller":"traceutil/trace.go:171","msg":"trace[968393011] linearizableReadLoop","detail":"{readStateIndex:15926; appliedIndex:15924; }","duration":"290.92863ms","start":"2026-09-01T13:55:25.051258Z","end":"2026-09-01T13:55:25.342186Z","steps":["trace[968393011] 'read index received' (duration: 273.064485ms)","trace[968393011] 'applied index is now lower than readState.Index' (duration: 17.863295ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:55:25.34231Z","caller":"traceutil/trace.go:171","msg":"trace[2069646682] transaction","detail":"{read_only:false; response_revision:15387; number_of_response:1; }","duration":"252.847506ms","start":"2026-09-01T13:55:25.089447Z","end":"2026-09-01T13:55:25.342295Z","steps":["trace[2069646682] 'process raft request' (duration: 252.602949ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:25.34246Z","caller":"traceutil/trace.go:171","msg":"trace[623617226] transaction","detail":"{read_only:false; response_revision:15388; number_of_response:1; }","duration":"232.400531ms","start":"2026-09-01T13:55:25.110042Z","end":"2026-09-01T13:55:25.342443Z","steps":["trace[623617226] 'process raft request' (duration: 232.082062ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:55:25.342547Z","caller":"traceutil/trace.go:171","msg":"trace[1589517974] transaction","detail":"{read_only:false; response_revision:15385; number_of_response:1; }","duration":"347.813586ms","start":"2026-09-01T13:55:24.994715Z","end":"2026-09-01T13:55:25.342529Z","steps":["trace[1589517974] 'process raft request' (duration: 347.114067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:25.342683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T13:55:24.994696Z","time spent":"347.899698ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T13:55:25.342706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.450494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T13:55:25.342747Z","caller":"traceutil/trace.go:171","msg":"trace[59774421] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:15388; }","duration":"291.543167ms","start":"2026-09-01T13:55:25.05119Z","end":"2026-09-01T13:55:25.342733Z","steps":["trace[59774421] 'agreement among raft nodes before linearized reading' (duration: 291.450444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:25.343012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.012207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-01T13:55:25.34305Z","caller":"traceutil/trace.go:171","msg":"trace[1930142801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15388; }","duration":"236.096739ms","start":"2026-09-01T13:55:25.106945Z","end":"2026-09-01T13:55:25.343042Z","steps":["trace[1930142801] 'agreement among raft nodes before linearized reading' (duration: 235.979946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:55:25.343989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.676395ms","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-09-01T13:55:25.34404Z","caller":"traceutil/trace.go:171","msg":"trace[1651293702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15388; }","duration":"224.778688ms","start":"2026-09-01T13:55:25.119251Z","end":"2026-09-01T13:55:25.34403Z","steps":["trace[1651293702] 'agreement among raft nodes before linearized reading' (duration: 224.622634ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:18.40517Z","caller":"traceutil/trace.go:171","msg":"trace[77191774] linearizableReadLoop","detail":"{readStateIndex:16580; appliedIndex:16579; }","duration":"205.455202ms","start":"2026-09-01T13:56:18.199693Z","end":"2026-09-01T13:56:18.405148Z","steps":["trace[77191774] 'read index received' (duration: 205.279017ms)","trace[77191774] 'applied index is now lower than readState.Index' (duration: 175.215µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T13:56:18.405264Z","caller":"traceutil/trace.go:171","msg":"trace[176369265] transaction","detail":"{read_only:false; response_revision:16023; number_of_response:1; }","duration":"218.48291ms","start":"2026-09-01T13:56:18.186728Z","end":"2026-09-01T13:56:18.405211Z","steps":["trace[176369265] 'process raft request' (duration: 218.289734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:18.405416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.708429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-01T13:56:18.405478Z","caller":"traceutil/trace.go:171","msg":"trace[2080449173] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16023; }","duration":"205.811201ms","start":"2026-09-01T13:56:18.19965Z","end":"2026-09-01T13:56:18.405461Z","steps":["trace[2080449173] 'agreement among raft nodes before linearized reading' (duration: 205.613316ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:18.405541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.35556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-01T13:56:18.405616Z","caller":"traceutil/trace.go:171","msg":"trace[1302506730] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16023; }","duration":"101.459723ms","start":"2026-09-01T13:56:18.304139Z","end":"2026-09-01T13:56:18.405598Z","steps":["trace[1302506730] 'agreement among raft nodes before linearized reading' (duration: 101.299789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:18.405606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.658687ms","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-09-01T13:56:18.405695Z","caller":"traceutil/trace.go:171","msg":"trace[1501355158] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16023; }","duration":"116.76036ms","start":"2026-09-01T13:56:18.28891Z","end":"2026-09-01T13:56:18.405671Z","steps":["trace[1501355158] 'agreement among raft nodes before linearized reading' (duration: 116.502163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:18.405706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.97359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T13:56:18.405757Z","caller":"traceutil/trace.go:171","msg":"trace[1077207185] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16023; }","duration":"101.055672ms","start":"2026-09-01T13:56:18.304688Z","end":"2026-09-01T13:56:18.405744Z","steps":["trace[1077207185] 'agreement among raft nodes before linearized reading' (duration: 100.928239ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:18.405855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.668118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:56:18.405904Z","caller":"traceutil/trace.go:171","msg":"trace[1129737946] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16023; }","duration":"101.758481ms","start":"2026-09-01T13:56:18.304131Z","end":"2026-09-01T13:56:18.40589Z","steps":["trace[1129737946] 'agreement among raft nodes before linearized reading' (duration: 101.628087ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.012174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.681075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-09-01T13:56:52.01224Z","caller":"traceutil/trace.go:171","msg":"trace[1429519463] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16201; }","duration":"117.792028ms","start":"2026-09-01T13:56:51.894436Z","end":"2026-09-01T13:56:52.012228Z","steps":["trace[1429519463] 'range keys from in-memory index tree' (duration: 117.546181ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.012428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.317418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-01T13:56:52.012449Z","caller":"traceutil/trace.go:171","msg":"trace[247009086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16201; }","duration":"152.365589ms","start":"2026-09-01T13:56:51.860078Z","end":"2026-09-01T13:56:52.012444Z","steps":["trace[247009086] 'range keys from in-memory index tree' (duration: 152.230815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.012544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.129786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-01T13:56:52.012565Z","caller":"traceutil/trace.go:171","msg":"trace[868059199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16201; }","duration":"148.167457ms","start":"2026-09-01T13:56:51.864394Z","end":"2026-09-01T13:56:52.012561Z","steps":["trace[868059199] 'range keys from in-memory index tree' (duration: 148.071604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.012641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.805279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rp8lx\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-09-01T13:56:52.01266Z","caller":"traceutil/trace.go:171","msg":"trace[238276241] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rp8lx; range_end:; response_count:1; response_revision:16201; }","duration":"135.86853ms","start":"2026-09-01T13:56:51.876787Z","end":"2026-09-01T13:56:52.012656Z","steps":["trace[238276241] 'range keys from in-memory index tree' (duration: 135.687165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.012815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.318266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-01T13:56:52.012837Z","caller":"traceutil/trace.go:171","msg":"trace[1797344822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16201; }","duration":"120.367107ms","start":"2026-09-01T13:56:51.892465Z","end":"2026-09-01T13:56:52.012832Z","steps":["trace[1797344822] 'range keys from in-memory index tree' (duration: 120.244394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.012904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.062942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-09-01T13:56:52.012919Z","caller":"traceutil/trace.go:171","msg":"trace[1660037285] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16201; }","duration":"119.093922ms","start":"2026-09-01T13:56:51.89382Z","end":"2026-09-01T13:56:52.012914Z","steps":["trace[1660037285] 'range keys from in-memory index tree' (duration: 119.00641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.013038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.812417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:56:52.013089Z","caller":"traceutil/trace.go:171","msg":"trace[256292387] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16201; }","duration":"117.897249ms","start":"2026-09-01T13:56:51.89518Z","end":"2026-09-01T13:56:52.013077Z","steps":["trace[256292387] 'range keys from in-memory index tree' (duration: 117.754366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.013688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.713872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T13:56:52.013798Z","caller":"traceutil/trace.go:171","msg":"trace[832852098] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16201; }","duration":"118.858985ms","start":"2026-09-01T13:56:51.894917Z","end":"2026-09-01T13:56:52.013776Z","steps":["trace[832852098] 'range keys from in-memory index tree' (duration: 118.65416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T13:56:52.014492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.358204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-09-01T13:56:52.014612Z","caller":"traceutil/trace.go:171","msg":"trace[1290738900] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16201; }","duration":"120.199532ms","start":"2026-09-01T13:56:51.894387Z","end":"2026-09-01T13:56:52.014587Z","steps":["trace[1290738900] 'range keys from in-memory index tree' (duration: 117.787587ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:52.180526Z","caller":"traceutil/trace.go:171","msg":"trace[2076629758] transaction","detail":"{read_only:false; response_revision:16202; number_of_response:1; }","duration":"163.460935ms","start":"2026-09-01T13:56:52.017031Z","end":"2026-09-01T13:56:52.180492Z","steps":["trace[2076629758] 'process raft request' (duration: 163.213728ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:52.192959Z","caller":"traceutil/trace.go:171","msg":"trace[669422489] transaction","detail":"{read_only:false; response_revision:16203; number_of_response:1; }","duration":"175.33202ms","start":"2026-09-01T13:56:52.017578Z","end":"2026-09-01T13:56:52.19291Z","steps":["trace[669422489] 'process raft request' (duration: 175.000141ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:52.192969Z","caller":"traceutil/trace.go:171","msg":"trace[2119342463] transaction","detail":"{read_only:false; response_revision:16204; number_of_response:1; }","duration":"175.062113ms","start":"2026-09-01T13:56:52.017849Z","end":"2026-09-01T13:56:52.192911Z","steps":["trace[2119342463] 'process raft request' (duration: 174.95339ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:52.193225Z","caller":"traceutil/trace.go:171","msg":"trace[99656161] transaction","detail":"{read_only:false; response_revision:16205; number_of_response:1; }","duration":"175.062972ms","start":"2026-09-01T13:56:52.01815Z","end":"2026-09-01T13:56:52.193213Z","steps":["trace[99656161] 'process raft request' (duration: 174.704553ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:56:52.193209Z","caller":"traceutil/trace.go:171","msg":"trace[330584755] linearizableReadLoop","detail":"{readStateIndex:16769; appliedIndex:16765; }","duration":"172.378121ms","start":"2026-09-01T13:56:52.020785Z","end":"2026-09-01T13:56:52.193164Z","steps":["trace[330584755] 'read index received' (duration: 159.628222ms)","trace[330584755] 'applied index is now lower than readState.Index' (duration: 12.747479ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T13:56:52.194218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.42193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:732042"} {"level":"info","ts":"2026-09-01T13:56:52.194331Z","caller":"traceutil/trace.go:171","msg":"trace[269671053] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16205; }","duration":"173.607315ms","start":"2026-09-01T13:56:52.020702Z","end":"2026-09-01T13:56:52.19431Z","steps":["trace[269671053] 'agreement among raft nodes before linearized reading' (duration: 172.581127ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T13:58:35.431689Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14534} {"level":"info","ts":"2026-09-01T13:58:35.526065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14534,"took":"91.615211ms","hash":2104604470,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":30588928,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T13:58:35.52614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2104604470,"revision":14534,"compact-revision":12530} {"level":"info","ts":"2026-09-01T14:00:03.469638Z","caller":"traceutil/trace.go:171","msg":"trace[442714256] transaction","detail":"{read_only:false; response_revision:17753; number_of_response:1; }","duration":"100.784805ms","start":"2026-09-01T14:00:03.368819Z","end":"2026-09-01T14:00:03.469604Z","steps":["trace[442714256] 'process raft request' (duration: 100.713953ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:00:03.469707Z","caller":"traceutil/trace.go:171","msg":"trace[660401809] transaction","detail":"{read_only:false; response_revision:17752; number_of_response:1; }","duration":"102.559701ms","start":"2026-09-01T14:00:03.367097Z","end":"2026-09-01T14:00:03.469657Z","steps":["trace[660401809] 'process raft request' (duration: 70.882188ms)","trace[660401809] 'compare' (duration: 31.386176ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:03:22.522532Z","caller":"traceutil/trace.go:171","msg":"trace[876703638] linearizableReadLoop","detail":"{readStateIndex:19707; appliedIndex:19706; }","duration":"145.387232ms","start":"2026-09-01T14:03:22.377128Z","end":"2026-09-01T14:03:22.522515Z","steps":["trace[876703638] 'read index received' (duration: 145.28512ms)","trace[876703638] 'applied index is now lower than readState.Index' (duration: 101.442µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:03:22.522665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.525417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T14:03:22.522682Z","caller":"traceutil/trace.go:171","msg":"trace[1849172962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19047; }","duration":"145.568078ms","start":"2026-09-01T14:03:22.377107Z","end":"2026-09-01T14:03:22.522675Z","steps":["trace[1849172962] 'agreement among raft nodes before linearized reading' (duration: 145.473085ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:03:22.522726Z","caller":"traceutil/trace.go:171","msg":"trace[245348180] transaction","detail":"{read_only:false; response_revision:19047; number_of_response:1; }","duration":"148.171877ms","start":"2026-09-01T14:03:22.374542Z","end":"2026-09-01T14:03:22.522714Z","steps":["trace[245348180] 'process raft request' (duration: 147.884379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:03:22.868351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.88613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T14:03:22.870403Z","caller":"traceutil/trace.go:171","msg":"trace[227599048] linearizableReadLoop","detail":"{readStateIndex:19708; appliedIndex:19707; }","duration":"277.203934ms","start":"2026-09-01T14:03:22.593148Z","end":"2026-09-01T14:03:22.870352Z","steps":["trace[227599048] 'read index received' (duration: 139.168907ms)","trace[227599048] 'applied index is now lower than readState.Index' (duration: 138.032366ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:03:22.870515Z","caller":"traceutil/trace.go:171","msg":"trace[801516780] transaction","detail":"{read_only:false; response_revision:19048; number_of_response:1; }","duration":"343.722486ms","start":"2026-09-01T14:03:22.526713Z","end":"2026-09-01T14:03:22.870435Z","steps":["trace[801516780] 'process raft request' (duration: 205.661549ms)","trace[801516780] 'compare' (duration: 135.585181ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:03:22.871395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:03:22.526689Z","time spent":"344.529768ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T14:03:22.87185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.781104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-01T14:03:22.872183Z","caller":"traceutil/trace.go:171","msg":"trace[1295254624] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:19048; }","duration":"143.148794ms","start":"2026-09-01T14:03:22.729015Z","end":"2026-09-01T14:03:22.872164Z","steps":["trace[1295254624] 'agreement among raft nodes before linearized reading' (duration: 142.592349ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:03:22.871998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.198474ms","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-09-01T14:03:22.872946Z","caller":"traceutil/trace.go:171","msg":"trace[1736035463] 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:19048; }","duration":"278.208712ms","start":"2026-09-01T14:03:22.594711Z","end":"2026-09-01T14:03:22.87292Z","steps":["trace[1736035463] 'agreement among raft nodes before linearized reading' (duration: 277.191235ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:03:22.870935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.762139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-01T14:03:22.873074Z","caller":"traceutil/trace.go:171","msg":"trace[602627316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19048; }","duration":"279.945008ms","start":"2026-09-01T14:03:22.593118Z","end":"2026-09-01T14:03:22.873063Z","steps":["trace[602627316] 'agreement among raft nodes before linearized reading' (duration: 277.42779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:03:22.87357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.604858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-01T14:03:22.873811Z","caller":"traceutil/trace.go:171","msg":"trace[265335720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19048; }","duration":"279.842864ms","start":"2026-09-01T14:03:22.593937Z","end":"2026-09-01T14:03:22.87378Z","steps":["trace[265335720] 'agreement among raft nodes before linearized reading' (duration: 277.586824ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:03:35.438497Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16862} {"level":"info","ts":"2026-09-01T14:03:35.539633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16862,"took":"96.656274ms","hash":1591126958,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T14:03:35.53969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1591126958,"revision":16862,"compact-revision":14534} {"level":"info","ts":"2026-09-01T14:03:45.674846Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T14:03:45.682121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T14:03:45.682878Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T14:05:03.774029Z","caller":"traceutil/trace.go:171","msg":"trace[1732088849] transaction","detail":"{read_only:false; response_revision:20094; number_of_response:1; }","duration":"111.135681ms","start":"2026-09-01T14:05:03.662868Z","end":"2026-09-01T14:05:03.774003Z","steps":["trace[1732088849] 'process raft request' (duration: 111.006767ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:05:03.780085Z","caller":"traceutil/trace.go:171","msg":"trace[499551789] transaction","detail":"{read_only:false; response_revision:20095; number_of_response:1; }","duration":"111.840979ms","start":"2026-09-01T14:05:03.66822Z","end":"2026-09-01T14:05:03.780061Z","steps":["trace[499551789] 'process raft request' (duration: 111.656514ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:08:35.445405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19269} {"level":"info","ts":"2026-09-01T14:08:35.55059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19269,"took":"102.511571ms","hash":1957401324,"current-db-size-bytes":55066624,"current-db-size":"55 MB","current-db-size-in-use-bytes":33804288,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T14:08:35.550667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1957401324,"revision":19269,"compact-revision":16862} {"level":"warn","ts":"2026-09-01T14:09:31.371953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.477808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T14:09:31.372066Z","caller":"traceutil/trace.go:171","msg":"trace[511681242] transaction","detail":"{read_only:false; response_revision:21919; number_of_response:1; }","duration":"291.949812ms","start":"2026-09-01T14:09:31.080097Z","end":"2026-09-01T14:09:31.372047Z","steps":["trace[511681242] 'process raft request' (duration: 161.283489ms)","trace[511681242] 'compare' (duration: 130.372595ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:09:31.772592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.819871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T14:09:31.772718Z","caller":"traceutil/trace.go:171","msg":"trace[1974734492] linearizableReadLoop","detail":"{readStateIndex:22673; appliedIndex:22671; }","duration":"594.375232ms","start":"2026-09-01T14:09:31.178321Z","end":"2026-09-01T14:09:31.772697Z","steps":["trace[1974734492] 'read index received' (duration: 63.142234ms)","trace[1974734492] 'applied index is now lower than readState.Index' (duration: 531.230938ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:09:31.772856Z","caller":"traceutil/trace.go:171","msg":"trace[153714129] transaction","detail":"{read_only:false; response_revision:21920; number_of_response:1; }","duration":"666.191256ms","start":"2026-09-01T14:09:31.106632Z","end":"2026-09-01T14:09:31.772823Z","steps":["trace[153714129] 'process raft request' (duration: 411.021595ms)","trace[153714129] 'compare' (duration: 254.178154ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:09:31.772962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:09:31.1066Z","time spent":"666.312289ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T14:09:31.773064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.629663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T14:09:31.773116Z","caller":"traceutil/trace.go:171","msg":"trace[336573934] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:21920; }","duration":"312.722735ms","start":"2026-09-01T14:09:31.460378Z","end":"2026-09-01T14:09:31.7731Z","steps":["trace[336573934] 'agreement among raft nodes before linearized reading' (duration: 312.631063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:09:31.773159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:09:31.460358Z","time spent":"312.786948ms","remote":"127.0.0.1:33640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-01T14:09:31.774619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.866755ms","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-09-01T14:09:31.774747Z","caller":"traceutil/trace.go:171","msg":"trace[1362122142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21920; }","duration":"596.414976ms","start":"2026-09-01T14:09:31.178305Z","end":"2026-09-01T14:09:31.77472Z","steps":["trace[1362122142] 'agreement among raft nodes before linearized reading' (duration: 594.503605ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:09:31.774806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T14:09:31.178282Z","time spent":"596.504389ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-01T14:09:32.134379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.529643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T14:09:32.13441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.460046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-01T14:09:32.134461Z","caller":"traceutil/trace.go:171","msg":"trace[501297387] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:21921; }","duration":"141.676986ms","start":"2026-09-01T14:09:31.992763Z","end":"2026-09-01T14:09:32.13444Z","steps":["trace[501297387] 'count revisions from in-memory index tree' (duration: 141.398699ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:09:32.134478Z","caller":"traceutil/trace.go:171","msg":"trace[1336592653] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21921; }","duration":"244.566279ms","start":"2026-09-01T14:09:31.889893Z","end":"2026-09-01T14:09:32.13446Z","steps":["trace[1336592653] 'range keys from in-memory index tree' (duration: 244.271761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:09:32.134648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.444819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T14:09:32.135265Z","caller":"traceutil/trace.go:171","msg":"trace[239695982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21921; }","duration":"214.374993ms","start":"2026-09-01T14:09:31.920865Z","end":"2026-09-01T14:09:32.13524Z","steps":["trace[239695982] 'range keys from in-memory index tree' (duration: 213.361687ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:09:32.723445Z","caller":"traceutil/trace.go:171","msg":"trace[620200584] transaction","detail":"{read_only:false; response_revision:21922; number_of_response:1; }","duration":"105.944364ms","start":"2026-09-01T14:09:32.61746Z","end":"2026-09-01T14:09:32.723404Z","steps":["trace[620200584] 'process raft request' (duration: 105.624665ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:09:32.946162Z","caller":"traceutil/trace.go:171","msg":"trace[201928408] linearizableReadLoop","detail":"{readStateIndex:22677; appliedIndex:22675; }","duration":"120.271606ms","start":"2026-09-01T14:09:32.825863Z","end":"2026-09-01T14:09:32.946134Z","steps":["trace[201928408] 'read index received' (duration: 49.623062ms)","trace[201928408] 'applied index is now lower than readState.Index' (duration: 70.647354ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:09:32.94639Z","caller":"traceutil/trace.go:171","msg":"trace[1581647241] transaction","detail":"{read_only:false; response_revision:21923; number_of_response:1; }","duration":"298.110616ms","start":"2026-09-01T14:09:32.648197Z","end":"2026-09-01T14:09:32.946308Z","steps":["trace[1581647241] 'process raft request' (duration: 227.242877ms)","trace[1581647241] 'compare' (duration: 70.222372ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T14:09:32.94658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.649535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T14:09:32.946641Z","caller":"traceutil/trace.go:171","msg":"trace[1958182784] transaction","detail":"{read_only:false; response_revision:21924; number_of_response:1; }","duration":"150.931824ms","start":"2026-09-01T14:09:32.795621Z","end":"2026-09-01T14:09:32.946553Z","steps":["trace[1958182784] 'process raft request' (duration: 150.439911ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:09:32.946645Z","caller":"traceutil/trace.go:171","msg":"trace[1046295312] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:21924; }","duration":"120.81695ms","start":"2026-09-01T14:09:32.825812Z","end":"2026-09-01T14:09:32.946629Z","steps":["trace[1046295312] 'agreement among raft nodes before linearized reading' (duration: 120.645815ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:09:33.092798Z","caller":"traceutil/trace.go:171","msg":"trace[2050709057] transaction","detail":"{read_only:false; response_revision:21925; number_of_response:1; }","duration":"143.179146ms","start":"2026-09-01T14:09:32.94959Z","end":"2026-09-01T14:09:33.092769Z","steps":["trace[2050709057] 'process raft request' (duration: 142.752665ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:09:33.095169Z","caller":"traceutil/trace.go:171","msg":"trace[253598009] linearizableReadLoop","detail":"{readStateIndex:22679; appliedIndex:22677; }","duration":"143.843243ms","start":"2026-09-01T14:09:32.951298Z","end":"2026-09-01T14:09:33.095142Z","steps":["trace[253598009] 'read index received' (duration: 141.066429ms)","trace[253598009] 'applied index is now lower than readState.Index' (duration: 2.774394ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:09:33.095223Z","caller":"traceutil/trace.go:171","msg":"trace[596076064] transaction","detail":"{read_only:false; response_revision:21926; number_of_response:1; }","duration":"143.951436ms","start":"2026-09-01T14:09:32.951247Z","end":"2026-09-01T14:09:33.095198Z","steps":["trace[596076064] 'process raft request' (duration: 143.273549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:09:33.095663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.341087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T14:09:33.095764Z","caller":"traceutil/trace.go:171","msg":"trace[344988220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21927; }","duration":"144.478571ms","start":"2026-09-01T14:09:32.951267Z","end":"2026-09-01T14:09:33.095746Z","steps":["trace[344988220] 'agreement among raft nodes before linearized reading' (duration: 144.174472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:09:33.095733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.781866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-01T14:09:33.096076Z","caller":"traceutil/trace.go:171","msg":"trace[477827372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21927; }","duration":"110.109824ms","start":"2026-09-01T14:09:32.985853Z","end":"2026-09-01T14:09:33.095963Z","steps":["trace[477827372] 'agreement among raft nodes before linearized reading' (duration: 109.60402ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:13:35.452425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21613} {"level":"info","ts":"2026-09-01T14:13:35.56898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21613,"took":"113.348275ms","hash":3214697353,"current-db-size-bytes":55443456,"current-db-size":"55 MB","current-db-size-in-use-bytes":41467904,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-01T14:13:35.56908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3214697353,"revision":21613,"compact-revision":19269} {"level":"info","ts":"2026-09-01T14:13:40.171937Z","caller":"traceutil/trace.go:171","msg":"trace[2000115393] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"281.970902ms","start":"2026-09-01T14:13:39.889932Z","end":"2026-09-01T14:13:40.171903Z","steps":["trace[2000115393] 'process raft request' (duration: 281.801158ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:13:40.219518Z","caller":"traceutil/trace.go:171","msg":"trace[1382385444] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"120.438643ms","start":"2026-09-01T14:13:40.09902Z","end":"2026-09-01T14:13:40.219458Z","steps":["trace[1382385444] 'process raft request' (duration: 120.34159ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:13:40.219803Z","caller":"traceutil/trace.go:171","msg":"trace[856297191] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"113.413608ms","start":"2026-09-01T14:13:40.106368Z","end":"2026-09-01T14:13:40.219781Z","steps":["trace[856297191] 'process raft request' (duration: 113.044558ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:13:40.219778Z","caller":"traceutil/trace.go:171","msg":"trace[770996602] linearizableReadLoop","detail":"{readStateIndex:25276; appliedIndex:25275; }","duration":"144.435657ms","start":"2026-09-01T14:13:40.0753Z","end":"2026-09-01T14:13:40.219736Z","steps":["trace[770996602] 'read index received' (duration: 96.970173ms)","trace[770996602] 'applied index is now lower than readState.Index' (duration: 47.461764ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T14:13:40.219584Z","caller":"traceutil/trace.go:171","msg":"trace[1667307048] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"256.434968ms","start":"2026-09-01T14:13:39.9631Z","end":"2026-09-01T14:13:40.219535Z","steps":["trace[1667307048] 'process raft request' (duration: 256.048608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T14:13:40.220103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.762537ms","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-09-01T14:13:40.220174Z","caller":"traceutil/trace.go:171","msg":"trace[1757431518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24447; }","duration":"144.908901ms","start":"2026-09-01T14:13:40.075246Z","end":"2026-09-01T14:13:40.220155Z","steps":["trace[1757431518] 'agreement among raft nodes before linearized reading' (duration: 144.646444ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T14:15:46.779774Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067cd.wal"} {"level":"info","ts":"2026-09-01T14:18:35.45967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24349} {"level":"info","ts":"2026-09-01T14:18:35.607615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24349,"took":"144.194577ms","hash":703477203,"current-db-size-bytes":56287232,"current-db-size":"56 MB","current-db-size-in-use-bytes":33550336,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T14:18:35.607684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":703477203,"revision":24349,"compact-revision":21613} {"level":"info","ts":"2026-09-01T14:23:17.029478Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"17dd22a2661aae79","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T14:23:17.037584Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T14:23:17.037741Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-01T14:23:35.472378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26956} {"level":"info","ts":"2026-09-01T14:23:35.606962Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26956,"took":"130.818227ms","hash":3901600411,"current-db-size-bytes":56287232,"current-db-size":"56 MB","current-db-size-in-use-bytes":29634560,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T14:23:35.607056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3901600411,"revision":26956,"compact-revision":24349}